"./sync.test -test.v -test.timeout 1h0m0s -remote TestProtonDrive: -verbose -test.run '^(TestMoveWithDeleteEmptySrcDirs|TestServerSideMove|TestServerSideMoveDeleteEmptySourceDirs|TestServerSideMoveOverSelf|TestServerSideMoveWithFilter|TestSyncBackupDir|TestSyncBackupDirSuffixOnly|TestSyncBackupDirWithSuffix|TestSyncBackupDirWithSuffixKeepExtension|TestSyncSuffix|TestSyncSuffixKeepExtension|TestSyncWithTrackRenames|TestSyncWithTrackRenamesStrategyLeaf|TestSyncWithTrackRenamesStrategyModtime)$'" - Starting (try 5/5) 2025/06/21 06:15:40 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-falaxer4lali" 2025/06/21 06:15:40 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/06/21 06:15:40 DEBUG : proton drive root link ID 'rclone-test-falaxer4lali': Has cached credentials 2025/06/21 06:15:42 DEBUG : proton drive root link ID 'rclone-test-falaxer4lali': Used cached credential to initialize the ProtonDrive API 2025/06/21 06:15:42 DEBUG : Creating backend with remote "/tmp/rclone3700911434" === RUN TestServerSideMoveOverSelf run.go:180: Remote "proton drive root link ID 'rclone-test-falaxer4lali'", Local "Local file system at /tmp/rclone3700911434", Modify Window "1s" 2025/06/21 06:15:47 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-fofujim0vape" 2025/06/21 06:15:47 DEBUG : proton drive root link ID 'rclone-test-fofujim0vape': Has cached credentials 2025/06/21 06:15:49 DEBUG : proton drive root link ID 'rclone-test-fofujim0vape': Used cached credential to initialize the ProtonDrive API sync_test.go:672: Server side copy (if possible) proton drive root link ID 'rclone-test-falaxer4lali' -> proton drive root link ID 'rclone-test-fofujim0vape' 2025/06/21 06:15:50 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/06/21 06:15:50 DEBUG : proton drive root link ID 'rclone-test-fofujim0vape': Waiting for checks to finish 2025/06/21 06:15:50 DEBUG : proton drive root link ID 'rclone-test-fofujim0vape': Waiting for transfers to finish 2025/06/21 06:15:55 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2025/06/21 06:15:55 INFO : sub dir/hello world: Copied (new) 2025/06/21 06:15:57.582176 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/06/21 06:15:57.582219 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/06/21 06:16:00 DEBUG : proton drive root link ID 'rclone-test-fofujim0vape': Using server-side directory move 2025/06/21 06:16:00 INFO : proton drive root link ID 'rclone-test-fofujim0vape': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2025/06/21 06:16:01 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/06/21 06:16:01 DEBUG : proton drive root link ID 'rclone-test-fofujim0vape': Waiting for checks to finish 2025/06/21 06:16:01 DEBUG : proton drive root link ID 'rclone-test-fofujim0vape': Waiting for transfers to finish 2025/06/21 06:16:02 INFO : sub dir/hello world: Deleted 2025/06/21 06:16:03.263663 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/MSAP2wj6R-TCxDT_LcvpqGuYiFwF1WJarB4fosNSE315sO9W1tluhPn-bns37zhAB54g2DFIvlugw0xjcpvoJw==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/06/21 06:16:03.263711 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/MSAP2wj6R-TCxDT_LcvpqGuYiFwF1WJarB4fosNSE315sO9W1tluhPn-bns37zhAB54g2DFIvlugw0xjcpvoJw==/move: This value should not be blank. (Code=2000, Status=400) 2025/06/21 06:16:03 ERROR : sub dir/hello world: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/MSAP2wj6R-TCxDT_LcvpqGuYiFwF1WJarB4fosNSE315sO9W1tluhPn-bns37zhAB54g2DFIvlugw0xjcpvoJw==/move: This value should not be blank. (Code=2000, Status=400) sync_test.go:685: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:685 Error: Received unexpected error: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/MSAP2wj6R-TCxDT_LcvpqGuYiFwF1WJarB4fosNSE315sO9W1tluhPn-bns37zhAB54g2DFIvlugw0xjcpvoJw==/move: This value should not be blank. (Code=2000, Status=400) Test: TestServerSideMoveOverSelf 2025/06/21 06:16:03 DEBUG : proton drive root link ID 'rclone-test-fofujim0vape': Purge remote --- FAIL: TestServerSideMoveOverSelf (23.43s) === RUN TestSyncWithTrackRenames run.go:180: Remote "proton drive root link ID 'rclone-test-falaxer4lali'", Local "Local file system at /tmp/rclone3700911434", Modify Window "1s" sync_test.go:1539: Can track renames: true 2025/06/21 06:16:06 INFO : proton drive root link ID 'rclone-test-falaxer4lali': Making map for --track-renames 2025/06/21 06:16:06 INFO : proton drive root link ID 'rclone-test-falaxer4lali': Finished making map for --track-renames 2025/06/21 06:16:06 DEBUG : proton drive root link ID 'rclone-test-falaxer4lali': Waiting for checks to finish 2025/06/21 06:16:06 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/06/21 06:16:06 DEBUG : proton drive root link ID 'rclone-test-falaxer4lali': Waiting for renames to finish 2025/06/21 06:16:06 DEBUG : yam: Need to transfer - No matching file found at Destination 2025/06/21 06:16:06 DEBUG : proton drive root link ID 'rclone-test-falaxer4lali': Waiting for transfers to finish 2025/06/21 06:16:08 DEBUG : potato: sha1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2025/06/21 06:16:08 INFO : potato: Copied (new) 2025/06/21 06:16:08 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/06/21 06:16:08 INFO : yam: Copied (new) 2025/06/21 06:16:08 DEBUG : Waiting for deletions to finish 2025/06/21 06:16:10 DEBUG : potato: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/06/21 06:16:10 INFO : proton drive root link ID 'rclone-test-falaxer4lali': Making map for --track-renames 2025/06/21 06:16:10 INFO : proton drive root link ID 'rclone-test-falaxer4lali': Finished making map for --track-renames 2025/06/21 06:16:10 DEBUG : proton drive root link ID 'rclone-test-falaxer4lali': Waiting for checks to finish 2025/06/21 06:16:10 DEBUG : potato: Unchanged skipping 2025/06/21 06:16:10 DEBUG : proton drive root link ID 'rclone-test-falaxer4lali': Waiting for renames to finish 2025/06/21 06:16:11.014814 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/Uj54t4fRoxJJUaeF7LxFQW4N5Aq-RJJWu5szLhohQjUVSTrAcbIx0Om0c6FzF7VLsCqLtqYC6i7aUVdw_PdZlQ==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/06/21 06:16:11.014852 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/Uj54t4fRoxJJUaeF7LxFQW4N5Aq-RJJWu5szLhohQjUVSTrAcbIx0Om0c6FzF7VLsCqLtqYC6i7aUVdw_PdZlQ==/move: This value should not be blank. (Code=2000, Status=400) 2025/06/21 06:16:11 ERROR : yam: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/Uj54t4fRoxJJUaeF7LxFQW4N5Aq-RJJWu5szLhohQjUVSTrAcbIx0Om0c6FzF7VLsCqLtqYC6i7aUVdw_PdZlQ==/move: This value should not be blank. (Code=2000, Status=400) 2025/06/21 06:16:11 DEBUG : yaml: Failed to rename to "yam": 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/Uj54t4fRoxJJUaeF7LxFQW4N5Aq-RJJWu5szLhohQjUVSTrAcbIx0Om0c6FzF7VLsCqLtqYC6i7aUVdw_PdZlQ==/move: This value should not be blank. (Code=2000, Status=400) 2025/06/21 06:16:11 DEBUG : yaml: Need to transfer - No matching file found at Destination 2025/06/21 06:16:11 DEBUG : proton drive root link ID 'rclone-test-falaxer4lali': Waiting for transfers to finish 2025/06/21 06:16:12 DEBUG : yaml: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/06/21 06:16:12 INFO : yaml: Copied (new) 2025/06/21 06:16:12 ERROR : proton drive root link ID 'rclone-test-falaxer4lali': not deleting files as there were IO errors 2025/06/21 06:16:12 ERROR : proton drive root link ID 'rclone-test-falaxer4lali': not deleting directories as there were IO errors sync_test.go:1557: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1557 Error: Received unexpected error: not deleting files as there were IO errors Test: TestSyncWithTrackRenames --- FAIL: TestSyncWithTrackRenames (9.35s) === RUN TestSyncWithTrackRenamesStrategyModtime run.go:180: Remote "proton drive root link ID 'rclone-test-falaxer4lali'", Local "Local file system at /tmp/rclone3700911434", Modify Window "1s" sync_test.go:1611: Can track renames: true 2025/06/21 06:16:15 INFO : proton drive root link ID 'rclone-test-falaxer4lali': Making map for --track-renames 2025/06/21 06:16:15 INFO : proton drive root link ID 'rclone-test-falaxer4lali': Finished making map for --track-renames 2025/06/21 06:16:15 DEBUG : proton drive root link ID 'rclone-test-falaxer4lali': Waiting for checks to finish 2025/06/21 06:16:15 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/06/21 06:16:15 DEBUG : yam: Need to transfer - No matching file found at Destination 2025/06/21 06:16:15 DEBUG : proton drive root link ID 'rclone-test-falaxer4lali': Waiting for renames to finish 2025/06/21 06:16:15 DEBUG : proton drive root link ID 'rclone-test-falaxer4lali': Waiting for transfers to finish 2025/06/21 06:16:17 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/06/21 06:16:17 INFO : yam: Copied (new) 2025/06/21 06:16:17 DEBUG : potato: sha1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2025/06/21 06:16:17 INFO : potato: Copied (new) 2025/06/21 06:16:17 DEBUG : Waiting for deletions to finish 2025/06/21 06:16:19 DEBUG : potato: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/06/21 06:16:19 INFO : proton drive root link ID 'rclone-test-falaxer4lali': Making map for --track-renames 2025/06/21 06:16:19 INFO : proton drive root link ID 'rclone-test-falaxer4lali': Finished making map for --track-renames 2025/06/21 06:16:19 DEBUG : proton drive root link ID 'rclone-test-falaxer4lali': Waiting for checks to finish 2025/06/21 06:16:19 DEBUG : potato: Unchanged skipping 2025/06/21 06:16:19 DEBUG : proton drive root link ID 'rclone-test-falaxer4lali': Waiting for renames to finish 2025/06/21 06:16:20.210077 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/5wUmXb6OruKPSKUA0j9gydssrmlhdWjWhtGWZ_ZoJbeeP8qS54Nq1S3KEh1DZ8qyB-nXB4cP1vbk4e0fgIv35Q==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/06/21 06:16:20.210112 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/5wUmXb6OruKPSKUA0j9gydssrmlhdWjWhtGWZ_ZoJbeeP8qS54Nq1S3KEh1DZ8qyB-nXB4cP1vbk4e0fgIv35Q==/move: This value should not be blank. (Code=2000, Status=400) 2025/06/21 06:16:20 ERROR : yam: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/5wUmXb6OruKPSKUA0j9gydssrmlhdWjWhtGWZ_ZoJbeeP8qS54Nq1S3KEh1DZ8qyB-nXB4cP1vbk4e0fgIv35Q==/move: This value should not be blank. (Code=2000, Status=400) 2025/06/21 06:16:20 DEBUG : yaml: Failed to rename to "yam": 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/5wUmXb6OruKPSKUA0j9gydssrmlhdWjWhtGWZ_ZoJbeeP8qS54Nq1S3KEh1DZ8qyB-nXB4cP1vbk4e0fgIv35Q==/move: This value should not be blank. (Code=2000, Status=400) 2025/06/21 06:16:20 DEBUG : yaml: Need to transfer - No matching file found at Destination 2025/06/21 06:16:20 DEBUG : proton drive root link ID 'rclone-test-falaxer4lali': Waiting for transfers to finish 2025/06/21 06:16:22 DEBUG : yaml: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/06/21 06:16:22 INFO : yaml: Copied (new) 2025/06/21 06:16:22 ERROR : proton drive root link ID 'rclone-test-falaxer4lali': not deleting files as there were IO errors 2025/06/21 06:16:22 ERROR : proton drive root link ID 'rclone-test-falaxer4lali': not deleting directories as there were IO errors sync_test.go:1629: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1629 Error: Received unexpected error: not deleting files as there were IO errors Test: TestSyncWithTrackRenamesStrategyModtime --- FAIL: TestSyncWithTrackRenamesStrategyModtime (9.35s) === RUN TestSyncWithTrackRenamesStrategyLeaf run.go:180: Remote "proton drive root link ID 'rclone-test-falaxer4lali'", Local "Local file system at /tmp/rclone3700911434", Modify Window "1s" sync_test.go:1650: Can track renames: true 2025/06/21 06:16:25 INFO : proton drive root link ID 'rclone-test-falaxer4lali': Making map for --track-renames 2025/06/21 06:16:25 INFO : proton drive root link ID 'rclone-test-falaxer4lali': Finished making map for --track-renames 2025/06/21 06:16:25 DEBUG : proton drive root link ID 'rclone-test-falaxer4lali': Waiting for checks to finish 2025/06/21 06:16:25 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/06/21 06:16:25 DEBUG : sub/yam: Need to transfer - No matching file found at Destination 2025/06/21 06:16:25 DEBUG : proton drive root link ID 'rclone-test-falaxer4lali': Waiting for renames to finish 2025/06/21 06:16:25 DEBUG : proton drive root link ID 'rclone-test-falaxer4lali': Waiting for transfers to finish 2025/06/21 06:16:28 DEBUG : potato: sha1 = 1d1ac61cfffcfdd0a15cc9ae575fb06ca8c11fb5 OK 2025/06/21 06:16:28 INFO : potato: Copied (new) 2025/06/21 06:16:28 DEBUG : sub/yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/06/21 06:16:28 INFO : sub/yam: Copied (new) 2025/06/21 06:16:28 DEBUG : Waiting for deletions to finish 2025/06/21 06:16:29 DEBUG : potato: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/06/21 06:16:29 DEBUG : potato: Unchanged skipping 2025/06/21 06:16:30 INFO : proton drive root link ID 'rclone-test-falaxer4lali': Making map for --track-renames 2025/06/21 06:16:30 INFO : proton drive root link ID 'rclone-test-falaxer4lali': Finished making map for --track-renames 2025/06/21 06:16:30 DEBUG : proton drive root link ID 'rclone-test-falaxer4lali': Waiting for checks to finish 2025/06/21 06:16:30 DEBUG : proton drive root link ID 'rclone-test-falaxer4lali': Waiting for renames to finish 2025/06/21 06:16:31.162605 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/5ox2UtHXN5qLdbQnWzOVBDmpwXUtSxHtR_pg2CJO4spkil4_8MyFJIv8ShlpX627nf9SvA_9i2r8TaOYYuus4w==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/06/21 06:16:31.162644 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/5ox2UtHXN5qLdbQnWzOVBDmpwXUtSxHtR_pg2CJO4spkil4_8MyFJIv8ShlpX627nf9SvA_9i2r8TaOYYuus4w==/move: This value should not be blank. (Code=2000, Status=400) 2025/06/21 06:16:31 ERROR : sub/yam: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/5ox2UtHXN5qLdbQnWzOVBDmpwXUtSxHtR_pg2CJO4spkil4_8MyFJIv8ShlpX627nf9SvA_9i2r8TaOYYuus4w==/move: This value should not be blank. (Code=2000, Status=400) 2025/06/21 06:16:31 DEBUG : yam: Failed to rename to "sub/yam": 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/5ox2UtHXN5qLdbQnWzOVBDmpwXUtSxHtR_pg2CJO4spkil4_8MyFJIv8ShlpX627nf9SvA_9i2r8TaOYYuus4w==/move: This value should not be blank. (Code=2000, Status=400) 2025/06/21 06:16:31 DEBUG : yam: Need to transfer - No matching file found at Destination 2025/06/21 06:16:31 DEBUG : proton drive root link ID 'rclone-test-falaxer4lali': Waiting for transfers to finish 2025/06/21 06:16:33 DEBUG : yam: sha1 = ad84b2fb6729688a5ee3225164a6d4d58465b33e OK 2025/06/21 06:16:33 INFO : yam: Copied (new) 2025/06/21 06:16:33 ERROR : proton drive root link ID 'rclone-test-falaxer4lali': not deleting files as there were IO errors 2025/06/21 06:16:33 ERROR : proton drive root link ID 'rclone-test-falaxer4lali': not deleting directories as there were IO errors sync_test.go:1668: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1668 Error: Received unexpected error: not deleting files as there were IO errors Test: TestSyncWithTrackRenamesStrategyLeaf --- FAIL: TestSyncWithTrackRenamesStrategyLeaf (11.95s) === RUN TestMoveWithDeleteEmptySrcDirs run.go:180: Remote "proton drive root link ID 'rclone-test-falaxer4lali'", Local "Local file system at /tmp/rclone3700911434", Modify Window "1s" 2025/06/21 06:16:37 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/06/21 06:16:37 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination 2025/06/21 06:16:37 DEBUG : proton drive root link ID 'rclone-test-falaxer4lali': Waiting for checks to finish 2025/06/21 06:16:37 DEBUG : proton drive root link ID 'rclone-test-falaxer4lali': Waiting for transfers to finish 2025/06/21 06:16:42 DEBUG : nested/sub dir/file: sha1 = b4b3e0a278988bc15f2913af3f4153ccef74e465 OK 2025/06/21 06:16:42 INFO : nested/sub dir/file: Copied (new) 2025/06/21 06:16:42 INFO : nested/sub dir/file: Deleted 2025/06/21 06:16:42 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK 2025/06/21 06:16:42 INFO : sub dir/hello world: Copied (new) 2025/06/21 06:16:42 INFO : sub dir/hello world: Deleted 2025/06/21 06:16:42 ERROR : Local file system at /tmp/rclone3700911434: not deleting directories as there were IO errors sync_test.go:1789: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1789 Error: Received unexpected error: not deleting directories as there were IO errors Test: TestMoveWithDeleteEmptySrcDirs --- FAIL: TestMoveWithDeleteEmptySrcDirs (9.58s) === RUN TestServerSideMove run.go:180: Remote "proton drive root link ID 'rclone-test-falaxer4lali'", Local "Local file system at /tmp/rclone3700911434", Modify Window "1s" 2025/06/21 06:16:46 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-wavupex7femo" 2025/06/21 06:16:46 DEBUG : proton drive root link ID 'rclone-test-wavupex7femo': Has cached credentials 2025/06/21 06:16:47 DEBUG : proton drive root link ID 'rclone-test-wavupex7femo': Used cached credential to initialize the ProtonDrive API sync_test.go:1706: Server side move (if possible) proton drive root link ID 'rclone-test-falaxer4lali' -> proton drive root link ID 'rclone-test-wavupex7femo' 2025/06/21 06:17:00 DEBUG : proton drive root link ID 'rclone-test-wavupex7femo': Using server-side directory move 2025/06/21 06:17:00 INFO : proton drive root link ID 'rclone-test-wavupex7femo': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2025/06/21 06:17:01 DEBUG : potato2: Need to transfer - File not found at Destination 2025/06/21 06:17:01 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1s) 2025/06/21 06:17:01 DEBUG : empty space: Unchanged skipping 2025/06/21 06:17:01 DEBUG : proton drive root link ID 'rclone-test-wavupex7femo': Waiting for checks to finish 2025/06/21 06:17:01 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/06/21 06:17:02 INFO : empty space: Deleted 2025/06/21 06:17:02 DEBUG : proton drive root link ID 'rclone-test-wavupex7femo': Waiting for transfers to finish 2025/06/21 06:17:02 INFO : potato3: Deleted 2025/06/21 06:17:02.581813 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/G9CAmHy5HHpsJt9m3kayOy4gEyqSIvh5OlBkNz1uF5lqvZnA01_Cldl5zaS6IWO5NgcZOqYbQsKES2qlsN4oRw==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/06/21 06:17:02.581855 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/G9CAmHy5HHpsJt9m3kayOy4gEyqSIvh5OlBkNz1uF5lqvZnA01_Cldl5zaS6IWO5NgcZOqYbQsKES2qlsN4oRw==/move: This value should not be blank. (Code=2000, Status=400) 2025/06/21 06:17:02 ERROR : potato2: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/G9CAmHy5HHpsJt9m3kayOy4gEyqSIvh5OlBkNz1uF5lqvZnA01_Cldl5zaS6IWO5NgcZOqYbQsKES2qlsN4oRw==/move: This value should not be blank. (Code=2000, Status=400) 2025/06/21 06:17:02.937015 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/mXE1uWG8C_WqmJbtlbUuRWZUC2ltCZwS6-J60IJO4G6XktCsTokQLKLUaXs33kkn2hmey92kb4f9Yzb5sLCgTA==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/06/21 06:17:02.937051 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/mXE1uWG8C_WqmJbtlbUuRWZUC2ltCZwS6-J60IJO4G6XktCsTokQLKLUaXs33kkn2hmey92kb4f9Yzb5sLCgTA==/move: This value should not be blank. (Code=2000, Status=400) 2025/06/21 06:17:02 ERROR : potato3: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/mXE1uWG8C_WqmJbtlbUuRWZUC2ltCZwS6-J60IJO4G6XktCsTokQLKLUaXs33kkn2hmey92kb4f9Yzb5sLCgTA==/move: This value should not be blank. (Code=2000, Status=400) sync_test.go:1717: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1717 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1882 Error: Received unexpected error: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/mXE1uWG8C_WqmJbtlbUuRWZUC2ltCZwS6-J60IJO4G6XktCsTokQLKLUaXs33kkn2hmey92kb4f9Yzb5sLCgTA==/move: This value should not be blank. (Code=2000, Status=400) Test: TestServerSideMove 2025/06/21 06:17:02 DEBUG : proton drive root link ID 'rclone-test-wavupex7femo': Purge remote --- FAIL: TestServerSideMove (19.17s) === RUN TestServerSideMoveWithFilter run.go:180: Remote "proton drive root link ID 'rclone-test-falaxer4lali'", Local "Local file system at /tmp/rclone3700911434", Modify Window "1s" 2025/06/21 06:17:05 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-votopex1zoki" 2025/06/21 06:17:05 DEBUG : proton drive root link ID 'rclone-test-votopex1zoki': Has cached credentials 2025/06/21 06:17:07 DEBUG : proton drive root link ID 'rclone-test-votopex1zoki': Used cached credential to initialize the ProtonDrive API sync_test.go:1706: Server side move (if possible) proton drive root link ID 'rclone-test-falaxer4lali' -> proton drive root link ID 'rclone-test-votopex1zoki' 2025/06/21 06:17:20 DEBUG : empty space: Excluded (Size Filter) 2025/06/21 06:17:20 DEBUG : empty space: Excluded 2025/06/21 06:17:21 DEBUG : empty space: Excluded (Size Filter) 2025/06/21 06:17:21 DEBUG : empty space: Excluded 2025/06/21 06:17:21 DEBUG : potato2: Need to transfer - File not found at Destination 2025/06/21 06:17:21 DEBUG : proton drive root link ID 'rclone-test-votopex1zoki': Waiting for checks to finish 2025/06/21 06:17:21 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/06/21 06:17:21 DEBUG : proton drive root link ID 'rclone-test-votopex1zoki': Waiting for transfers to finish 2025/06/21 06:17:21 INFO : potato3: Deleted 2025/06/21 06:17:22.013796 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/iUdiInzTX2o1M20u0ahGiTyFTJxouwBWURb2TzrbxI58N1DHeLqcTWCZfFpDvEKSLgbOPHMF26brFc52EUzNtg==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/06/21 06:17:22.013835 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/iUdiInzTX2o1M20u0ahGiTyFTJxouwBWURb2TzrbxI58N1DHeLqcTWCZfFpDvEKSLgbOPHMF26brFc52EUzNtg==/move: This value should not be blank. (Code=2000, Status=400) 2025/06/21 06:17:22 ERROR : potato2: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/iUdiInzTX2o1M20u0ahGiTyFTJxouwBWURb2TzrbxI58N1DHeLqcTWCZfFpDvEKSLgbOPHMF26brFc52EUzNtg==/move: This value should not be blank. (Code=2000, Status=400) 2025/06/21 06:17:22.291005 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/iTh1zQnQRY6ncQlymTssK7Bj1cDcqR4ih8oBLvblnSrBjRqzo_hN0EPufSseeuVprTHlK65gFDnVb88WREvbPQ==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/06/21 06:17:22.291040 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/iTh1zQnQRY6ncQlymTssK7Bj1cDcqR4ih8oBLvblnSrBjRqzo_hN0EPufSseeuVprTHlK65gFDnVb88WREvbPQ==/move: This value should not be blank. (Code=2000, Status=400) 2025/06/21 06:17:22 ERROR : potato3: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/iTh1zQnQRY6ncQlymTssK7Bj1cDcqR4ih8oBLvblnSrBjRqzo_hN0EPufSseeuVprTHlK65gFDnVb88WREvbPQ==/move: This value should not be blank. (Code=2000, Status=400) sync_test.go:1717: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1717 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1895 Error: Received unexpected error: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/iTh1zQnQRY6ncQlymTssK7Bj1cDcqR4ih8oBLvblnSrBjRqzo_hN0EPufSseeuVprTHlK65gFDnVb88WREvbPQ==/move: This value should not be blank. (Code=2000, Status=400) Test: TestServerSideMoveWithFilter 2025/06/21 06:17:22 DEBUG : proton drive root link ID 'rclone-test-votopex1zoki': Purge remote --- FAIL: TestServerSideMoveWithFilter (20.12s) === RUN TestServerSideMoveDeleteEmptySourceDirs run.go:180: Remote "proton drive root link ID 'rclone-test-falaxer4lali'", Local "Local file system at /tmp/rclone3700911434", Modify Window "1s" 2025/06/21 06:17:25 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-teniyan7poya" 2025/06/21 06:17:25 DEBUG : proton drive root link ID 'rclone-test-teniyan7poya': Has cached credentials 2025/06/21 06:17:27 DEBUG : proton drive root link ID 'rclone-test-teniyan7poya': Used cached credential to initialize the ProtonDrive API 2025/06/21 06:17:33 INFO : tomatoDir: Making directory sync_test.go:1706: Server side move (if possible) proton drive root link ID 'rclone-test-falaxer4lali' -> proton drive root link ID 'rclone-test-teniyan7poya' 2025/06/21 06:17:41 DEBUG : proton drive root link ID 'rclone-test-teniyan7poya': Using server-side directory move 2025/06/21 06:17:41 INFO : proton drive root link ID 'rclone-test-teniyan7poya': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2025/06/21 06:17:41 DEBUG : potato2: Need to transfer - File not found at Destination 2025/06/21 06:17:41 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1s) 2025/06/21 06:17:41 DEBUG : empty space: Unchanged skipping 2025/06/21 06:17:41 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/06/21 06:17:42 DEBUG : proton drive root link ID 'rclone-test-teniyan7poya': Waiting for checks to finish 2025/06/21 06:17:42 INFO : potato3: Deleted 2025/06/21 06:17:42 INFO : empty space: Deleted 2025/06/21 06:17:42 DEBUG : proton drive root link ID 'rclone-test-teniyan7poya': Waiting for transfers to finish 2025/06/21 06:17:42.876261 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/m4imwL0i59Rb_hpX5b5GQBG9-0kO7nThmNNNi9ZZZylN-OvH9UlqdzyjgT_PVO5ylrZ-gZxG2HOfe7vPLECanA==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/06/21 06:17:42.876317 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/m4imwL0i59Rb_hpX5b5GQBG9-0kO7nThmNNNi9ZZZylN-OvH9UlqdzyjgT_PVO5ylrZ-gZxG2HOfe7vPLECanA==/move: This value should not be blank. (Code=2000, Status=400) 2025/06/21 06:17:42 ERROR : potato2: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/m4imwL0i59Rb_hpX5b5GQBG9-0kO7nThmNNNi9ZZZylN-OvH9UlqdzyjgT_PVO5ylrZ-gZxG2HOfe7vPLECanA==/move: This value should not be blank. (Code=2000, Status=400) 2025/06/21 06:17:43.082583 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/oC2VG4M_AAp54x89JSUPCp-LFgWDnIMeMcJYk5_B-5wR-PLNXKK2OjpJYvnpeVwxNQtImWWp_aFC4dfY_0GNYA==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/06/21 06:17:43.082628 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/oC2VG4M_AAp54x89JSUPCp-LFgWDnIMeMcJYk5_B-5wR-PLNXKK2OjpJYvnpeVwxNQtImWWp_aFC4dfY_0GNYA==/move: This value should not be blank. (Code=2000, Status=400) 2025/06/21 06:17:43 ERROR : potato3: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/oC2VG4M_AAp54x89JSUPCp-LFgWDnIMeMcJYk5_B-5wR-PLNXKK2OjpJYvnpeVwxNQtImWWp_aFC4dfY_0GNYA==/move: This value should not be blank. (Code=2000, Status=400) 2025/06/21 06:17:43 ERROR : proton drive root link ID 'rclone-test-falaxer4lali': not deleting directories as there were IO errors sync_test.go:1717: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1717 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1902 Error: Received unexpected error: not deleting directories as there were IO errors Test: TestServerSideMoveDeleteEmptySourceDirs 2025/06/21 06:17:43 DEBUG : proton drive root link ID 'rclone-test-teniyan7poya': Purge remote --- FAIL: TestServerSideMoveDeleteEmptySourceDirs (21.00s) === RUN TestSyncBackupDir run.go:180: Remote "proton drive root link ID 'rclone-test-falaxer4lali'", Local "Local file system at /tmp/rclone3700911434", Modify Window "1s" 2025/06/21 06:17:54 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-falaxer4lali/dst" 2025/06/21 06:17:54 DEBUG : proton drive root link ID 'rclone-test-falaxer4lali/dst': Has cached credentials 2025/06/21 06:17:56 DEBUG : proton drive root link ID 'rclone-test-falaxer4lali/dst': Used cached credential to initialize the ProtonDrive API 2025/06/21 06:19:10 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-falaxer4lali/backup" 2025/06/21 06:19:10 DEBUG : proton drive root link ID 'rclone-test-falaxer4lali/backup': Has cached credentials 2025/06/21 06:19:12 DEBUG : proton drive root link ID 'rclone-test-falaxer4lali/backup': Used cached credential to initialize the ProtonDrive API 2025/06/21 06:21:53 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/06/21 06:21:53 DEBUG : proton drive root link ID 'rclone-test-falaxer4lali/dst': Waiting for checks to finish 2025/06/21 06:21:53 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/06/21 06:21:53 DEBUG : two: Unchanged skipping 2025/06/21 06:21:55.539791 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/WLMeHGJXYv5jMumrwptg-g2Zk-t2-NaSfxbtmMWsfYO8z6PbUmn3oIsNpcco8fB626b2z11nRa0Xi0-Mjic2Yg==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/06/21 06:21:55.539834 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/WLMeHGJXYv5jMumrwptg-g2Zk-t2-NaSfxbtmMWsfYO8z6PbUmn3oIsNpcco8fB626b2z11nRa0Xi0-Mjic2Yg==/move: This value should not be blank. (Code=2000, Status=400) 2025/06/21 06:21:55 ERROR : one: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/WLMeHGJXYv5jMumrwptg-g2Zk-t2-NaSfxbtmMWsfYO8z6PbUmn3oIsNpcco8fB626b2z11nRa0Xi0-Mjic2Yg==/move: This value should not be blank. (Code=2000, Status=400) 2025/06/21 06:21:55 DEBUG : proton drive root link ID 'rclone-test-falaxer4lali/dst': Waiting for transfers to finish 2025/06/21 06:21:55 ERROR : proton drive root link ID 'rclone-test-falaxer4lali/dst': not deleting files as there were IO errors 2025/06/21 06:21:55 ERROR : proton drive root link ID 'rclone-test-falaxer4lali/dst': not deleting directories as there were IO errors sync_test.go:2386: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2386 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2428 Error: Received unexpected error: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/WLMeHGJXYv5jMumrwptg-g2Zk-t2-NaSfxbtmMWsfYO8z6PbUmn3oIsNpcco8fB626b2z11nRa0Xi0-Mjic2Yg==/move: This value should not be blank. (Code=2000, Status=400) Test: TestSyncBackupDir --- FAIL: TestSyncBackupDir (253.09s) === RUN TestSyncBackupDirWithSuffix run.go:180: Remote "proton drive root link ID 'rclone-test-falaxer4lali'", Local "Local file system at /tmp/rclone3700911434", Modify Window "1s" 2025/06/21 06:22:08 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-falaxer4lali/dst" 2025/06/21 06:22:08 DEBUG : Config file has changed externally - reloading 2025/06/21 06:22:08 DEBUG : proton drive root link ID 'rclone-test-falaxer4lali/dst': Has cached credentials 2025/06/21 06:22:09 DEBUG : proton drive root link ID 'rclone-test-falaxer4lali/dst': Used cached credential to initialize the ProtonDrive API 2025/06/21 06:23:32 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-falaxer4lali/backup" 2025/06/21 06:23:32 DEBUG : proton drive root link ID 'rclone-test-falaxer4lali/backup': Has cached credentials 2025/06/21 06:23:33 DEBUG : proton drive root link ID 'rclone-test-falaxer4lali/backup': Used cached credential to initialize the ProtonDrive API 2025/06/21 06:26:19 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/06/21 06:26:19 DEBUG : proton drive root link ID 'rclone-test-falaxer4lali/dst': Waiting for checks to finish 2025/06/21 06:26:19 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/06/21 06:26:19 DEBUG : two: Unchanged skipping 2025/06/21 06:26:21.246731 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/FwEKKB2fv5ROBQ9FmToHxCcBOCcDZp3ednHO91-M855xozDdSEtcFM8vqYYUgWBm76oWEU2uHQQ0ymni4gT38w==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/06/21 06:26:21.246810 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/FwEKKB2fv5ROBQ9FmToHxCcBOCcDZp3ednHO91-M855xozDdSEtcFM8vqYYUgWBm76oWEU2uHQQ0ymni4gT38w==/move: This value should not be blank. (Code=2000, Status=400) 2025/06/21 06:26:21 ERROR : one: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/FwEKKB2fv5ROBQ9FmToHxCcBOCcDZp3ednHO91-M855xozDdSEtcFM8vqYYUgWBm76oWEU2uHQQ0ymni4gT38w==/move: This value should not be blank. (Code=2000, Status=400) 2025/06/21 06:26:21 DEBUG : proton drive root link ID 'rclone-test-falaxer4lali/dst': Waiting for transfers to finish 2025/06/21 06:26:21 ERROR : proton drive root link ID 'rclone-test-falaxer4lali/dst': not deleting files as there were IO errors 2025/06/21 06:26:21 ERROR : proton drive root link ID 'rclone-test-falaxer4lali/dst': not deleting directories as there were IO errors sync_test.go:2386: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2386 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2432 Error: Received unexpected error: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/FwEKKB2fv5ROBQ9FmToHxCcBOCcDZp3ednHO91-M855xozDdSEtcFM8vqYYUgWBm76oWEU2uHQQ0ymni4gT38w==/move: This value should not be blank. (Code=2000, Status=400) Test: TestSyncBackupDirWithSuffix --- FAIL: TestSyncBackupDirWithSuffix (265.68s) === RUN TestSyncBackupDirWithSuffixKeepExtension run.go:180: Remote "proton drive root link ID 'rclone-test-falaxer4lali'", Local "Local file system at /tmp/rclone3700911434", Modify Window "1s" 2025/06/21 06:26:33 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-falaxer4lali/dst" 2025/06/21 06:26:33 DEBUG : proton drive root link ID 'rclone-test-falaxer4lali/dst': Has cached credentials 2025/06/21 06:26:34 DEBUG : proton drive root link ID 'rclone-test-falaxer4lali/dst': Used cached credential to initialize the ProtonDrive API 2025/06/21 06:27:56 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-falaxer4lali/backup" 2025/06/21 06:27:56 DEBUG : proton drive root link ID 'rclone-test-falaxer4lali/backup': Has cached credentials 2025/06/21 06:27:58 DEBUG : proton drive root link ID 'rclone-test-falaxer4lali/backup': Used cached credential to initialize the ProtonDrive API 2025/06/21 06:30:27 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/06/21 06:30:27 DEBUG : proton drive root link ID 'rclone-test-falaxer4lali/dst': Waiting for checks to finish 2025/06/21 06:30:27 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/06/21 06:30:27 DEBUG : two: Unchanged skipping 2025/06/21 06:30:30.089977 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/zi2XKidhvPeoU_cO6NAXvs2LtGnqsKD4BlJCiaUACTnGJTG-tb7mo4fQDr-PxWvvDVBD85xhdqE4w3LqtpwHXA==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/06/21 06:30:30.090013 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/zi2XKidhvPeoU_cO6NAXvs2LtGnqsKD4BlJCiaUACTnGJTG-tb7mo4fQDr-PxWvvDVBD85xhdqE4w3LqtpwHXA==/move: This value should not be blank. (Code=2000, Status=400) 2025/06/21 06:30:30 ERROR : one: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/zi2XKidhvPeoU_cO6NAXvs2LtGnqsKD4BlJCiaUACTnGJTG-tb7mo4fQDr-PxWvvDVBD85xhdqE4w3LqtpwHXA==/move: This value should not be blank. (Code=2000, Status=400) 2025/06/21 06:30:30 DEBUG : proton drive root link ID 'rclone-test-falaxer4lali/dst': Waiting for transfers to finish 2025/06/21 06:30:30 ERROR : proton drive root link ID 'rclone-test-falaxer4lali/dst': not deleting files as there were IO errors 2025/06/21 06:30:30 ERROR : proton drive root link ID 'rclone-test-falaxer4lali/dst': not deleting directories as there were IO errors sync_test.go:2386: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2386 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2436 Error: Received unexpected error: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/zi2XKidhvPeoU_cO6NAXvs2LtGnqsKD4BlJCiaUACTnGJTG-tb7mo4fQDr-PxWvvDVBD85xhdqE4w3LqtpwHXA==/move: This value should not be blank. (Code=2000, Status=400) Test: TestSyncBackupDirWithSuffixKeepExtension --- FAIL: TestSyncBackupDirWithSuffixKeepExtension (248.90s) === RUN TestSyncBackupDirSuffixOnly run.go:180: Remote "proton drive root link ID 'rclone-test-falaxer4lali'", Local "Local file system at /tmp/rclone3700911434", Modify Window "1s" 2025/06/21 06:30:42 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-falaxer4lali/dst" 2025/06/21 06:30:42 DEBUG : proton drive root link ID 'rclone-test-falaxer4lali/dst': Has cached credentials 2025/06/21 06:30:44 DEBUG : proton drive root link ID 'rclone-test-falaxer4lali/dst': Used cached credential to initialize the ProtonDrive API 2025/06/21 06:31:58 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/06/21 06:31:58 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/06/21 06:31:58 DEBUG : two: Unchanged skipping 2025/06/21 06:31:58 DEBUG : proton drive root link ID 'rclone-test-falaxer4lali/dst': Waiting for checks to finish 2025/06/21 06:31:59.330773 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/hV3OPew334cTR2WYBmtjQaX1tU3jyAHc_ce4p_HI0kg6BWF7c5sHbhZ8z89BRHWwHoD7PkYdeUtVAeFrAjV4hw==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/06/21 06:31:59.330815 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/hV3OPew334cTR2WYBmtjQaX1tU3jyAHc_ce4p_HI0kg6BWF7c5sHbhZ8z89BRHWwHoD7PkYdeUtVAeFrAjV4hw==/move: This value should not be blank. (Code=2000, Status=400) 2025/06/21 06:31:59 ERROR : one: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/hV3OPew334cTR2WYBmtjQaX1tU3jyAHc_ce4p_HI0kg6BWF7c5sHbhZ8z89BRHWwHoD7PkYdeUtVAeFrAjV4hw==/move: This value should not be blank. (Code=2000, Status=400) 2025/06/21 06:31:59 DEBUG : proton drive root link ID 'rclone-test-falaxer4lali/dst': Waiting for transfers to finish 2025/06/21 06:31:59 ERROR : proton drive root link ID 'rclone-test-falaxer4lali/dst': not deleting files as there were IO errors 2025/06/21 06:31:59 ERROR : proton drive root link ID 'rclone-test-falaxer4lali/dst': not deleting directories as there were IO errors sync_test.go:2386: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2386 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2440 Error: Received unexpected error: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/hV3OPew334cTR2WYBmtjQaX1tU3jyAHc_ce4p_HI0kg6BWF7c5sHbhZ8z89BRHWwHoD7PkYdeUtVAeFrAjV4hw==/move: This value should not be blank. (Code=2000, Status=400) Test: TestSyncBackupDirSuffixOnly --- FAIL: TestSyncBackupDirSuffixOnly (88.62s) === RUN TestSyncSuffix run.go:180: Remote "proton drive root link ID 'rclone-test-falaxer4lali'", Local "Local file system at /tmp/rclone3700911434", Modify Window "1s" 2025/06/21 06:32:11 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-falaxer4lali/dst" 2025/06/21 06:32:11 DEBUG : proton drive root link ID 'rclone-test-falaxer4lali/dst': Has cached credentials 2025/06/21 06:32:12 DEBUG : proton drive root link ID 'rclone-test-falaxer4lali/dst': Used cached credential to initialize the ProtonDrive API 2025/06/21 06:33:27 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/06/21 06:33:28.646287 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/o0JQ2Q9lFXuIEswKGXXa53kOIzdQLyulbu2MhSzmzgQU4R58ezYUNdJjCossgDO0oHL35P9_Pg2okokoHbesfg==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/06/21 06:33:28.646325 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/o0JQ2Q9lFXuIEswKGXXa53kOIzdQLyulbu2MhSzmzgQU4R58ezYUNdJjCossgDO0oHL35P9_Pg2okokoHbesfg==/move: This value should not be blank. (Code=2000, Status=400) 2025/06/21 06:33:28 ERROR : one: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/o0JQ2Q9lFXuIEswKGXXa53kOIzdQLyulbu2MhSzmzgQU4R58ezYUNdJjCossgDO0oHL35P9_Pg2okokoHbesfg==/move: This value should not be blank. (Code=2000, Status=400) sync_test.go:2474: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2474 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2524 Error: Received unexpected error: moving to --backup-dir failed: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/o0JQ2Q9lFXuIEswKGXXa53kOIzdQLyulbu2MhSzmzgQU4R58ezYUNdJjCossgDO0oHL35P9_Pg2okokoHbesfg==/move: This value should not be blank. (Code=2000, Status=400) Test: TestSyncSuffix --- FAIL: TestSyncSuffix (89.38s) === RUN TestSyncSuffixKeepExtension run.go:180: Remote "proton drive root link ID 'rclone-test-falaxer4lali'", Local "Local file system at /tmp/rclone3700911434", Modify Window "1s" 2025/06/21 06:33:40 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-falaxer4lali/dst" 2025/06/21 06:33:40 DEBUG : proton drive root link ID 'rclone-test-falaxer4lali/dst': Has cached credentials 2025/06/21 06:33:42 DEBUG : proton drive root link ID 'rclone-test-falaxer4lali/dst': Used cached credential to initialize the ProtonDrive API 2025/06/21 06:34:57 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/06/21 06:34:58.152931 WARN RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/bXNH8eUk5pKaD30QBFgxOXTuAu94OjdPsMtNqMwc6nJ6hYsfonKvHgBRSvqZbAemJBe2PDQJR4NgzSk3GGBxVw==/move: This value should not be blank. (Code=2000, Status=400), Attempt 1 2025/06/21 06:34:58.152966 ERROR RESTY 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/bXNH8eUk5pKaD30QBFgxOXTuAu94OjdPsMtNqMwc6nJ6hYsfonKvHgBRSvqZbAemJBe2PDQJR4NgzSk3GGBxVw==/move: This value should not be blank. (Code=2000, Status=400) 2025/06/21 06:34:58 ERROR : one: Couldn't move: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/bXNH8eUk5pKaD30QBFgxOXTuAu94OjdPsMtNqMwc6nJ6hYsfonKvHgBRSvqZbAemJBe2PDQJR4NgzSk3GGBxVw==/move: This value should not be blank. (Code=2000, Status=400) sync_test.go:2474: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2474 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2525 Error: Received unexpected error: moving to --backup-dir failed: 400 PUT https://mail.proton.me/api/drive/shares/gYxulUP2_XOrbAxegpHKiBy09cfvss6mjykkd2Z3ZDpMFCmoWX4bC2TTm-Gl2UwrjReC8te_lFsQyeXT74Vgfw==/links/bXNH8eUk5pKaD30QBFgxOXTuAu94OjdPsMtNqMwc6nJ6hYsfonKvHgBRSvqZbAemJBe2PDQJR4NgzSk3GGBxVw==/move: This value should not be blank. (Code=2000, Status=400) Test: TestSyncSuffixKeepExtension --- FAIL: TestSyncSuffixKeepExtension (89.44s) FAIL 2025/06/21 06:35:01 DEBUG : proton drive root link ID 'rclone-test-falaxer4lali': Purge remote "./sync.test -test.v -test.timeout 1h0m0s -remote TestProtonDrive: -verbose -test.run '^(TestMoveWithDeleteEmptySrcDirs|TestServerSideMove|TestServerSideMoveDeleteEmptySourceDirs|TestServerSideMoveOverSelf|TestServerSideMoveWithFilter|TestSyncBackupDir|TestSyncBackupDirSuffixOnly|TestSyncBackupDirWithSuffix|TestSyncBackupDirWithSuffixKeepExtension|TestSyncSuffix|TestSyncSuffixKeepExtension|TestSyncWithTrackRenames|TestSyncWithTrackRenamesStrategyLeaf|TestSyncWithTrackRenamesStrategyModtime)$'" - Finished ERROR in 19m21.90946714s (try 5/5): exit status 1: Failed [TestServerSideMoveOverSelf TestSyncWithTrackRenames TestSyncWithTrackRenamesStrategyModtime TestSyncWithTrackRenamesStrategyLeaf TestMoveWithDeleteEmptySrcDirs TestServerSideMove TestServerSideMoveWithFilter TestServerSideMoveDeleteEmptySourceDirs TestSyncBackupDir TestSyncBackupDirWithSuffix TestSyncBackupDirWithSuffixKeepExtension TestSyncBackupDirSuffixOnly TestSyncSuffix TestSyncSuffixKeepExtension]