"./sync.test -test.v -test.timeout 1h0m0s -remote TestProtonDrive: -verbose -test.run '^(TestMoveWithDeleteEmptySrcDirs|TestServerSideMove|TestServerSideMoveDeleteEmptySourceDirs|TestServerSideMoveOverSelf|TestServerSideMoveWithFilter|TestSyncBackupDir|TestSyncBackupDirSuffixOnly|TestSyncBackupDirWithSuffix|TestSyncBackupDirWithSuffixKeepExtension|TestSyncConcurrentTruncate|TestSyncSuffix|TestSyncSuffixKeepExtension|TestSyncWithTrackRenames|TestSyncWithTrackRenamesStrategyLeaf|TestSyncWithTrackRenamesStrategyModtime)$'" - Starting (try 2/5) 2025/09/14 04:10:24 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-sofihog9wepu" 2025/09/14 04:10:24 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/09/14 04:10:24 DEBUG : proton drive root link ID 'rclone-test-sofihog9wepu': Has cached credentials 2025/09/14 04:10:27 DEBUG : proton drive root link ID 'rclone-test-sofihog9wepu': Used cached credential to initialize the ProtonDrive API 2025/09/14 04:10:27 DEBUG : Creating backend with remote "/tmp/rclone248909538" === RUN TestServerSideMoveOverSelf run.go:180: Remote "proton drive root link ID 'rclone-test-sofihog9wepu'", Local "Local file system at /tmp/rclone248909538", Modify Window "1s" 2025/09/14 04:10:32 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-biwagun9roye" 2025/09/14 04:10:32 DEBUG : proton drive root link ID 'rclone-test-biwagun9roye': Has cached credentials 2025/09/14 04:10:34 DEBUG : proton drive root link ID 'rclone-test-biwagun9roye': Used cached credential to initialize the ProtonDrive API sync_test.go:701: Server side copy (if possible) proton drive root link ID 'rclone-test-sofihog9wepu' -> proton drive root link ID 'rclone-test-biwagun9roye' 2025/09/14 04:10:36 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/09/14 04:10:36 DEBUG : proton drive root link ID 'rclone-test-biwagun9roye': Waiting for checks to finish 2025/09/14 04:10:36 DEBUG : proton drive root link ID 'rclone-test-biwagun9roye': Waiting for transfers to finish 2025/09/14 04:10:41 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2025/09/14 04:10:41 INFO : sub dir/hello world: Copied (new) 2025/09/14 04:10:43.942448 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/14 04:10:43.942473 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/14 04:10:47 DEBUG : proton drive root link ID 'rclone-test-biwagun9roye': Using server-side directory move 2025/09/14 04:10:47 INFO : proton drive root link ID 'rclone-test-biwagun9roye': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2025/09/14 04:10:48 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/09/14 04:10:48 DEBUG : proton drive root link ID 'rclone-test-biwagun9roye': Waiting for checks to finish 2025/09/14 04:10:48 DEBUG : proton drive root link ID 'rclone-test-biwagun9roye': Waiting for transfers to finish 2025/09/14 04:10:49 INFO : sub dir/hello world: Deleted 2025/09/14 04:10:50.643929 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/LYVXsXCpOeivYJ3TZz7X_j9FlKvyxEmMoutE-j6293UJoiQb5LxsM4qp6QQ0SPwBu0zc1elQf4mNLU1ldzV5KQ==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/14 04:10:50.643962 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/LYVXsXCpOeivYJ3TZz7X_j9FlKvyxEmMoutE-j6293UJoiQb5LxsM4qp6QQ0SPwBu0zc1elQf4mNLU1ldzV5KQ==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/14 04:10:50 ERROR : sub dir/hello world: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/LYVXsXCpOeivYJ3TZz7X_j9FlKvyxEmMoutE-j6293UJoiQb5LxsM4qp6QQ0SPwBu0zc1elQf4mNLU1ldzV5KQ==/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/LYVXsXCpOeivYJ3TZz7X_j9FlKvyxEmMoutE-j6293UJoiQb5LxsM4qp6QQ0SPwBu0zc1elQf4mNLU1ldzV5KQ==/move: This value should not be blank. (Code=2000, Status=400) Test: TestServerSideMoveOverSelf 2025/09/14 04:10:50 DEBUG : proton drive root link ID 'rclone-test-biwagun9roye': Purge remote --- FAIL: TestServerSideMoveOverSelf (26.67s) === RUN TestSyncWithTrackRenames run.go:180: Remote "proton drive root link ID 'rclone-test-sofihog9wepu'", Local "Local file system at /tmp/rclone248909538", Modify Window "1s" sync_test.go:1568: Can track renames: true 2025/09/14 04:10:54 INFO : proton drive root link ID 'rclone-test-sofihog9wepu': Making map for --track-renames 2025/09/14 04:10:54 INFO : proton drive root link ID 'rclone-test-sofihog9wepu': Finished making map for --track-renames 2025/09/14 04:10:54 DEBUG : proton drive root link ID 'rclone-test-sofihog9wepu': Waiting for checks to finish 2025/09/14 04:10:54 DEBUG : yam: Need to transfer - No matching file found at Destination 2025/09/14 04:10:54 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/09/14 04:10:54 DEBUG : proton drive root link ID 'rclone-test-sofihog9wepu': Waiting for renames to finish 2025/09/14 04:10:54 DEBUG : proton drive root link ID 'rclone-test-sofihog9wepu': Waiting for transfers to finish 2025/09/14 04:10:56 DEBUG : potato: sha1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2025/09/14 04:10:56 INFO : potato: Copied (new) 2025/09/14 04:10:56 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/09/14 04:10:56 INFO : yam: Copied (new) 2025/09/14 04:10:56 DEBUG : Waiting for deletions to finish 2025/09/14 04:10:58 DEBUG : potato: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/09/14 04:10:58 INFO : proton drive root link ID 'rclone-test-sofihog9wepu': Making map for --track-renames 2025/09/14 04:10:58 INFO : proton drive root link ID 'rclone-test-sofihog9wepu': Finished making map for --track-renames 2025/09/14 04:10:58 DEBUG : proton drive root link ID 'rclone-test-sofihog9wepu': Waiting for checks to finish 2025/09/14 04:10:58 DEBUG : potato: Unchanged skipping 2025/09/14 04:10:58 DEBUG : proton drive root link ID 'rclone-test-sofihog9wepu': Waiting for renames to finish 2025/09/14 04:10:59.580389 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/XybQL0EUmScvzcBerPJRB7R1gaWVU52eOL2PgnRgEU-n4q1zxDK-MIhtnvZlJb4jXP6MjbFNa5U1_1Ati-Vg7A==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/14 04:10:59.580428 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/XybQL0EUmScvzcBerPJRB7R1gaWVU52eOL2PgnRgEU-n4q1zxDK-MIhtnvZlJb4jXP6MjbFNa5U1_1Ati-Vg7A==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/14 04:10:59 ERROR : yam: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/XybQL0EUmScvzcBerPJRB7R1gaWVU52eOL2PgnRgEU-n4q1zxDK-MIhtnvZlJb4jXP6MjbFNa5U1_1Ati-Vg7A==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/14 04:10:59 DEBUG : yaml: Failed to rename to "yam": 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/XybQL0EUmScvzcBerPJRB7R1gaWVU52eOL2PgnRgEU-n4q1zxDK-MIhtnvZlJb4jXP6MjbFNa5U1_1Ati-Vg7A==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/14 04:10:59 DEBUG : yaml: Need to transfer - No matching file found at Destination 2025/09/14 04:10:59 DEBUG : proton drive root link ID 'rclone-test-sofihog9wepu': Waiting for transfers to finish 2025/09/14 04:11:01 DEBUG : yaml: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/09/14 04:11:01 INFO : yaml: Copied (new) 2025/09/14 04:11:01 ERROR : proton drive root link ID 'rclone-test-sofihog9wepu': not deleting files as there were IO errors 2025/09/14 04:11:01 ERROR : proton drive root link ID 'rclone-test-sofihog9wepu': not deleting directories as there were IO errors sync_test.go:1586: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1586 Error: Received unexpected error: not deleting files as there were IO errors Test: TestSyncWithTrackRenames --- FAIL: TestSyncWithTrackRenames (10.15s) === RUN TestSyncWithTrackRenamesStrategyModtime run.go:180: Remote "proton drive root link ID 'rclone-test-sofihog9wepu'", Local "Local file system at /tmp/rclone248909538", Modify Window "1s" sync_test.go:1640: Can track renames: true 2025/09/14 04:11:05 INFO : proton drive root link ID 'rclone-test-sofihog9wepu': Making map for --track-renames 2025/09/14 04:11:05 INFO : proton drive root link ID 'rclone-test-sofihog9wepu': Finished making map for --track-renames 2025/09/14 04:11:05 DEBUG : proton drive root link ID 'rclone-test-sofihog9wepu': Waiting for checks to finish 2025/09/14 04:11:05 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/09/14 04:11:05 DEBUG : yam: Need to transfer - No matching file found at Destination 2025/09/14 04:11:05 DEBUG : proton drive root link ID 'rclone-test-sofihog9wepu': Waiting for renames to finish 2025/09/14 04:11:05 DEBUG : proton drive root link ID 'rclone-test-sofihog9wepu': Waiting for transfers to finish 2025/09/14 04:11:06 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/09/14 04:11:06 INFO : yam: Copied (new) 2025/09/14 04:11:07 DEBUG : potato: sha1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2025/09/14 04:11:07 INFO : potato: Copied (new) 2025/09/14 04:11:07 DEBUG : Waiting for deletions to finish 2025/09/14 04:11:09 DEBUG : potato: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/09/14 04:11:09 INFO : proton drive root link ID 'rclone-test-sofihog9wepu': Making map for --track-renames 2025/09/14 04:11:09 INFO : proton drive root link ID 'rclone-test-sofihog9wepu': Finished making map for --track-renames 2025/09/14 04:11:09 DEBUG : proton drive root link ID 'rclone-test-sofihog9wepu': Waiting for checks to finish 2025/09/14 04:11:09 DEBUG : potato: Unchanged skipping 2025/09/14 04:11:09 DEBUG : proton drive root link ID 'rclone-test-sofihog9wepu': Waiting for renames to finish 2025/09/14 04:11:09.907215 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/n35jPAMCJpdrmkmGvtLew2bHGmUd1jo4Wynu7wuptl1rg-o391tlwKJdwsFw6RUgBbaGY57DNXPY6p46AwF-kQ==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/14 04:11:09.907249 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/n35jPAMCJpdrmkmGvtLew2bHGmUd1jo4Wynu7wuptl1rg-o391tlwKJdwsFw6RUgBbaGY57DNXPY6p46AwF-kQ==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/14 04:11:09 ERROR : yam: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/n35jPAMCJpdrmkmGvtLew2bHGmUd1jo4Wynu7wuptl1rg-o391tlwKJdwsFw6RUgBbaGY57DNXPY6p46AwF-kQ==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/14 04:11:09 DEBUG : yaml: Failed to rename to "yam": 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/n35jPAMCJpdrmkmGvtLew2bHGmUd1jo4Wynu7wuptl1rg-o391tlwKJdwsFw6RUgBbaGY57DNXPY6p46AwF-kQ==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/14 04:11:09 DEBUG : yaml: Need to transfer - No matching file found at Destination 2025/09/14 04:11:09 DEBUG : proton drive root link ID 'rclone-test-sofihog9wepu': Waiting for transfers to finish 2025/09/14 04:11:11 DEBUG : yaml: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/09/14 04:11:11 INFO : yaml: Copied (new) 2025/09/14 04:11:11 ERROR : proton drive root link ID 'rclone-test-sofihog9wepu': not deleting files as there were IO errors 2025/09/14 04:11:11 ERROR : proton drive root link ID 'rclone-test-sofihog9wepu': not deleting directories as there were IO errors sync_test.go:1658: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1658 Error: Received unexpected error: not deleting files as there were IO errors Test: TestSyncWithTrackRenamesStrategyModtime --- FAIL: TestSyncWithTrackRenamesStrategyModtime (9.66s) === RUN TestSyncWithTrackRenamesStrategyLeaf run.go:180: Remote "proton drive root link ID 'rclone-test-sofihog9wepu'", Local "Local file system at /tmp/rclone248909538", Modify Window "1s" sync_test.go:1679: Can track renames: true 2025/09/14 04:11:14 INFO : proton drive root link ID 'rclone-test-sofihog9wepu': Making map for --track-renames 2025/09/14 04:11:14 INFO : proton drive root link ID 'rclone-test-sofihog9wepu': Finished making map for --track-renames 2025/09/14 04:11:14 DEBUG : proton drive root link ID 'rclone-test-sofihog9wepu': Waiting for checks to finish 2025/09/14 04:11:14 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/09/14 04:11:14 DEBUG : proton drive root link ID 'rclone-test-sofihog9wepu': Waiting for renames to finish 2025/09/14 04:11:14 DEBUG : sub/yam: Need to transfer - No matching file found at Destination 2025/09/14 04:11:14 DEBUG : proton drive root link ID 'rclone-test-sofihog9wepu': Waiting for transfers to finish 2025/09/14 04:11:16 DEBUG : potato: sha1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2025/09/14 04:11:16 INFO : potato: Copied (new) 2025/09/14 04:11:16 DEBUG : sub/yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/09/14 04:11:16 INFO : sub/yam: Copied (new) 2025/09/14 04:11:16 DEBUG : Waiting for deletions to finish 2025/09/14 04:11:18 DEBUG : potato: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/09/14 04:11:18 DEBUG : potato: Unchanged skipping 2025/09/14 04:11:19 INFO : proton drive root link ID 'rclone-test-sofihog9wepu': Making map for --track-renames 2025/09/14 04:11:19 INFO : proton drive root link ID 'rclone-test-sofihog9wepu': Finished making map for --track-renames 2025/09/14 04:11:19 DEBUG : proton drive root link ID 'rclone-test-sofihog9wepu': Waiting for checks to finish 2025/09/14 04:11:19 DEBUG : proton drive root link ID 'rclone-test-sofihog9wepu': Waiting for renames to finish 2025/09/14 04:11:20.484672 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/ihQ6WxovZamjSKUZuBZX86_vs9Qp8RWNvvZyYgk5WJwhog923gMBc4Gyl7WYYeUpQClXhjgcIvwJd3eNrxMouA==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/14 04:11:20.484757 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/ihQ6WxovZamjSKUZuBZX86_vs9Qp8RWNvvZyYgk5WJwhog923gMBc4Gyl7WYYeUpQClXhjgcIvwJd3eNrxMouA==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/14 04:11:20 ERROR : sub/yam: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/ihQ6WxovZamjSKUZuBZX86_vs9Qp8RWNvvZyYgk5WJwhog923gMBc4Gyl7WYYeUpQClXhjgcIvwJd3eNrxMouA==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/14 04:11:20 DEBUG : yam: Failed to rename to "sub/yam": 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/ihQ6WxovZamjSKUZuBZX86_vs9Qp8RWNvvZyYgk5WJwhog923gMBc4Gyl7WYYeUpQClXhjgcIvwJd3eNrxMouA==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/14 04:11:20 DEBUG : yam: Need to transfer - No matching file found at Destination 2025/09/14 04:11:20 DEBUG : proton drive root link ID 'rclone-test-sofihog9wepu': Waiting for transfers to finish 2025/09/14 04:11:22 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/09/14 04:11:22 INFO : yam: Copied (new) 2025/09/14 04:11:22 ERROR : proton drive root link ID 'rclone-test-sofihog9wepu': not deleting files as there were IO errors 2025/09/14 04:11:22 ERROR : proton drive root link ID 'rclone-test-sofihog9wepu': not deleting directories as there were IO errors sync_test.go:1697: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1697 Error: Received unexpected error: not deleting files as there were IO errors Test: TestSyncWithTrackRenamesStrategyLeaf --- FAIL: TestSyncWithTrackRenamesStrategyLeaf (12.45s) === RUN TestMoveWithDeleteEmptySrcDirs run.go:180: Remote "proton drive root link ID 'rclone-test-sofihog9wepu'", Local "Local file system at /tmp/rclone248909538", Modify Window "1s" 2025/09/14 04:11:27 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/09/14 04:11:27 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination 2025/09/14 04:11:27 DEBUG : proton drive root link ID 'rclone-test-sofihog9wepu': Waiting for checks to finish 2025/09/14 04:11:27 DEBUG : proton drive root link ID 'rclone-test-sofihog9wepu': Waiting for transfers to finish 2025/09/14 04:11:30 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2025/09/14 04:11:30 INFO : sub dir/hello world: Copied (new) 2025/09/14 04:11:30 INFO : sub dir/hello world: Deleted 2025/09/14 04:11:31 DEBUG : nested/sub dir/file: sha1 = b4b3e0a278988bc15f2913af3f4153ccef74e465 OK 2025/09/14 04:11:31 INFO : nested/sub dir/file: Copied (new) 2025/09/14 04:11:31 INFO : nested/sub dir/file: Deleted 2025/09/14 04:11:31 ERROR : Local file system at /tmp/rclone248909538: 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.90s) === RUN TestServerSideMove run.go:180: Remote "proton drive root link ID 'rclone-test-sofihog9wepu'", Local "Local file system at /tmp/rclone248909538", Modify Window "1s" 2025/09/14 04:11:35 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-lokexaz5cuve" 2025/09/14 04:11:35 DEBUG : proton drive root link ID 'rclone-test-lokexaz5cuve': Has cached credentials 2025/09/14 04:11:37 DEBUG : proton drive root link ID 'rclone-test-lokexaz5cuve': Used cached credential to initialize the ProtonDrive API sync_test.go:1735: Server side move (if possible) proton drive root link ID 'rclone-test-sofihog9wepu' -> proton drive root link ID 'rclone-test-lokexaz5cuve' 2025/09/14 04:11:49 DEBUG : proton drive root link ID 'rclone-test-lokexaz5cuve': Using server-side directory move 2025/09/14 04:11:49 INFO : proton drive root link ID 'rclone-test-lokexaz5cuve': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2025/09/14 04:11:51 DEBUG : potato2: Need to transfer - File not found at Destination 2025/09/14 04:11:51 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1s) 2025/09/14 04:11:51 DEBUG : empty space: Unchanged skipping 2025/09/14 04:11:51 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/09/14 04:11:51 DEBUG : proton drive root link ID 'rclone-test-lokexaz5cuve': Waiting for checks to finish 2025/09/14 04:11:51 INFO : empty space: Deleted 2025/09/14 04:11:51 DEBUG : proton drive root link ID 'rclone-test-lokexaz5cuve': Waiting for transfers to finish 2025/09/14 04:11:51 INFO : potato3: Deleted 2025/09/14 04:11:52.338759 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/-6itOsBdDXZ19ijAqLkOwhbUwQwyJU59YrZWb4lDJ8PTGpe3GLCE-GcwvkXSsSMBVnySq-JAk3CQEVT9GpEl4Q==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/14 04:11:52.338785 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/-6itOsBdDXZ19ijAqLkOwhbUwQwyJU59YrZWb4lDJ8PTGpe3GLCE-GcwvkXSsSMBVnySq-JAk3CQEVT9GpEl4Q==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/14 04:11:52 ERROR : potato2: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/-6itOsBdDXZ19ijAqLkOwhbUwQwyJU59YrZWb4lDJ8PTGpe3GLCE-GcwvkXSsSMBVnySq-JAk3CQEVT9GpEl4Q==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/14 04:11:52.655207 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/UIKtEhFZFeuMQNSFVViMzcbLON8s0DEDLZZ7gTfKDoLeTZ8LJmGNhKXVIxjBYuqKSRcCFPEh3kE06tILmovsvg==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/14 04:11:52.655237 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/UIKtEhFZFeuMQNSFVViMzcbLON8s0DEDLZZ7gTfKDoLeTZ8LJmGNhKXVIxjBYuqKSRcCFPEh3kE06tILmovsvg==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/14 04:11:52 ERROR : potato3: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/UIKtEhFZFeuMQNSFVViMzcbLON8s0DEDLZZ7gTfKDoLeTZ8LJmGNhKXVIxjBYuqKSRcCFPEh3kE06tILmovsvg==/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/UIKtEhFZFeuMQNSFVViMzcbLON8s0DEDLZZ7gTfKDoLeTZ8LJmGNhKXVIxjBYuqKSRcCFPEh3kE06tILmovsvg==/move: This value should not be blank. (Code=2000, Status=400) Test: TestServerSideMove 2025/09/14 04:11:52 DEBUG : proton drive root link ID 'rclone-test-lokexaz5cuve': Purge remote --- FAIL: TestServerSideMove (20.43s) === RUN TestServerSideMoveWithFilter run.go:180: Remote "proton drive root link ID 'rclone-test-sofihog9wepu'", Local "Local file system at /tmp/rclone248909538", Modify Window "1s" 2025/09/14 04:11:56 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-xutuyol7tede" 2025/09/14 04:11:56 DEBUG : proton drive root link ID 'rclone-test-xutuyol7tede': Has cached credentials 2025/09/14 04:11:58 DEBUG : proton drive root link ID 'rclone-test-xutuyol7tede': Used cached credential to initialize the ProtonDrive API sync_test.go:1735: Server side move (if possible) proton drive root link ID 'rclone-test-sofihog9wepu' -> proton drive root link ID 'rclone-test-xutuyol7tede' 2025/09/14 04:12:11 DEBUG : empty space: Excluded (Size Filter) 2025/09/14 04:12:11 DEBUG : empty space: Excluded 2025/09/14 04:12:11 DEBUG : empty space: Excluded (Size Filter) 2025/09/14 04:12:11 DEBUG : empty space: Excluded 2025/09/14 04:12:11 DEBUG : potato2: Need to transfer - File not found at Destination 2025/09/14 04:12:11 DEBUG : proton drive root link ID 'rclone-test-xutuyol7tede': Waiting for checks to finish 2025/09/14 04:12:11 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/09/14 04:12:11 DEBUG : proton drive root link ID 'rclone-test-xutuyol7tede': Waiting for transfers to finish 2025/09/14 04:12:11 INFO : potato3: Deleted 2025/09/14 04:12:12.337916 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/nJynvbsd0Q9sxKE362_v8-EzmXxYukQqypGGJkUfI4A45UoEWDSCoA8gNuvZAHe3PSa1iamItmHWkzsGqkcbgg==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/14 04:12:12.337953 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/nJynvbsd0Q9sxKE362_v8-EzmXxYukQqypGGJkUfI4A45UoEWDSCoA8gNuvZAHe3PSa1iamItmHWkzsGqkcbgg==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/14 04:12:12 ERROR : potato2: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/nJynvbsd0Q9sxKE362_v8-EzmXxYukQqypGGJkUfI4A45UoEWDSCoA8gNuvZAHe3PSa1iamItmHWkzsGqkcbgg==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/14 04:12:12.750808 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/Mu_OrEL3nvskyo4NEk17RMGsZRCxJqj_HqN9-2JIuJG-3kYsrCpocEkTGdrmEd6l7byPVc1s9GbHz8Jt8SPMAw==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/14 04:12:12.750841 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/Mu_OrEL3nvskyo4NEk17RMGsZRCxJqj_HqN9-2JIuJG-3kYsrCpocEkTGdrmEd6l7byPVc1s9GbHz8Jt8SPMAw==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/14 04:12:12 ERROR : potato3: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/Mu_OrEL3nvskyo4NEk17RMGsZRCxJqj_HqN9-2JIuJG-3kYsrCpocEkTGdrmEd6l7byPVc1s9GbHz8Jt8SPMAw==/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/Mu_OrEL3nvskyo4NEk17RMGsZRCxJqj_HqN9-2JIuJG-3kYsrCpocEkTGdrmEd6l7byPVc1s9GbHz8Jt8SPMAw==/move: This value should not be blank. (Code=2000, Status=400) Test: TestServerSideMoveWithFilter 2025/09/14 04:12:12 DEBUG : proton drive root link ID 'rclone-test-xutuyol7tede': Purge remote --- FAIL: TestServerSideMoveWithFilter (21.26s) === RUN TestServerSideMoveDeleteEmptySourceDirs run.go:180: Remote "proton drive root link ID 'rclone-test-sofihog9wepu'", Local "Local file system at /tmp/rclone248909538", Modify Window "1s" 2025/09/14 04:12:17 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-yoruzov1nuju" 2025/09/14 04:12:17 DEBUG : proton drive root link ID 'rclone-test-yoruzov1nuju': Has cached credentials 2025/09/14 04:12:19 DEBUG : proton drive root link ID 'rclone-test-yoruzov1nuju': Used cached credential to initialize the ProtonDrive API 2025/09/14 04:12:25 INFO : tomatoDir: Making directory sync_test.go:1735: Server side move (if possible) proton drive root link ID 'rclone-test-sofihog9wepu' -> proton drive root link ID 'rclone-test-yoruzov1nuju' 2025/09/14 04:12:33 DEBUG : proton drive root link ID 'rclone-test-yoruzov1nuju': Using server-side directory move 2025/09/14 04:12:33 INFO : proton drive root link ID 'rclone-test-yoruzov1nuju': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2025/09/14 04:12:34 DEBUG : potato2: Need to transfer - File not found at Destination 2025/09/14 04:12:34 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1s) 2025/09/14 04:12:34 DEBUG : empty space: Unchanged skipping 2025/09/14 04:12:34 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/09/14 04:12:34 DEBUG : proton drive root link ID 'rclone-test-yoruzov1nuju': Waiting for checks to finish 2025/09/14 04:12:35 INFO : empty space: Deleted 2025/09/14 04:12:35 DEBUG : proton drive root link ID 'rclone-test-yoruzov1nuju': Waiting for transfers to finish 2025/09/14 04:12:35 INFO : potato3: Deleted 2025/09/14 04:12:35.800854 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/GpEMExgdzgztPPSx_qN1fm5M1DtgPGOwb39UjesS_CRy8sR6hFu9ggJc4SLQd47a2fNJRg3CWuccsNYuc4Q0nQ==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/14 04:12:35.800891 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/GpEMExgdzgztPPSx_qN1fm5M1DtgPGOwb39UjesS_CRy8sR6hFu9ggJc4SLQd47a2fNJRg3CWuccsNYuc4Q0nQ==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/14 04:12:35 ERROR : potato2: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/GpEMExgdzgztPPSx_qN1fm5M1DtgPGOwb39UjesS_CRy8sR6hFu9ggJc4SLQd47a2fNJRg3CWuccsNYuc4Q0nQ==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/14 04:12:36.218480 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/1yxgb_8AAaWtD_9GjhjuHy581h7BH0dZW0KIu3NO5laiO6jPTbCUEgN7cx_r0Becjbxyov5oCQjELM7vdrBlzQ==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/14 04:12:36.218511 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/1yxgb_8AAaWtD_9GjhjuHy581h7BH0dZW0KIu3NO5laiO6jPTbCUEgN7cx_r0Becjbxyov5oCQjELM7vdrBlzQ==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/14 04:12:36 ERROR : potato3: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/1yxgb_8AAaWtD_9GjhjuHy581h7BH0dZW0KIu3NO5laiO6jPTbCUEgN7cx_r0Becjbxyov5oCQjELM7vdrBlzQ==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/14 04:12:36 ERROR : proton drive root link ID 'rclone-test-sofihog9wepu': 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/14 04:12:36 DEBUG : proton drive root link ID 'rclone-test-yoruzov1nuju': Purge remote --- FAIL: TestServerSideMoveDeleteEmptySourceDirs (23.40s) === RUN TestSyncBackupDir run.go:180: Remote "proton drive root link ID 'rclone-test-sofihog9wepu'", Local "Local file system at /tmp/rclone248909538", Modify Window "1s" 2025/09/14 04:12:48 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-sofihog9wepu/dst" 2025/09/14 04:12:48 DEBUG : proton drive root link ID 'rclone-test-sofihog9wepu/dst': Has cached credentials 2025/09/14 04:12:50 DEBUG : proton drive root link ID 'rclone-test-sofihog9wepu/dst': Used cached credential to initialize the ProtonDrive API 2025/09/14 04:14:26 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-sofihog9wepu/backup" 2025/09/14 04:14:26 DEBUG : proton drive root link ID 'rclone-test-sofihog9wepu/backup': Has cached credentials 2025/09/14 04:14:28 DEBUG : proton drive root link ID 'rclone-test-sofihog9wepu/backup': Used cached credential to initialize the ProtonDrive API 2025/09/14 04:17:39 DEBUG : proton drive root link ID 'rclone-test-sofihog9wepu/dst': Waiting for checks to finish 2025/09/14 04:17:39 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/09/14 04:17:39 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/09/14 04:17:39 DEBUG : two: Unchanged skipping 2025/09/14 04:17:41.779777 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/lMTAcbKQch0f_3fAee4V3XnKzhRyorlBjw2qJcGO--ef6jAaR7GWyi9yvSC6Qoy1oCF8IZXHUk-TodfmCVEuRA==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/14 04:17:41.779802 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/lMTAcbKQch0f_3fAee4V3XnKzhRyorlBjw2qJcGO--ef6jAaR7GWyi9yvSC6Qoy1oCF8IZXHUk-TodfmCVEuRA==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/14 04:17:41 ERROR : one: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/lMTAcbKQch0f_3fAee4V3XnKzhRyorlBjw2qJcGO--ef6jAaR7GWyi9yvSC6Qoy1oCF8IZXHUk-TodfmCVEuRA==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/14 04:17:41 DEBUG : proton drive root link ID 'rclone-test-sofihog9wepu/dst': Waiting for transfers to finish 2025/09/14 04:17:41 ERROR : proton drive root link ID 'rclone-test-sofihog9wepu/dst': not deleting files as there were IO errors 2025/09/14 04:17:41 ERROR : proton drive root link ID 'rclone-test-sofihog9wepu/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/lMTAcbKQch0f_3fAee4V3XnKzhRyorlBjw2qJcGO--ef6jAaR7GWyi9yvSC6Qoy1oCF8IZXHUk-TodfmCVEuRA==/move: This value should not be blank. (Code=2000, Status=400) Test: TestSyncBackupDir --- FAIL: TestSyncBackupDir (306.26s) === RUN TestSyncBackupDirWithSuffix run.go:180: Remote "proton drive root link ID 'rclone-test-sofihog9wepu'", Local "Local file system at /tmp/rclone248909538", Modify Window "1s" 2025/09/14 04:17:54 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-sofihog9wepu/dst" 2025/09/14 04:17:54 DEBUG : Config file has changed externally - reloading 2025/09/14 04:17:54 DEBUG : proton drive root link ID 'rclone-test-sofihog9wepu/dst': Has cached credentials 2025/09/14 04:17:57 DEBUG : proton drive root link ID 'rclone-test-sofihog9wepu/dst': Used cached credential to initialize the ProtonDrive API 2025/09/14 04:19:34 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-sofihog9wepu/backup" 2025/09/14 04:19:34 DEBUG : Config file has changed externally - reloading 2025/09/14 04:19:34 DEBUG : proton drive root link ID 'rclone-test-sofihog9wepu/backup': Has cached credentials 2025/09/14 04:19:36 DEBUG : proton drive root link ID 'rclone-test-sofihog9wepu/backup': Used cached credential to initialize the ProtonDrive API 2025/09/14 04:22:47 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/09/14 04:22:47 DEBUG : proton drive root link ID 'rclone-test-sofihog9wepu/dst': Waiting for checks to finish 2025/09/14 04:22:47 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/09/14 04:22:47 DEBUG : two: Unchanged skipping 2025/09/14 04:22:49.677267 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/TtPJb50sehEgafgVzCSCFuzrrJDvHAhqViSmH8hzmw4vfu4E9nBzDPIDzbnXYUA7rSpcrTDcsue-xa21-1shpQ==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/14 04:22:49.677335 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/TtPJb50sehEgafgVzCSCFuzrrJDvHAhqViSmH8hzmw4vfu4E9nBzDPIDzbnXYUA7rSpcrTDcsue-xa21-1shpQ==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/14 04:22:49 ERROR : one: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/TtPJb50sehEgafgVzCSCFuzrrJDvHAhqViSmH8hzmw4vfu4E9nBzDPIDzbnXYUA7rSpcrTDcsue-xa21-1shpQ==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/14 04:22:49 DEBUG : proton drive root link ID 'rclone-test-sofihog9wepu/dst': Waiting for transfers to finish 2025/09/14 04:22:49 ERROR : proton drive root link ID 'rclone-test-sofihog9wepu/dst': not deleting files as there were IO errors 2025/09/14 04:22:49 ERROR : proton drive root link ID 'rclone-test-sofihog9wepu/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/TtPJb50sehEgafgVzCSCFuzrrJDvHAhqViSmH8hzmw4vfu4E9nBzDPIDzbnXYUA7rSpcrTDcsue-xa21-1shpQ==/move: This value should not be blank. (Code=2000, Status=400) Test: TestSyncBackupDirWithSuffix --- FAIL: TestSyncBackupDirWithSuffix (307.86s) === RUN TestSyncBackupDirWithSuffixKeepExtension run.go:180: Remote "proton drive root link ID 'rclone-test-sofihog9wepu'", Local "Local file system at /tmp/rclone248909538", Modify Window "1s" 2025/09/14 04:23:03 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-sofihog9wepu/dst" 2025/09/14 04:23:03 DEBUG : Config file has changed externally - reloading 2025/09/14 04:23:03 DEBUG : proton drive root link ID 'rclone-test-sofihog9wepu/dst': Has cached credentials 2025/09/14 04:23:05 DEBUG : proton drive root link ID 'rclone-test-sofihog9wepu/dst': Used cached credential to initialize the ProtonDrive API 2025/09/14 04:24:41 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-sofihog9wepu/backup" 2025/09/14 04:24:41 DEBUG : Config file has changed externally - reloading 2025/09/14 04:24:41 DEBUG : proton drive root link ID 'rclone-test-sofihog9wepu/backup': Has cached credentials 2025/09/14 04:24:43 DEBUG : proton drive root link ID 'rclone-test-sofihog9wepu/backup': Used cached credential to initialize the ProtonDrive API 2025/09/14 04:27:56 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/09/14 04:27:56 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/09/14 04:27:56 DEBUG : two: Unchanged skipping 2025/09/14 04:27:56 DEBUG : proton drive root link ID 'rclone-test-sofihog9wepu/dst': Waiting for checks to finish 2025/09/14 04:27:58.577663 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/WdKJycXlhJYY41yLB07UqTN5jG0V788hWqMYyDtS7g4iQk1b8AiE77LpbzMfQyX0qdz7NKoPrzNlFxJQkEg6zw==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/14 04:27:58.577691 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/WdKJycXlhJYY41yLB07UqTN5jG0V788hWqMYyDtS7g4iQk1b8AiE77LpbzMfQyX0qdz7NKoPrzNlFxJQkEg6zw==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/14 04:27:58 ERROR : one: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/WdKJycXlhJYY41yLB07UqTN5jG0V788hWqMYyDtS7g4iQk1b8AiE77LpbzMfQyX0qdz7NKoPrzNlFxJQkEg6zw==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/14 04:27:58 DEBUG : proton drive root link ID 'rclone-test-sofihog9wepu/dst': Waiting for transfers to finish 2025/09/14 04:27:58 ERROR : proton drive root link ID 'rclone-test-sofihog9wepu/dst': not deleting files as there were IO errors 2025/09/14 04:27:58 ERROR : proton drive root link ID 'rclone-test-sofihog9wepu/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/WdKJycXlhJYY41yLB07UqTN5jG0V788hWqMYyDtS7g4iQk1b8AiE77LpbzMfQyX0qdz7NKoPrzNlFxJQkEg6zw==/move: This value should not be blank. (Code=2000, Status=400) Test: TestSyncBackupDirWithSuffixKeepExtension --- FAIL: TestSyncBackupDirWithSuffixKeepExtension (309.22s) === RUN TestSyncBackupDirSuffixOnly run.go:180: Remote "proton drive root link ID 'rclone-test-sofihog9wepu'", Local "Local file system at /tmp/rclone248909538", Modify Window "1s" 2025/09/14 04:28:11 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-sofihog9wepu/dst" 2025/09/14 04:28:11 DEBUG : Config file has changed externally - reloading 2025/09/14 04:28:11 DEBUG : proton drive root link ID 'rclone-test-sofihog9wepu/dst': Has cached credentials 2025/09/14 04:28:13 DEBUG : proton drive root link ID 'rclone-test-sofihog9wepu/dst': Used cached credential to initialize the ProtonDrive API 2025/09/14 04:29:51 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/09/14 04:29:51 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/09/14 04:29:51 DEBUG : proton drive root link ID 'rclone-test-sofihog9wepu/dst': Waiting for checks to finish 2025/09/14 04:29:51 DEBUG : two: Unchanged skipping 2025/09/14 04:29:52.478422 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/t1fvCPJR7gxpM63l5-Ja4vYDk6E5ISoI2Tl_SRImQRG0oGanmXLAjgxtGdZmQp0Tm2Hm005mznoPVYytzRMTAQ==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/14 04:29:52.478455 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/t1fvCPJR7gxpM63l5-Ja4vYDk6E5ISoI2Tl_SRImQRG0oGanmXLAjgxtGdZmQp0Tm2Hm005mznoPVYytzRMTAQ==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/14 04:29:52 ERROR : one: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/t1fvCPJR7gxpM63l5-Ja4vYDk6E5ISoI2Tl_SRImQRG0oGanmXLAjgxtGdZmQp0Tm2Hm005mznoPVYytzRMTAQ==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/14 04:29:52 DEBUG : proton drive root link ID 'rclone-test-sofihog9wepu/dst': Waiting for transfers to finish 2025/09/14 04:29:52 ERROR : proton drive root link ID 'rclone-test-sofihog9wepu/dst': not deleting files as there were IO errors 2025/09/14 04:29:52 ERROR : proton drive root link ID 'rclone-test-sofihog9wepu/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/t1fvCPJR7gxpM63l5-Ja4vYDk6E5ISoI2Tl_SRImQRG0oGanmXLAjgxtGdZmQp0Tm2Hm005mznoPVYytzRMTAQ==/move: This value should not be blank. (Code=2000, Status=400) Test: TestSyncBackupDirSuffixOnly --- FAIL: TestSyncBackupDirSuffixOnly (113.22s) === RUN TestSyncSuffix run.go:180: Remote "proton drive root link ID 'rclone-test-sofihog9wepu'", Local "Local file system at /tmp/rclone248909538", Modify Window "1s" 2025/09/14 04:30:04 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-sofihog9wepu/dst" 2025/09/14 04:30:04 DEBUG : proton drive root link ID 'rclone-test-sofihog9wepu/dst': Has cached credentials 2025/09/14 04:30:07 DEBUG : proton drive root link ID 'rclone-test-sofihog9wepu/dst': Used cached credential to initialize the ProtonDrive API 2025/09/14 04:31:45 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/09/14 04:31:45.780131 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/cEw72O352NuNJwZ-j_S5vIp1PkspN3OSgAju6aGZ0z6HOLBYOHNewIyAsCV4sh-fzSytSuQp18LtSUGpMdXWtg==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/14 04:31:45.780179 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/cEw72O352NuNJwZ-j_S5vIp1PkspN3OSgAju6aGZ0z6HOLBYOHNewIyAsCV4sh-fzSytSuQp18LtSUGpMdXWtg==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/14 04:31:45 ERROR : one: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/cEw72O352NuNJwZ-j_S5vIp1PkspN3OSgAju6aGZ0z6HOLBYOHNewIyAsCV4sh-fzSytSuQp18LtSUGpMdXWtg==/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/cEw72O352NuNJwZ-j_S5vIp1PkspN3OSgAju6aGZ0z6HOLBYOHNewIyAsCV4sh-fzSytSuQp18LtSUGpMdXWtg==/move: This value should not be blank. (Code=2000, Status=400) Test: TestSyncSuffix --- FAIL: TestSyncSuffix (113.14s) === RUN TestSyncSuffixKeepExtension run.go:180: Remote "proton drive root link ID 'rclone-test-sofihog9wepu'", Local "Local file system at /tmp/rclone248909538", Modify Window "1s" 2025/09/14 04:31:58 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-sofihog9wepu/dst" 2025/09/14 04:31:58 DEBUG : proton drive root link ID 'rclone-test-sofihog9wepu/dst': Has cached credentials 2025/09/14 04:32:00 DEBUG : proton drive root link ID 'rclone-test-sofihog9wepu/dst': Used cached credential to initialize the ProtonDrive API 2025/09/14 04:33:36 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/09/14 04:33:37.145182 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/cgitF7CYKcjlWx6iv6nprDjBFCN9DAc9h1a7gMQTgAAXt9rOy5Oy5MMiofccMbcjFAL7sHL8fbYURsdbt6wjVA==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/09/14 04:33:37.145214 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/cgitF7CYKcjlWx6iv6nprDjBFCN9DAc9h1a7gMQTgAAXt9rOy5Oy5MMiofccMbcjFAL7sHL8fbYURsdbt6wjVA==/move: This value should not be blank. (Code=2000, Status=400) 2025/09/14 04:33:37 ERROR : one: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/cgitF7CYKcjlWx6iv6nprDjBFCN9DAc9h1a7gMQTgAAXt9rOy5Oy5MMiofccMbcjFAL7sHL8fbYURsdbt6wjVA==/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/cgitF7CYKcjlWx6iv6nprDjBFCN9DAc9h1a7gMQTgAAXt9rOy5Oy5MMiofccMbcjFAL7sHL8fbYURsdbt6wjVA==/move: This value should not be blank. (Code=2000, Status=400) Test: TestSyncSuffixKeepExtension --- FAIL: TestSyncSuffixKeepExtension (111.01s) === RUN TestSyncConcurrentTruncate run.go:180: Remote "proton drive root link ID 'rclone-test-sofihog9wepu'", Local "Local file system at /tmp/rclone248909538", Modify Window "1s" 2025/09/14 04:35:07 DEBUG : both10: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/09/14 04:35:07 DEBUG : both11: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/09/14 04:35:07 DEBUG : proton drive root link ID 'rclone-test-sofihog9wepu': Waiting for checks to finish 2025/09/14 04:35:07 DEBUG : both0: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/09/14 04:35:07 DEBUG : both12: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/09/14 04:35:07 DEBUG : both10: Unchanged skipping 2025/09/14 04:35:07 DEBUG : both13: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/09/14 04:35:07 DEBUG : both12: Unchanged skipping 2025/09/14 04:35:07 DEBUG : both14: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/09/14 04:35:07 DEBUG : both14: Unchanged skipping 2025/09/14 04:35:07 DEBUG : both15: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/09/14 04:35:07 DEBUG : both15: Unchanged skipping 2025/09/14 04:35:07 DEBUG : both16: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/09/14 04:35:07 DEBUG : both13: Unchanged skipping 2025/09/14 04:35:07 DEBUG : both17: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/09/14 04:35:07 DEBUG : both17: Unchanged skipping 2025/09/14 04:35:07 DEBUG : both18: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/09/14 04:35:07 DEBUG : both0: Unchanged skipping 2025/09/14 04:35:07 DEBUG : both19: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/09/14 04:35:07 DEBUG : both16: Unchanged skipping 2025/09/14 04:35:07 DEBUG : both11: Unchanged skipping 2025/09/14 04:35:07 DEBUG : both1: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/09/14 04:35:07 DEBUG : both2: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/09/14 04:35:07 DEBUG : both18: Unchanged skipping 2025/09/14 04:35:07 DEBUG : both3: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/09/14 04:35:07 DEBUG : both3: Unchanged skipping 2025/09/14 04:35:07 DEBUG : both4: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/09/14 04:35:07 DEBUG : both4: Unchanged skipping 2025/09/14 04:35:07 DEBUG : both5: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/09/14 04:35:07 DEBUG : both5: Unchanged skipping 2025/09/14 04:35:07 DEBUG : both6: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/09/14 04:35:07 DEBUG : both19: Unchanged skipping 2025/09/14 04:35:07 DEBUG : both7: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/09/14 04:35:07 DEBUG : both1: Unchanged skipping 2025/09/14 04:35:07 DEBUG : both8: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/09/14 04:35:07 DEBUG : both8: Unchanged skipping 2025/09/14 04:35:07 DEBUG : both9: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/09/14 04:35:07 DEBUG : both9: Unchanged skipping 2025/09/14 04:35:07 DEBUG : both2: Unchanged skipping 2025/09/14 04:35:07 DEBUG : both6: Unchanged skipping 2025/09/14 04:35:07 DEBUG : only11: Sizes differ (src 0 vs dst 6) 2025/09/14 04:35:07 DEBUG : only10: Sizes differ (src 0 vs dst 6) 2025/09/14 04:35:07 DEBUG : only0: Sizes differ (src 0 vs dst 6) 2025/09/14 04:35:07 DEBUG : both7: Unchanged skipping 2025/09/14 04:35:07 DEBUG : only12: Sizes differ (src 0 vs dst 6) 2025/09/14 04:35:07 DEBUG : only13: Sizes differ (src 0 vs dst 6) 2025/09/14 04:35:07 DEBUG : only14: Sizes differ (src 0 vs dst 6) 2025/09/14 04:35:07 DEBUG : only15: Sizes differ (src 0 vs dst 6) 2025/09/14 04:35:07 DEBUG : only16: Sizes differ (src 0 vs dst 6) 2025/09/14 04:35:07 DEBUG : only17: Sizes differ (src 0 vs dst 6) 2025/09/14 04:35:07 DEBUG : only18: Sizes differ (src 0 vs dst 6) 2025/09/14 04:35:07 DEBUG : only19: Sizes differ (src 0 vs dst 6) 2025/09/14 04:35:07 DEBUG : only1: Sizes differ (src 0 vs dst 6) 2025/09/14 04:35:07 DEBUG : only2: Sizes differ (src 0 vs dst 6) 2025/09/14 04:35:07 DEBUG : only3: Sizes differ (src 0 vs dst 6) 2025/09/14 04:35:07 DEBUG : only4: Sizes differ (src 0 vs dst 6) 2025/09/14 04:35:07 DEBUG : only5: Sizes differ (src 0 vs dst 6) 2025/09/14 04:35:07 DEBUG : only6: Sizes differ (src 0 vs dst 6) 2025/09/14 04:35:07 DEBUG : only7: Sizes differ (src 0 vs dst 6) 2025/09/14 04:35:07 DEBUG : only8: Sizes differ (src 0 vs dst 6) 2025/09/14 04:35:07 DEBUG : only9: Sizes differ (src 0 vs dst 6) 2025/09/14 04:35:07 DEBUG : proton drive root link ID 'rclone-test-sofihog9wepu': Waiting for transfers to finish 2025/09/14 04:35:07.800817 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/14 04:35:07.800847 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/14 04:35:07.804202 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/14 04:35:07.804222 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/14 04:35:07.837417 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/14 04:35:07.837446 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/14 04:35:08.101602 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/14 04:35:08.101625 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/14 04:35:09 DEBUG : only11: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/09/14 04:35:09 INFO : only11: Copied (replaced existing) 2025/09/14 04:35:09 DEBUG : only12: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/09/14 04:35:09 INFO : only12: Copied (replaced existing) 2025/09/14 04:35:09 DEBUG : only15: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/09/14 04:35:09 INFO : only15: Copied (replaced existing) 2025/09/14 04:35:09 DEBUG : only14: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/09/14 04:35:09 INFO : only14: Copied (replaced existing) 2025/09/14 04:35:09.445855 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/14 04:35:09.445916 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/14 04:35:09.495932 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/14 04:35:09.495972 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/14 04:35:09.522476 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/14 04:35:09.522517 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/14 04:35:09.779531 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/14 04:35:09.779595 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/14 04:35:10 DEBUG : only18: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/09/14 04:35:10 INFO : only18: Copied (replaced existing) 2025/09/14 04:35:10 DEBUG : only10: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/09/14 04:35:10 INFO : only10: Copied (replaced existing) 2025/09/14 04:35:10 DEBUG : only19: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/09/14 04:35:10 INFO : only19: Copied (replaced existing) 2025/09/14 04:35:10.845805 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/14 04:35:10.845842 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/14 04:35:11.128455 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/14 04:35:11.128482 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/14 04:35:11.191882 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/14 04:35:11.191916 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/14 04:35:11 DEBUG : only17: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/09/14 04:35:11 INFO : only17: Copied (replaced existing) 2025/09/14 04:35:11.751372 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/14 04:35:11.751421 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/14 04:35:12 DEBUG : only2: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/09/14 04:35:12 INFO : only2: Copied (replaced existing) 2025/09/14 04:35:12 DEBUG : only3: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/09/14 04:35:12 INFO : only3: Copied (replaced existing) 2025/09/14 04:35:12.701034 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/14 04:35:12.701062 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/14 04:35:12 DEBUG : only1: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/09/14 04:35:12 INFO : only1: Copied (replaced existing) 2025/09/14 04:35:13.073858 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/14 04:35:13.073886 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/14 04:35:13 DEBUG : only0: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/09/14 04:35:13 INFO : only0: Copied (replaced existing) 2025/09/14 04:35:13.188949 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/14 04:35:13.188989 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/14 04:35:13.534749 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/14 04:35:13.534780 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/14 04:35:14 DEBUG : only5: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/09/14 04:35:14 INFO : only5: Copied (replaced existing) 2025/09/14 04:35:14.184201 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/14 04:35:14.184236 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/14 04:35:14 DEBUG : only13: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/09/14 04:35:14 INFO : only13: Copied (replaced existing) 2025/09/14 04:35:14 DEBUG : only16: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/09/14 04:35:14 INFO : only16: Copied (replaced existing) 2025/09/14 04:35:14 DEBUG : only8: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/09/14 04:35:14 INFO : only8: Copied (replaced existing) 2025/09/14 04:35:14.884851 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/14 04:35:14.884880 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/14 04:35:14.990035 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/14 04:35:14.990082 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/14 04:35:15.079505 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/14 04:35:15.079528 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/14 04:35:15 DEBUG : only4: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/09/14 04:35:15 INFO : only4: Copied (replaced existing) 2025/09/14 04:35:16 DEBUG : only7: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/09/14 04:35:16 INFO : only7: Copied (replaced existing) 2025/09/14 04:35:16 DEBUG : only9: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/09/14 04:35:16 INFO : only9: Copied (replaced existing) 2025/09/14 04:35:16 DEBUG : only6: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/09/14 04:35:16 INFO : only6: Copied (replaced existing) 2025/09/14 04:35:16 DEBUG : Waiting for deletions to finish --- PASS: TestSyncConcurrentTruncate (141.73s) FAIL 2025/09/14 04:36:03 DEBUG : proton drive root link ID 'rclone-test-sofihog9wepu': Purge remote "./sync.test -test.v -test.timeout 1h0m0s -remote TestProtonDrive: -verbose -test.run '^(TestMoveWithDeleteEmptySrcDirs|TestServerSideMove|TestServerSideMoveDeleteEmptySourceDirs|TestServerSideMoveOverSelf|TestServerSideMoveWithFilter|TestSyncBackupDir|TestSyncBackupDirSuffixOnly|TestSyncBackupDirWithSuffix|TestSyncBackupDirWithSuffixKeepExtension|TestSyncConcurrentTruncate|TestSyncSuffix|TestSyncSuffixKeepExtension|TestSyncWithTrackRenames|TestSyncWithTrackRenamesStrategyLeaf|TestSyncWithTrackRenamesStrategyModtime)$'" - Finished ERROR in 25m39.061089855s (try 2/5): exit status 1: Failed [TestServerSideMoveOverSelf TestSyncWithTrackRenames TestSyncWithTrackRenamesStrategyModtime TestSyncWithTrackRenamesStrategyLeaf TestMoveWithDeleteEmptySrcDirs TestServerSideMove TestServerSideMoveWithFilter TestServerSideMoveDeleteEmptySourceDirs TestSyncBackupDir TestSyncBackupDirWithSuffix TestSyncBackupDirWithSuffixKeepExtension TestSyncBackupDirSuffixOnly TestSyncSuffix TestSyncSuffixKeepExtension]