"./sync.test -test.v -test.timeout 1h0m0s -remote TestProtonDrive: -verbose -test.run '^(TestFixCase|TestNothingToTransferWithEmptyDirs|TestNothingToTransferWithoutEmptyDirs|TestSyncConcurrentDelete|TestSyncConcurrentTruncate|TestSyncIgnoreCase|TestSyncImmutable|TestSyncSuffix|TestSyncSuffixKeepExtension|TestSyncUTFNorm)$|^TestMaxTransfer$/^(Cautious|Hard|Soft)$'" - Starting (try 2/5) 2024/12/09 02:48:58 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-zokunir9jenu" 2024/12/09 02:48:58 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2024/12/09 02:48:58 DEBUG : proton drive root link ID 'rclone-test-zokunir9jenu': Using username and password to log in 2024/12/09 02:49:00 DEBUG : proton drive root link ID 'rclone-test-zokunir9jenu': Used username and password to initialize the ProtonDrive API 2024/12/09 02:49:00 DEBUG : Saving config "client_uid" in section "TestProtonDrive" of the config file 2024/12/09 02:49:00 DEBUG : Saving config "client_access_token" in section "TestProtonDrive" of the config file 2024/12/09 02:49:00 DEBUG : Saving config "client_refresh_token" in section "TestProtonDrive" of the config file 2024/12/09 02:49:00 DEBUG : Saving config "client_salted_key_pass" in section "TestProtonDrive" of the config file 2024/12/09 02:49:01 DEBUG : Creating backend with remote "/tmp/rclone2405541440" === RUN TestSyncSuffix run.go:180: Remote "proton drive root link ID 'rclone-test-zokunir9jenu'", Local "Local file system at /tmp/rclone2405541440", Modify Window "1s" 2024/12/09 02:49:05 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-zokunir9jenu/dst" 2024/12/09 02:49:05 DEBUG : proton drive root link ID 'rclone-test-zokunir9jenu/dst': Has cached credentials 2024/12/09 02:49:06 DEBUG : proton drive root link ID 'rclone-test-zokunir9jenu/dst': Used cached credential to initialize the ProtonDrive API 2024/12/09 02:49:34 DEBUG : one: Sizes differ (src 4 vs dst 3) 2024/12/09 02:49:40 INFO : one: Moved (server-side) to: one.bak 2024/12/09 02:49:41 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2024/12/09 02:49:41 INFO : one: Copied (new) 2024/12/09 02:49:41 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/12/09 02:49:41 DEBUG : two: Unchanged skipping 2024/12/09 02:49:42 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2024/12/09 02:49:48 INFO : three.txt: Moved (server-side) to: three.txt.bak 2024/12/09 02:49:48 DEBUG : three.txt: sha1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2024/12/09 02:49:48 INFO : three.txt: Copied (new) 2024/12/09 02:49:51 DEBUG : one: Sizes differ (src 5 vs dst 4) 2024/12/09 02:49:51 INFO : one.bak: Deleted 2024/12/09 02:49:57 INFO : one: Moved (server-side) to: one.bak 2024/12/09 02:49:58 DEBUG : one: sha1 = 88a8c2880f555638f990d1ec4c8359b25caa20c7 OK 2024/12/09 02:49:58 INFO : one: Copied (new) 2024/12/09 02:49:58 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/12/09 02:49:58 DEBUG : two: Unchanged skipping 2024/12/09 02:49:59 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2024/12/09 02:49:59 INFO : three.txt.bak: Deleted 2024/12/09 02:50:05 INFO : three.txt: Moved (server-side) to: three.txt.bak 2024/12/09 02:50:06 DEBUG : three.txt: sha1 = 476e1707cbd7f0497a7dc4d44f0b07b8566ffc30 OK 2024/12/09 02:50:06 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffix (68.43s) === RUN TestSyncSuffixKeepExtension run.go:180: Remote "proton drive root link ID 'rclone-test-zokunir9jenu'", Local "Local file system at /tmp/rclone2405541440", Modify Window "1s" 2024/12/09 02:50:13 DEBUG : Creating backend with remote "TestProtonDrive:rclone-test-zokunir9jenu/dst" 2024/12/09 02:50:13 DEBUG : Config file has changed externally - reloading 2024/12/09 02:50:13 DEBUG : proton drive root link ID 'rclone-test-zokunir9jenu/dst': Has cached credentials 2024/12/09 02:50:13 DEBUG : proton drive root link ID 'rclone-test-zokunir9jenu/dst': Used cached credential to initialize the ProtonDrive API 2024/12/09 02:50:41 DEBUG : one: Sizes differ (src 4 vs dst 3) 2024/12/09 02:50:47 INFO : one: Moved (server-side) to: one-2019-01-01 2024/12/09 02:50:47 DEBUG : one: sha1 = d63a9910ed57f038b76549c070df6196b2102f42 OK 2024/12/09 02:50:47 INFO : one: Copied (new) 2024/12/09 02:50:48 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/12/09 02:50:48 DEBUG : two: Unchanged skipping 2024/12/09 02:50:48 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2024/12/09 02:50:54 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2024/12/09 02:50:55 DEBUG : three.txt: sha1 = 92e0258fe3c7456f15dff78d509f6e3c9166c254 OK 2024/12/09 02:50:55 INFO : three.txt: Copied (new) 2024/12/09 02:50:57 DEBUG : one: Sizes differ (src 5 vs dst 4) 2024/12/09 02:50:58 INFO : one-2019-01-01: Deleted 2024/12/09 02:51:04 INFO : one: Moved (server-side) to: one-2019-01-01 2024/12/09 02:51:04 DEBUG : one: sha1 = 88a8c2880f555638f990d1ec4c8359b25caa20c7 OK 2024/12/09 02:51:04 INFO : one: Copied (new) 2024/12/09 02:51:05 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/12/09 02:51:05 DEBUG : two: Unchanged skipping 2024/12/09 02:51:05 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2024/12/09 02:51:06 INFO : three-2019-01-01.txt: Deleted 2024/12/09 02:51:12 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2024/12/09 02:51:12 DEBUG : three.txt: sha1 = 476e1707cbd7f0497a7dc4d44f0b07b8566ffc30 OK 2024/12/09 02:51:12 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffixKeepExtension (66.76s) === RUN TestSyncUTFNorm run.go:180: Remote "proton drive root link ID 'rclone-test-zokunir9jenu'", Local "Local file system at /tmp/rclone2405541440", Modify Window "1s" 2024/12/09 02:51:17 DEBUG : Testêé: Sizes differ (src 14 vs dst 18) 2024/12/09 02:51:17 DEBUG : proton drive root link ID 'rclone-test-zokunir9jenu': Waiting for checks to finish 2024/12/09 02:51:17 DEBUG : proton drive root link ID 'rclone-test-zokunir9jenu': Waiting for transfers to finish 2024/12/09 02:51:18.026579 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 2024/12/09 02:51:18.026603 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) 2024/12/09 02:51:19 DEBUG : Testêé: sha1 = a54d88e06612d820bc3be72877c74f257b561b19 OK 2024/12/09 02:51:19 INFO : Testêé: Copied (replaced existing) to: Testêé 2024/12/09 02:51:19 DEBUG : Waiting for deletions to finish --- PASS: TestSyncUTFNorm (3.80s) === RUN TestSyncImmutable run.go:180: Remote "proton drive root link ID 'rclone-test-zokunir9jenu'", Local "Local file system at /tmp/rclone2405541440", Modify Window "1s" 2024/12/09 02:51:20 DEBUG : existing: Need to transfer - File not found at Destination 2024/12/09 02:51:20 DEBUG : proton drive root link ID 'rclone-test-zokunir9jenu': Waiting for checks to finish 2024/12/09 02:51:20 DEBUG : proton drive root link ID 'rclone-test-zokunir9jenu': Waiting for transfers to finish 2024/12/09 02:51:21 DEBUG : existing: sha1 = 3e2e95f5ad970eadfa7e17eaf73da97024aa5359 OK 2024/12/09 02:51:21 INFO : existing: Copied (new) 2024/12/09 02:51:21 DEBUG : Waiting for deletions to finish run.go:180: Remote "proton drive root link ID 'rclone-test-zokunir9jenu'", Local "Local file system at /tmp/rclone2405541440", Modify Window "1s" 2024/12/09 02:51:22 DEBUG : existing: Sizes differ (src 8 vs dst 6) 2024/12/09 02:51:22 ERROR : existing: Source and destination exist but do not match: immutable file modified 2024/12/09 02:51:22 DEBUG : proton drive root link ID 'rclone-test-zokunir9jenu': Waiting for checks to finish 2024/12/09 02:51:22 DEBUG : proton drive root link ID 'rclone-test-zokunir9jenu': Waiting for transfers to finish 2024/12/09 02:51:22 ERROR : proton drive root link ID 'rclone-test-zokunir9jenu': not deleting files as there were IO errors 2024/12/09 02:51:22 ERROR : proton drive root link ID 'rclone-test-zokunir9jenu': not deleting directories as there were IO errors run.go:180: Remote "proton drive root link ID 'rclone-test-zokunir9jenu'", Local "Local file system at /tmp/rclone2405541440", Modify Window "1s" --- PASS: TestSyncImmutable (4.20s) === RUN TestSyncIgnoreCase run.go:180: Remote "proton drive root link ID 'rclone-test-zokunir9jenu'", Local "Local file system at /tmp/rclone2405541440", Modify Window "1s" 2024/12/09 02:51:25 DEBUG : existing: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/12/09 02:51:25 DEBUG : existing: Unchanged skipping 2024/12/09 02:51:25 DEBUG : proton drive root link ID 'rclone-test-zokunir9jenu': Waiting for checks to finish 2024/12/09 02:51:25 DEBUG : proton drive root link ID 'rclone-test-zokunir9jenu': Waiting for transfers to finish 2024/12/09 02:51:25 DEBUG : Waiting for deletions to finish 2024/12/09 02:51:25 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreCase (2.35s) === RUN TestFixCase run.go:180: Remote "proton drive root link ID 'rclone-test-zokunir9jenu'", Local "Local file system at /tmp/rclone2405541440", Modify Window "1s" sync_test.go:2635: Skipping test as local or remote are case-sensitive --- SKIP: TestFixCase (0.40s) === RUN TestMaxTransfer === RUN TestMaxTransfer/Hard run.go:180: Remote "proton drive root link ID 'rclone-test-zokunir9jenu'", Local "Local file system at /tmp/rclone2405541440", Modify Window "1s" sync_test.go:2674: This test only runs on local === RUN TestMaxTransfer/Soft run.go:180: Remote "proton drive root link ID 'rclone-test-zokunir9jenu'", Local "Local file system at /tmp/rclone2405541440", Modify Window "1s" sync_test.go:2674: This test only runs on local === RUN TestMaxTransfer/Cautious run.go:180: Remote "proton drive root link ID 'rclone-test-zokunir9jenu'", Local "Local file system at /tmp/rclone2405541440", Modify Window "1s" sync_test.go:2674: This test only runs on local --- PASS: TestMaxTransfer (1.23s) --- SKIP: TestMaxTransfer/Hard (0.38s) --- SKIP: TestMaxTransfer/Soft (0.43s) --- SKIP: TestMaxTransfer/Cautious (0.42s) === RUN TestSyncConcurrentDelete run.go:180: Remote "proton drive root link ID 'rclone-test-zokunir9jenu'", Local "Local file system at /tmp/rclone2405541440", Modify Window "1s" 2024/12/09 02:52:08 DEBUG : both0: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/12/09 02:52:08 DEBUG : both10: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/12/09 02:52:08 DEBUG : both1: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/12/09 02:52:08 DEBUG : proton drive root link ID 'rclone-test-zokunir9jenu': Waiting for checks to finish 2024/12/09 02:52:08 DEBUG : both11: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/12/09 02:52:08 DEBUG : both0: Unchanged skipping 2024/12/09 02:52:08 DEBUG : both12: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/12/09 02:52:08 DEBUG : both12: Unchanged skipping 2024/12/09 02:52:08 DEBUG : both13: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/12/09 02:52:08 DEBUG : both13: Unchanged skipping 2024/12/09 02:52:08 DEBUG : both14: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/12/09 02:52:08 DEBUG : both14: Unchanged skipping 2024/12/09 02:52:08 DEBUG : both15: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/12/09 02:52:08 DEBUG : both15: Unchanged skipping 2024/12/09 02:52:08 DEBUG : both16: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/12/09 02:52:08 DEBUG : both16: Unchanged skipping 2024/12/09 02:52:08 DEBUG : both17: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/12/09 02:52:08 DEBUG : both17: Unchanged skipping 2024/12/09 02:52:08 DEBUG : both18: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/12/09 02:52:08 DEBUG : both18: Unchanged skipping 2024/12/09 02:52:08 DEBUG : both19: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/12/09 02:52:08 DEBUG : both19: Unchanged skipping 2024/12/09 02:52:08 DEBUG : both2: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/12/09 02:52:08 DEBUG : both2: Unchanged skipping 2024/12/09 02:52:08 DEBUG : both3: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/12/09 02:52:08 DEBUG : both3: Unchanged skipping 2024/12/09 02:52:08 DEBUG : both4: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/12/09 02:52:08 DEBUG : both4: Unchanged skipping 2024/12/09 02:52:08 DEBUG : both5: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/12/09 02:52:08 DEBUG : both5: Unchanged skipping 2024/12/09 02:52:08 DEBUG : both6: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/12/09 02:52:08 DEBUG : both6: Unchanged skipping 2024/12/09 02:52:08 DEBUG : both7: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/12/09 02:52:08 DEBUG : both7: Unchanged skipping 2024/12/09 02:52:08 DEBUG : both8: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/12/09 02:52:08 DEBUG : both8: Unchanged skipping 2024/12/09 02:52:08 DEBUG : both9: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/12/09 02:52:08 DEBUG : both9: Unchanged skipping 2024/12/09 02:52:08 DEBUG : both1: Unchanged skipping 2024/12/09 02:52:08 DEBUG : both10: Unchanged skipping 2024/12/09 02:52:08 DEBUG : both11: Unchanged skipping 2024/12/09 02:52:08 DEBUG : proton drive root link ID 'rclone-test-zokunir9jenu': Waiting for transfers to finish 2024/12/09 02:52:08 DEBUG : Waiting for deletions to finish 2024/12/09 02:52:08 INFO : only0: Deleted 2024/12/09 02:52:08 INFO : only10: Deleted 2024/12/09 02:52:08 INFO : only17: Deleted 2024/12/09 02:52:09 INFO : only18: Deleted 2024/12/09 02:52:09 INFO : only13: Deleted 2024/12/09 02:52:09 INFO : only19: Deleted 2024/12/09 02:52:09 INFO : only4: Deleted 2024/12/09 02:52:09 INFO : only12: Deleted 2024/12/09 02:52:09 INFO : only5: Deleted 2024/12/09 02:52:09 INFO : only6: Deleted 2024/12/09 02:52:09 INFO : only8: Deleted 2024/12/09 02:52:09 INFO : only15: Deleted 2024/12/09 02:52:09 INFO : only9: Deleted 2024/12/09 02:52:09 INFO : only16: Deleted 2024/12/09 02:52:09 INFO : only1: Deleted 2024/12/09 02:52:09 INFO : only11: Deleted 2024/12/09 02:52:09 INFO : only14: Deleted 2024/12/09 02:52:09 INFO : only2: Deleted 2024/12/09 02:52:09 INFO : only3: Deleted 2024/12/09 02:52:09 INFO : only7: Deleted 2024/12/09 02:52:09 INFO : There was nothing to transfer run.go:180: Remote "proton drive root link ID 'rclone-test-zokunir9jenu'", Local "Local file system at /tmp/rclone2405541440", Modify Window "1s" --- PASS: TestSyncConcurrentDelete (50.96s) === RUN TestSyncConcurrentTruncate run.go:180: Remote "proton drive root link ID 'rclone-test-zokunir9jenu'", Local "Local file system at /tmp/rclone2405541440", Modify Window "1s" 2024/12/09 02:53:00 DEBUG : both1: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/12/09 02:53:00 DEBUG : both10: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/12/09 02:53:00 DEBUG : both11: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/12/09 02:53:00 DEBUG : proton drive root link ID 'rclone-test-zokunir9jenu': Waiting for checks to finish 2024/12/09 02:53:00 DEBUG : both0: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/12/09 02:53:00 DEBUG : both1: Unchanged skipping 2024/12/09 02:53:00 DEBUG : both12: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/12/09 02:53:00 DEBUG : both12: Unchanged skipping 2024/12/09 02:53:00 DEBUG : both13: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/12/09 02:53:00 DEBUG : both13: Unchanged skipping 2024/12/09 02:53:00 DEBUG : both14: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/12/09 02:53:00 DEBUG : both14: Unchanged skipping 2024/12/09 02:53:00 DEBUG : both15: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/12/09 02:53:00 DEBUG : both15: Unchanged skipping 2024/12/09 02:53:00 DEBUG : both16: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/12/09 02:53:00 DEBUG : both16: Unchanged skipping 2024/12/09 02:53:00 DEBUG : both17: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/12/09 02:53:00 DEBUG : both17: Unchanged skipping 2024/12/09 02:53:00 DEBUG : both18: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/12/09 02:53:00 DEBUG : both18: Unchanged skipping 2024/12/09 02:53:00 DEBUG : both19: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/12/09 02:53:00 DEBUG : both19: Unchanged skipping 2024/12/09 02:53:00 DEBUG : both2: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/12/09 02:53:00 DEBUG : both2: Unchanged skipping 2024/12/09 02:53:00 DEBUG : both3: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/12/09 02:53:00 DEBUG : both3: Unchanged skipping 2024/12/09 02:53:00 DEBUG : both4: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/12/09 02:53:00 DEBUG : both4: Unchanged skipping 2024/12/09 02:53:00 DEBUG : both5: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/12/09 02:53:00 DEBUG : both5: Unchanged skipping 2024/12/09 02:53:00 DEBUG : both6: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/12/09 02:53:00 DEBUG : both6: Unchanged skipping 2024/12/09 02:53:00 DEBUG : both7: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/12/09 02:53:00 DEBUG : both7: Unchanged skipping 2024/12/09 02:53:00 DEBUG : both8: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/12/09 02:53:00 DEBUG : both8: Unchanged skipping 2024/12/09 02:53:00 DEBUG : both9: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/12/09 02:53:00 DEBUG : both11: Unchanged skipping 2024/12/09 02:53:00 DEBUG : both10: Unchanged skipping 2024/12/09 02:53:00 DEBUG : only0: Sizes differ (src 0 vs dst 6) 2024/12/09 02:53:00 DEBUG : only1: Sizes differ (src 0 vs dst 6) 2024/12/09 02:53:00 DEBUG : both0: Unchanged skipping 2024/12/09 02:53:00 DEBUG : only10: Sizes differ (src 0 vs dst 6) 2024/12/09 02:53:00 DEBUG : both9: Unchanged skipping 2024/12/09 02:53:00 DEBUG : only11: Sizes differ (src 0 vs dst 6) 2024/12/09 02:53:00 DEBUG : only12: Sizes differ (src 0 vs dst 6) 2024/12/09 02:53:00 DEBUG : only13: Sizes differ (src 0 vs dst 6) 2024/12/09 02:53:00 DEBUG : only14: Sizes differ (src 0 vs dst 6) 2024/12/09 02:53:00 DEBUG : only15: Sizes differ (src 0 vs dst 6) 2024/12/09 02:53:00 DEBUG : only16: Sizes differ (src 0 vs dst 6) 2024/12/09 02:53:00 DEBUG : only17: Sizes differ (src 0 vs dst 6) 2024/12/09 02:53:00 DEBUG : only18: Sizes differ (src 0 vs dst 6) 2024/12/09 02:53:00 DEBUG : only19: Sizes differ (src 0 vs dst 6) 2024/12/09 02:53:00 DEBUG : only2: Sizes differ (src 0 vs dst 6) 2024/12/09 02:53:00 DEBUG : only3: Sizes differ (src 0 vs dst 6) 2024/12/09 02:53:00 DEBUG : only4: Sizes differ (src 0 vs dst 6) 2024/12/09 02:53:00 DEBUG : only5: Sizes differ (src 0 vs dst 6) 2024/12/09 02:53:00 DEBUG : only6: Sizes differ (src 0 vs dst 6) 2024/12/09 02:53:00 DEBUG : only7: Sizes differ (src 0 vs dst 6) 2024/12/09 02:53:00 DEBUG : only8: Sizes differ (src 0 vs dst 6) 2024/12/09 02:53:00 DEBUG : only9: Sizes differ (src 0 vs dst 6) 2024/12/09 02:53:00 DEBUG : proton drive root link ID 'rclone-test-zokunir9jenu': Waiting for transfers to finish 2024/12/09 02:53:00.385440 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 2024/12/09 02:53:00.385464 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) 2024/12/09 02:53:00.418355 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 2024/12/09 02:53:00.418391 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) 2024/12/09 02:53:00.451662 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 2024/12/09 02:53:00.451685 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) 2024/12/09 02:53:00.458563 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 2024/12/09 02:53:00.458578 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) 2024/12/09 02:53:01 DEBUG : only14: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2024/12/09 02:53:01 INFO : only14: Copied (replaced existing) 2024/12/09 02:53:01 DEBUG : only10: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2024/12/09 02:53:01 INFO : only10: Copied (replaced existing) 2024/12/09 02:53:01 DEBUG : only13: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2024/12/09 02:53:01 INFO : only13: Copied (replaced existing) 2024/12/09 02:53:01.338917 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 2024/12/09 02:53:01.338958 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) 2024/12/09 02:53:01.355451 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 2024/12/09 02:53:01.355468 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) 2024/12/09 02:53:01 DEBUG : only12: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2024/12/09 02:53:01 INFO : only12: Copied (replaced existing) 2024/12/09 02:53:01.466712 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 2024/12/09 02:53:01.466733 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) 2024/12/09 02:53:01.567720 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 2024/12/09 02:53:01.567744 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) 2024/12/09 02:53:02 DEBUG : only16: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2024/12/09 02:53:02 INFO : only16: Copied (replaced existing) 2024/12/09 02:53:02 DEBUG : only15: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2024/12/09 02:53:02 INFO : only15: Copied (replaced existing) 2024/12/09 02:53:02 DEBUG : only17: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2024/12/09 02:53:02 INFO : only17: Copied (replaced existing) 2024/12/09 02:53:02.285247 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 2024/12/09 02:53:02.285271 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) 2024/12/09 02:53:02.299137 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 2024/12/09 02:53:02.299155 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) 2024/12/09 02:53:02.425330 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 2024/12/09 02:53:02.425355 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) 2024/12/09 02:53:02 DEBUG : only18: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2024/12/09 02:53:02 INFO : only18: Copied (replaced existing) 2024/12/09 02:53:02.645767 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 2024/12/09 02:53:02.645804 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) 2024/12/09 02:53:03 DEBUG : only2: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2024/12/09 02:53:03 INFO : only2: Copied (replaced existing) 2024/12/09 02:53:03 DEBUG : only19: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2024/12/09 02:53:03 INFO : only19: Copied (replaced existing) 2024/12/09 02:53:03.222050 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 2024/12/09 02:53:03.222071 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) 2024/12/09 02:53:03.274317 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 2024/12/09 02:53:03.274334 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) 2024/12/09 02:53:03 DEBUG : only3: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2024/12/09 02:53:03 INFO : only3: Copied (replaced existing) 2024/12/09 02:53:03.507341 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 2024/12/09 02:53:03.507363 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) 2024/12/09 02:53:03 DEBUG : only0: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2024/12/09 02:53:03 INFO : only0: Copied (replaced existing) 2024/12/09 02:53:03.671513 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 2024/12/09 02:53:03.671536 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) 2024/12/09 02:53:04 DEBUG : only11: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2024/12/09 02:53:04 INFO : only11: Copied (replaced existing) 2024/12/09 02:53:04 DEBUG : only1: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2024/12/09 02:53:04 INFO : only1: Copied (replaced existing) 2024/12/09 02:53:04.223239 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 2024/12/09 02:53:04.223271 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) 2024/12/09 02:53:04.229073 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 2024/12/09 02:53:04.229127 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) 2024/12/09 02:53:04 DEBUG : only4: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2024/12/09 02:53:04 INFO : only4: Copied (replaced existing) 2024/12/09 02:53:04.414739 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 2024/12/09 02:53:04.414766 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) 2024/12/09 02:53:04 DEBUG : only8: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2024/12/09 02:53:04 INFO : only8: Copied (replaced existing) 2024/12/09 02:53:04.641477 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 2024/12/09 02:53:04.641515 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) 2024/12/09 02:53:04 DEBUG : only5: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2024/12/09 02:53:04 INFO : only5: Copied (replaced existing) 2024/12/09 02:53:04 DEBUG : only9: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2024/12/09 02:53:04 INFO : only9: Copied (replaced existing) 2024/12/09 02:53:05 DEBUG : only6: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2024/12/09 02:53:05 INFO : only6: Copied (replaced existing) 2024/12/09 02:53:05 DEBUG : only7: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2024/12/09 02:53:05 INFO : only7: Copied (replaced existing) 2024/12/09 02:53:05 DEBUG : Waiting for deletions to finish run.go:180: Remote "proton drive root link ID 'rclone-test-zokunir9jenu'", Local "Local file system at /tmp/rclone2405541440", Modify Window "1s" --- PASS: TestSyncConcurrentTruncate (64.14s) === RUN TestNothingToTransferWithEmptyDirs run.go:180: Remote "proton drive root link ID 'rclone-test-zokunir9jenu'", Local "Local file system at /tmp/rclone2405541440", Modify Window "1s" 2024/12/09 02:53:23 INFO : sub dir: Set directory modification time (using DirSetModTime) 2024/12/09 02:53:23 DEBUG : sub dir: Making directory 2024/12/09 02:53:23 INFO : sub dir: Made directory with modification time 2011-12-30 12:59:59 +0000 UTC run.go:180: Remote "proton drive root link ID 'rclone-test-zokunir9jenu'", Local "Local file system at /tmp/rclone2405541440", Modify Window "1s" run.go:180: Remote "proton drive root link ID 'rclone-test-zokunir9jenu'", Local "Local file system at /tmp/rclone2405541440", Modify Window "1s" --- PASS: TestNothingToTransferWithEmptyDirs (17.41s) === RUN TestNothingToTransferWithoutEmptyDirs run.go:180: Remote "proton drive root link ID 'rclone-test-zokunir9jenu'", Local "Local file system at /tmp/rclone2405541440", Modify Window "1s" 2024/12/09 02:53:40 INFO : sub dir: Set directory modification time (using DirSetModTime) 2024/12/09 02:53:40 DEBUG : sub dir: Making directory 2024/12/09 02:53:41 INFO : sub dir: Made directory with modification time 2011-12-30 12:59:59 +0000 UTC run.go:180: Remote "proton drive root link ID 'rclone-test-zokunir9jenu'", Local "Local file system at /tmp/rclone2405541440", Modify Window "1s" run.go:180: Remote "proton drive root link ID 'rclone-test-zokunir9jenu'", Local "Local file system at /tmp/rclone2405541440", Modify Window "1s" --- PASS: TestNothingToTransferWithoutEmptyDirs (20.87s) PASS 2024/12/09 02:54:01 DEBUG : proton drive root link ID 'rclone-test-zokunir9jenu': Purge remote "./sync.test -test.v -test.timeout 1h0m0s -remote TestProtonDrive: -verbose -test.run '^(TestFixCase|TestNothingToTransferWithEmptyDirs|TestNothingToTransferWithoutEmptyDirs|TestSyncConcurrentDelete|TestSyncConcurrentTruncate|TestSyncIgnoreCase|TestSyncImmutable|TestSyncSuffix|TestSyncSuffixKeepExtension|TestSyncUTFNorm)$|^TestMaxTransfer$/^(Cautious|Hard|Soft)$'" - Finished OK in 5m3.535593616s (try 2/5)