"./sync.test -test.v -test.timeout 2h0m0s -remote TestPutio: -verbose -test.run '^TestSyncConcurrentTruncate$'" - Starting (try 3/5)
2026/09/08 06:04:55 DEBUG : Creating backend with remote "TestPutio:rclone-test-zewigep9hufe"
2026/09/08 06:04:55 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/09/08 06:04:55 DEBUG : Creating backend with remote "/tmp/rclone1403018897"
=== RUN   TestSyncConcurrentTruncate
    run.go:198: Remote "Putio root 'rclone-test-zewigep9hufe'", Local "Local file system at /tmp/rclone1403018897", Modify Window "1s"
2026/09/08 06:04:56 DEBUG : Putio root 'rclone-test-zewigep9hufe': chunkStart: 0, reqSize: 6
2026/09/08 06:04:56 DEBUG : Putio root 'rclone-test-zewigep9hufe': Sending chunk. transferOffset: 0 length: 6
2026/09/08 06:04:56 DEBUG : pacer: low level retry 1/10 (error unexpected status code (429) response while doing POST to https://upload.put.io/files/: trying again in 4.358371004s)
2026/09/08 06:04:56 DEBUG : pacer: Rate limited, increasing sleep to 4.358371004s
2026/09/08 06:04:56 DEBUG : pacer: low level retry 2/10 (error unexpected status code (429) response while doing POST to https://upload.put.io/files/: trying again in 4.156088005s)
2026/09/08 06:04:56 DEBUG : pacer: Rate limited, increasing sleep to 4.156088005s
2026/09/08 06:05:01 DEBUG : pacer: Reducing sleep to 2.078044002s
2026/09/08 06:05:01 DEBUG : Putio root 'rclone-test-zewigep9hufe': chunkStart: 0, reqSize: 6
2026/09/08 06:05:05 DEBUG : Putio root 'rclone-test-zewigep9hufe': Sending chunk. transferOffset: 0 length: 6
2026/09/08 06:05:05 DEBUG : pacer: Reducing sleep to 1.039022001s
2026/09/08 06:05:07 DEBUG : pacer: Reducing sleep to 519.511ms
2026/09/08 06:05:08 DEBUG : pacer: Reducing sleep to 259.7555ms
2026/09/08 06:05:09 DEBUG : pacer: Reducing sleep to 129.87775ms
2026/09/08 06:05:09 DEBUG : Putio root 'rclone-test-zewigep9hufe': chunkStart: 0, reqSize: 6
2026/09/08 06:05:09 DEBUG : Putio root 'rclone-test-zewigep9hufe': Sending chunk. transferOffset: 0 length: 6
2026/09/08 06:05:09 DEBUG : pacer: Reducing sleep to 64.938875ms
2026/09/08 06:05:09 DEBUG : pacer: Reducing sleep to 32.469437ms
2026/09/08 06:05:09 DEBUG : pacer: Reducing sleep to 16.234718ms
2026/09/08 06:05:09 DEBUG : pacer: Reducing sleep to 10ms
2026/09/08 06:05:09 DEBUG : Putio root 'rclone-test-zewigep9hufe': chunkStart: 0, reqSize: 6
2026/09/08 06:05:09 DEBUG : Putio root 'rclone-test-zewigep9hufe': Sending chunk. transferOffset: 0 length: 6
2026/09/08 06:05:10 DEBUG : Putio root 'rclone-test-zewigep9hufe': chunkStart: 0, reqSize: 6
2026/09/08 06:05:10 DEBUG : Putio root 'rclone-test-zewigep9hufe': Sending chunk. transferOffset: 0 length: 6
2026/09/08 06:05:11 DEBUG : Putio root 'rclone-test-zewigep9hufe': chunkStart: 0, reqSize: 6
2026/09/08 06:05:11 DEBUG : Putio root 'rclone-test-zewigep9hufe': Sending chunk. transferOffset: 0 length: 6
2026/09/08 06:05:12 DEBUG : Putio root 'rclone-test-zewigep9hufe': chunkStart: 0, reqSize: 6
2026/09/08 06:05:12 DEBUG : Putio root 'rclone-test-zewigep9hufe': Sending chunk. transferOffset: 0 length: 6
2026/09/08 06:05:13 DEBUG : Putio root 'rclone-test-zewigep9hufe': chunkStart: 0, reqSize: 6
2026/09/08 06:05:13 DEBUG : Putio root 'rclone-test-zewigep9hufe': Sending chunk. transferOffset: 0 length: 6
2026/09/08 06:05:13 DEBUG : Putio root 'rclone-test-zewigep9hufe': chunkStart: 0, reqSize: 6
2026/09/08 06:05:13 DEBUG : Putio root 'rclone-test-zewigep9hufe': Sending chunk. transferOffset: 0 length: 6
2026/09/08 06:05:14 DEBUG : Putio root 'rclone-test-zewigep9hufe': chunkStart: 0, reqSize: 6
2026/09/08 06:05:14 DEBUG : Putio root 'rclone-test-zewigep9hufe': Sending chunk. transferOffset: 0 length: 6
2026/09/08 06:05:15 DEBUG : Putio root 'rclone-test-zewigep9hufe': chunkStart: 0, reqSize: 6
2026/09/08 06:05:15 DEBUG : Putio root 'rclone-test-zewigep9hufe': Sending chunk. transferOffset: 0 length: 6
2026/09/08 06:05:16 DEBUG : Putio root 'rclone-test-zewigep9hufe': chunkStart: 0, reqSize: 6
2026/09/08 06:05:16 DEBUG : Putio root 'rclone-test-zewigep9hufe': Sending chunk. transferOffset: 0 length: 6
2026/09/08 06:05:17 DEBUG : Putio root 'rclone-test-zewigep9hufe': chunkStart: 0, reqSize: 6
2026/09/08 06:05:17 DEBUG : Putio root 'rclone-test-zewigep9hufe': Sending chunk. transferOffset: 0 length: 6
2026/09/08 06:05:18 DEBUG : Putio root 'rclone-test-zewigep9hufe': chunkStart: 0, reqSize: 6
2026/09/08 06:05:18 DEBUG : Putio root 'rclone-test-zewigep9hufe': Sending chunk. transferOffset: 0 length: 6
2026/09/08 06:05:19 DEBUG : Putio root 'rclone-test-zewigep9hufe': chunkStart: 0, reqSize: 6
2026/09/08 06:05:19 DEBUG : Putio root 'rclone-test-zewigep9hufe': Sending chunk. transferOffset: 0 length: 6
2026/09/08 06:05:19 DEBUG : Putio root 'rclone-test-zewigep9hufe': chunkStart: 0, reqSize: 6
2026/09/08 06:05:19 DEBUG : Putio root 'rclone-test-zewigep9hufe': Sending chunk. transferOffset: 0 length: 6
2026/09/08 06:05:20 DEBUG : Putio root 'rclone-test-zewigep9hufe': chunkStart: 0, reqSize: 6
2026/09/08 06:05:20 DEBUG : Putio root 'rclone-test-zewigep9hufe': Sending chunk. transferOffset: 0 length: 6
2026/09/08 06:05:21 DEBUG : Putio root 'rclone-test-zewigep9hufe': chunkStart: 0, reqSize: 6
2026/09/08 06:05:21 DEBUG : Putio root 'rclone-test-zewigep9hufe': Sending chunk. transferOffset: 0 length: 6
2026/09/08 06:05:21 DEBUG : Putio root 'rclone-test-zewigep9hufe': chunkStart: 0, reqSize: 6
2026/09/08 06:05:21 DEBUG : Putio root 'rclone-test-zewigep9hufe': Sending chunk. transferOffset: 0 length: 6
2026/09/08 06:05:22 DEBUG : Putio root 'rclone-test-zewigep9hufe': chunkStart: 0, reqSize: 6
2026/09/08 06:05:22 DEBUG : Putio root 'rclone-test-zewigep9hufe': Sending chunk. transferOffset: 0 length: 6
2026/09/08 06:05:23 DEBUG : Putio root 'rclone-test-zewigep9hufe': chunkStart: 0, reqSize: 6
2026/09/08 06:05:23 DEBUG : Putio root 'rclone-test-zewigep9hufe': Sending chunk. transferOffset: 0 length: 6
2026/09/08 06:05:24 DEBUG : Putio root 'rclone-test-zewigep9hufe': chunkStart: 0, reqSize: 6
2026/09/08 06:05:24 DEBUG : Putio root 'rclone-test-zewigep9hufe': Sending chunk. transferOffset: 0 length: 6
2026/09/08 06:05:25 DEBUG : Putio root 'rclone-test-zewigep9hufe': chunkStart: 0, reqSize: 6
2026/09/08 06:05:25 DEBUG : Putio root 'rclone-test-zewigep9hufe': Sending chunk. transferOffset: 0 length: 6
2026/09/08 06:05:25 DEBUG : Putio root 'rclone-test-zewigep9hufe': chunkStart: 0, reqSize: 6
2026/09/08 06:05:25 DEBUG : Putio root 'rclone-test-zewigep9hufe': Sending chunk. transferOffset: 0 length: 6
2026/09/08 06:05:26 DEBUG : Putio root 'rclone-test-zewigep9hufe': chunkStart: 0, reqSize: 6
2026/09/08 06:05:26 DEBUG : Putio root 'rclone-test-zewigep9hufe': Sending chunk. transferOffset: 0 length: 6
2026/09/08 06:05:27 DEBUG : Putio root 'rclone-test-zewigep9hufe': chunkStart: 0, reqSize: 6
2026/09/08 06:05:27 DEBUG : Putio root 'rclone-test-zewigep9hufe': Sending chunk. transferOffset: 0 length: 6
2026/09/08 06:05:27 DEBUG : Putio root 'rclone-test-zewigep9hufe': chunkStart: 0, reqSize: 6
2026/09/08 06:05:27 DEBUG : Putio root 'rclone-test-zewigep9hufe': Sending chunk. transferOffset: 0 length: 6
2026/09/08 06:05:28 DEBUG : Putio root 'rclone-test-zewigep9hufe': chunkStart: 0, reqSize: 6
2026/09/08 06:05:28 DEBUG : Putio root 'rclone-test-zewigep9hufe': Sending chunk. transferOffset: 0 length: 6
2026/09/08 06:05:29 DEBUG : Putio root 'rclone-test-zewigep9hufe': chunkStart: 0, reqSize: 6
2026/09/08 06:05:29 DEBUG : Putio root 'rclone-test-zewigep9hufe': Sending chunk. transferOffset: 0 length: 6
2026/09/08 06:05:29 DEBUG : Putio root 'rclone-test-zewigep9hufe': chunkStart: 0, reqSize: 6
2026/09/08 06:05:29 DEBUG : Putio root 'rclone-test-zewigep9hufe': Sending chunk. transferOffset: 0 length: 6
2026/09/08 06:05:30 DEBUG : Putio root 'rclone-test-zewigep9hufe': chunkStart: 0, reqSize: 6
2026/09/08 06:05:30 DEBUG : Putio root 'rclone-test-zewigep9hufe': Sending chunk. transferOffset: 0 length: 6
2026/09/08 06:05:31 DEBUG : Putio root 'rclone-test-zewigep9hufe': chunkStart: 0, reqSize: 6
2026/09/08 06:05:31 DEBUG : Putio root 'rclone-test-zewigep9hufe': Sending chunk. transferOffset: 0 length: 6
2026/09/08 06:05:32 DEBUG : Putio root 'rclone-test-zewigep9hufe': chunkStart: 0, reqSize: 6
2026/09/08 06:05:32 DEBUG : Putio root 'rclone-test-zewigep9hufe': Sending chunk. transferOffset: 0 length: 6
2026/09/08 06:05:32 DEBUG : Putio root 'rclone-test-zewigep9hufe': chunkStart: 0, reqSize: 6
2026/09/08 06:05:32 DEBUG : Putio root 'rclone-test-zewigep9hufe': Sending chunk. transferOffset: 0 length: 6
2026/09/08 06:05:33 DEBUG : Putio root 'rclone-test-zewigep9hufe': chunkStart: 0, reqSize: 6
2026/09/08 06:05:33 DEBUG : Putio root 'rclone-test-zewigep9hufe': Sending chunk. transferOffset: 0 length: 6
2026/09/08 06:05:34 DEBUG : Putio root 'rclone-test-zewigep9hufe': chunkStart: 0, reqSize: 6
2026/09/08 06:05:34 DEBUG : Putio root 'rclone-test-zewigep9hufe': Sending chunk. transferOffset: 0 length: 6
2026/09/08 06:05:35 DEBUG : Putio root 'rclone-test-zewigep9hufe': chunkStart: 0, reqSize: 6
2026/09/08 06:05:35 DEBUG : Putio root 'rclone-test-zewigep9hufe': Sending chunk. transferOffset: 0 length: 6
2026/09/08 06:05:36 DEBUG : Putio root 'rclone-test-zewigep9hufe': chunkStart: 0, reqSize: 6
2026/09/08 06:05:36 DEBUG : Putio root 'rclone-test-zewigep9hufe': Sending chunk. transferOffset: 0 length: 6
2026/09/08 06:05:36 DEBUG : Putio root 'rclone-test-zewigep9hufe': chunkStart: 0, reqSize: 6
2026/09/08 06:05:36 DEBUG : Putio root 'rclone-test-zewigep9hufe': Sending chunk. transferOffset: 0 length: 6
2026/09/08 06:05:37 DEBUG : Putio root 'rclone-test-zewigep9hufe': chunkStart: 0, reqSize: 6
2026/09/08 06:05:37 DEBUG : Putio root 'rclone-test-zewigep9hufe': Sending chunk. transferOffset: 0 length: 6
2026/09/08 06:05:38 DEBUG : both11: size = 6 OK
2026/09/08 06:05:38 DEBUG : Putio root 'rclone-test-zewigep9hufe': Waiting for checks to finish
2026/09/08 06:05:38 DEBUG : both12: size = 6 OK
2026/09/08 06:05:38 DEBUG : both12: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2026/09/08 06:05:38 DEBUG : both12: Unchanged skipping
2026/09/08 06:05:38 DEBUG : both13: size = 6 OK
2026/09/08 06:05:38 DEBUG : both13: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2026/09/08 06:05:38 DEBUG : both11: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2026/09/08 06:05:38 DEBUG : both10: size = 6 OK
2026/09/08 06:05:38 DEBUG : both10: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2026/09/08 06:05:38 DEBUG : both0: size = 6 OK
2026/09/08 06:05:38 DEBUG : both13: Unchanged skipping
2026/09/08 06:05:38 DEBUG : both14: size = 6 OK
2026/09/08 06:05:38 DEBUG : both0: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2026/09/08 06:05:38 DEBUG : both14: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2026/09/08 06:05:38 DEBUG : both10: Unchanged skipping
2026/09/08 06:05:38 DEBUG : both15: size = 6 OK
2026/09/08 06:05:38 DEBUG : both15: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2026/09/08 06:05:38 DEBUG : both15: Unchanged skipping
2026/09/08 06:05:38 DEBUG : both16: size = 6 OK
2026/09/08 06:05:38 DEBUG : both16: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2026/09/08 06:05:38 DEBUG : both16: Unchanged skipping
2026/09/08 06:05:38 DEBUG : both17: size = 6 OK
2026/09/08 06:05:38 DEBUG : both17: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2026/09/08 06:05:38 DEBUG : both11: Unchanged skipping
2026/09/08 06:05:38 DEBUG : both18: size = 6 OK
2026/09/08 06:05:38 DEBUG : both18: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2026/09/08 06:05:38 DEBUG : both18: Unchanged skipping
2026/09/08 06:05:38 DEBUG : both19: size = 6 OK
2026/09/08 06:05:38 DEBUG : both19: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2026/09/08 06:05:38 DEBUG : both19: Unchanged skipping
2026/09/08 06:05:38 DEBUG : both1: size = 6 OK
2026/09/08 06:05:38 DEBUG : both1: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2026/09/08 06:05:38 DEBUG : both0: Unchanged skipping
2026/09/08 06:05:38 DEBUG : both2: size = 6 OK
2026/09/08 06:05:38 DEBUG : both2: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2026/09/08 06:05:38 DEBUG : both2: Unchanged skipping
2026/09/08 06:05:38 DEBUG : both3: size = 6 OK
2026/09/08 06:05:38 DEBUG : both3: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2026/09/08 06:05:38 DEBUG : both3: Unchanged skipping
2026/09/08 06:05:38 DEBUG : both4: size = 6 OK
2026/09/08 06:05:38 DEBUG : both4: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2026/09/08 06:05:38 DEBUG : both4: Unchanged skipping
2026/09/08 06:05:38 DEBUG : both5: size = 6 OK
2026/09/08 06:05:38 DEBUG : both5: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2026/09/08 06:05:38 DEBUG : both5: Unchanged skipping
2026/09/08 06:05:38 DEBUG : both6: size = 6 OK
2026/09/08 06:05:38 DEBUG : both6: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2026/09/08 06:05:38 DEBUG : both14: Unchanged skipping
2026/09/08 06:05:38 DEBUG : both7: size = 6 OK
2026/09/08 06:05:38 DEBUG : both7: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2026/09/08 06:05:38 DEBUG : both7: Unchanged skipping
2026/09/08 06:05:38 DEBUG : both8: size = 6 OK
2026/09/08 06:05:38 DEBUG : both8: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2026/09/08 06:05:38 DEBUG : both8: Unchanged skipping
2026/09/08 06:05:38 DEBUG : both9: size = 6 OK
2026/09/08 06:05:38 DEBUG : both9: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2026/09/08 06:05:38 DEBUG : both9: Unchanged skipping
2026/09/08 06:05:38 DEBUG : only0: size = 0 (Local file system at /tmp/rclone1403018897)
2026/09/08 06:05:38 DEBUG : only0: size = 6 (Putio root 'rclone-test-zewigep9hufe')
2026/09/08 06:05:38 DEBUG : only0: Sizes differ
2026/09/08 06:05:38 DEBUG : only10: size = 0 (Local file system at /tmp/rclone1403018897)
2026/09/08 06:05:38 DEBUG : only10: size = 6 (Putio root 'rclone-test-zewigep9hufe')
2026/09/08 06:05:38 DEBUG : only10: Sizes differ
2026/09/08 06:05:38 DEBUG : only11: size = 0 (Local file system at /tmp/rclone1403018897)
2026/09/08 06:05:38 DEBUG : only11: size = 6 (Putio root 'rclone-test-zewigep9hufe')
2026/09/08 06:05:38 DEBUG : only11: Sizes differ
2026/09/08 06:05:38 DEBUG : only12: size = 0 (Local file system at /tmp/rclone1403018897)
2026/09/08 06:05:38 DEBUG : only12: size = 6 (Putio root 'rclone-test-zewigep9hufe')
2026/09/08 06:05:38 DEBUG : only12: Sizes differ
2026/09/08 06:05:38 DEBUG : both6: Unchanged skipping
2026/09/08 06:05:38 DEBUG : only13: size = 0 (Local file system at /tmp/rclone1403018897)
2026/09/08 06:05:38 DEBUG : only13: size = 6 (Putio root 'rclone-test-zewigep9hufe')
2026/09/08 06:05:38 DEBUG : only13: Sizes differ
2026/09/08 06:05:38 DEBUG : both17: Unchanged skipping
2026/09/08 06:05:38 DEBUG : only14: size = 0 (Local file system at /tmp/rclone1403018897)
2026/09/08 06:05:38 DEBUG : only14: size = 6 (Putio root 'rclone-test-zewigep9hufe')
2026/09/08 06:05:38 DEBUG : only15: size = 0 (Local file system at /tmp/rclone1403018897)
2026/09/08 06:05:38 DEBUG : only14: Sizes differ
2026/09/08 06:05:38 DEBUG : only16: size = 0 (Local file system at /tmp/rclone1403018897)
2026/09/08 06:05:38 DEBUG : only16: size = 6 (Putio root 'rclone-test-zewigep9hufe')
2026/09/08 06:05:38 DEBUG : only16: Sizes differ
2026/09/08 06:05:38 DEBUG : both1: Unchanged skipping
2026/09/08 06:05:38 DEBUG : only17: size = 0 (Local file system at /tmp/rclone1403018897)
2026/09/08 06:05:38 DEBUG : only18: size = 0 (Local file system at /tmp/rclone1403018897)
2026/09/08 06:05:38 DEBUG : only17: size = 6 (Putio root 'rclone-test-zewigep9hufe')
2026/09/08 06:05:38 DEBUG : only15: size = 6 (Putio root 'rclone-test-zewigep9hufe')
2026/09/08 06:05:38 DEBUG : only17: Sizes differ
2026/09/08 06:05:38 DEBUG : only15: Sizes differ
2026/09/08 06:05:38 DEBUG : only18: size = 6 (Putio root 'rclone-test-zewigep9hufe')
2026/09/08 06:05:38 DEBUG : only18: Sizes differ
2026/09/08 06:05:38 DEBUG : only19: size = 0 (Local file system at /tmp/rclone1403018897)
2026/09/08 06:05:38 DEBUG : only19: size = 6 (Putio root 'rclone-test-zewigep9hufe')
2026/09/08 06:05:38 DEBUG : only19: Sizes differ
2026/09/08 06:05:38 DEBUG : only1: size = 0 (Local file system at /tmp/rclone1403018897)
2026/09/08 06:05:38 DEBUG : only1: size = 6 (Putio root 'rclone-test-zewigep9hufe')
2026/09/08 06:05:38 DEBUG : only1: Sizes differ
2026/09/08 06:05:38 DEBUG : only2: size = 0 (Local file system at /tmp/rclone1403018897)
2026/09/08 06:05:38 DEBUG : only2: size = 6 (Putio root 'rclone-test-zewigep9hufe')
2026/09/08 06:05:38 DEBUG : only2: Sizes differ
2026/09/08 06:05:38 DEBUG : only3: size = 0 (Local file system at /tmp/rclone1403018897)
2026/09/08 06:05:38 DEBUG : only3: size = 6 (Putio root 'rclone-test-zewigep9hufe')
2026/09/08 06:05:38 DEBUG : only3: Sizes differ
2026/09/08 06:05:38 DEBUG : only4: size = 0 (Local file system at /tmp/rclone1403018897)
2026/09/08 06:05:38 DEBUG : only4: size = 6 (Putio root 'rclone-test-zewigep9hufe')
2026/09/08 06:05:38 DEBUG : only4: Sizes differ
2026/09/08 06:05:38 DEBUG : only5: size = 0 (Local file system at /tmp/rclone1403018897)
2026/09/08 06:05:38 DEBUG : only5: size = 6 (Putio root 'rclone-test-zewigep9hufe')
2026/09/08 06:05:38 DEBUG : only5: Sizes differ
2026/09/08 06:05:38 DEBUG : only6: size = 0 (Local file system at /tmp/rclone1403018897)
2026/09/08 06:05:38 DEBUG : only6: size = 6 (Putio root 'rclone-test-zewigep9hufe')
2026/09/08 06:05:38 DEBUG : only6: Sizes differ
2026/09/08 06:05:38 DEBUG : only7: size = 0 (Local file system at /tmp/rclone1403018897)
2026/09/08 06:05:38 DEBUG : only7: size = 6 (Putio root 'rclone-test-zewigep9hufe')
2026/09/08 06:05:38 DEBUG : only7: Sizes differ
2026/09/08 06:05:38 DEBUG : only8: size = 0 (Local file system at /tmp/rclone1403018897)
2026/09/08 06:05:38 DEBUG : only8: size = 6 (Putio root 'rclone-test-zewigep9hufe')
2026/09/08 06:05:38 DEBUG : only8: Sizes differ
2026/09/08 06:05:38 DEBUG : only9: size = 0 (Local file system at /tmp/rclone1403018897)
2026/09/08 06:05:38 DEBUG : only9: size = 6 (Putio root 'rclone-test-zewigep9hufe')
2026/09/08 06:05:38 DEBUG : only9: Sizes differ
2026/09/08 06:05:38 DEBUG : Putio root 'rclone-test-zewigep9hufe': Waiting for transfers to finish
2026/09/08 06:05:38 DEBUG : Putio root 'rclone-test-zewigep9hufe': Sending zero length chunk
2026/09/08 06:05:39 DEBUG : only0: size = 0 OK
2026/09/08 06:05:39 DEBUG : only0: crc32 = 00000000 OK
2026/09/08 06:05:39 INFO  : only0: Copied (replaced existing)
2026/09/08 06:05:39 DEBUG : Putio root 'rclone-test-zewigep9hufe': Sending zero length chunk
2026/09/08 06:05:39 DEBUG : Putio root 'rclone-test-zewigep9hufe': Sending zero length chunk
2026/09/08 06:05:39 DEBUG : pacer: low level retry 1/10 (error unexpected status code (500) response while doing PATCH to https://s66.put.io/files/ee153aa70fe305a96b30677b9a2e991d81255b59)
2026/09/08 06:05:39 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/09/08 06:05:39 DEBUG : Putio root 'rclone-test-zewigep9hufe': Sending zero length chunk
2026/09/08 06:05:39 DEBUG : pacer: Reducing sleep to 10ms
2026/09/08 06:05:39 ERROR : only14: Failed to copy: unexpected status code (404) response while doing PATCH to https://s66.put.io/files/ee153aa70fe305a96b30677b9a2e991d81255b59
2026/09/08 06:05:39 DEBUG : only10: size = 0 OK
2026/09/08 06:05:39 DEBUG : only10: crc32 = 00000000 OK
2026/09/08 06:05:39 INFO  : only10: Copied (replaced existing)
2026/09/08 06:05:40 DEBUG : Putio root 'rclone-test-zewigep9hufe': Sending zero length chunk
2026/09/08 06:05:40 DEBUG : Putio root 'rclone-test-zewigep9hufe': Sending zero length chunk
2026/09/08 06:05:40 DEBUG : only12: size = 0 OK
2026/09/08 06:05:40 DEBUG : only12: crc32 = 00000000 OK
2026/09/08 06:05:40 INFO  : only12: Copied (replaced existing)
2026/09/08 06:05:40 DEBUG : Putio root 'rclone-test-zewigep9hufe': Sending zero length chunk
2026/09/08 06:05:40 DEBUG : only16: size = 0 OK
2026/09/08 06:05:40 DEBUG : only16: crc32 = 00000000 OK
2026/09/08 06:05:40 INFO  : only16: Copied (replaced existing)
2026/09/08 06:05:40 DEBUG : Putio root 'rclone-test-zewigep9hufe': Sending zero length chunk
2026/09/08 06:05:41 DEBUG : Putio root 'rclone-test-zewigep9hufe': Sending zero length chunk
2026/09/08 06:05:41 DEBUG : only11: size = 0 OK
2026/09/08 06:05:41 DEBUG : only11: crc32 = 00000000 OK
2026/09/08 06:05:41 INFO  : only11: Copied (replaced existing)
2026/09/08 06:05:41 DEBUG : only18: size = 0 OK
2026/09/08 06:05:41 DEBUG : only18: crc32 = 00000000 OK
2026/09/08 06:05:41 INFO  : only18: Copied (replaced existing)
2026/09/08 06:05:41 DEBUG : only1: size = 0 OK
2026/09/08 06:05:41 DEBUG : only1: crc32 = 00000000 OK
2026/09/08 06:05:41 INFO  : only1: Copied (replaced existing)
2026/09/08 06:05:41 DEBUG : Putio root 'rclone-test-zewigep9hufe': Sending zero length chunk
2026/09/08 06:05:41 DEBUG : Putio root 'rclone-test-zewigep9hufe': Sending zero length chunk
2026/09/08 06:05:41 DEBUG : only2: size = 0 OK
2026/09/08 06:05:41 DEBUG : only2: crc32 = 00000000 OK
2026/09/08 06:05:41 INFO  : only2: Copied (replaced existing)
2026/09/08 06:05:41 DEBUG : Putio root 'rclone-test-zewigep9hufe': Sending zero length chunk
2026/09/08 06:05:41 DEBUG : only3: size = 0 OK
2026/09/08 06:05:41 DEBUG : only3: crc32 = 00000000 OK
2026/09/08 06:05:41 INFO  : only3: Copied (replaced existing)
2026/09/08 06:05:42 DEBUG : Putio root 'rclone-test-zewigep9hufe': Sending zero length chunk
2026/09/08 06:05:42 DEBUG : Putio root 'rclone-test-zewigep9hufe': Sending zero length chunk
2026/09/08 06:05:42 DEBUG : only4: size = 0 OK
2026/09/08 06:05:42 DEBUG : only4: crc32 = 00000000 OK
2026/09/08 06:05:42 INFO  : only4: Copied (replaced existing)
2026/09/08 06:05:42 DEBUG : Putio root 'rclone-test-zewigep9hufe': Sending zero length chunk
2026/09/08 06:05:42 DEBUG : only17: size = 0 OK
2026/09/08 06:05:42 DEBUG : only17: crc32 = 00000000 OK
2026/09/08 06:05:42 INFO  : only17: Copied (replaced existing)
2026/09/08 06:05:42 DEBUG : only5: size = 0 OK
2026/09/08 06:05:42 DEBUG : only5: crc32 = 00000000 OK
2026/09/08 06:05:42 INFO  : only5: Copied (replaced existing)
2026/09/08 06:05:42 DEBUG : Putio root 'rclone-test-zewigep9hufe': Sending zero length chunk
2026/09/08 06:05:42 DEBUG : only13: size = 0 OK
2026/09/08 06:05:42 DEBUG : only13: crc32 = 00000000 OK
2026/09/08 06:05:42 INFO  : only13: Copied (replaced existing)
2026/09/08 06:05:42 DEBUG : Putio root 'rclone-test-zewigep9hufe': Sending zero length chunk
2026/09/08 06:05:43 DEBUG : only7: size = 0 OK
2026/09/08 06:05:43 DEBUG : only7: crc32 = 00000000 OK
2026/09/08 06:05:43 INFO  : only7: Copied (replaced existing)
2026/09/08 06:05:43 DEBUG : only8: size = 0 OK
2026/09/08 06:05:43 DEBUG : only8: crc32 = 00000000 OK
2026/09/08 06:05:43 INFO  : only8: Copied (replaced existing)
2026/09/08 06:05:43 DEBUG : Putio root 'rclone-test-zewigep9hufe': Sending zero length chunk
2026/09/08 06:05:43 DEBUG : Putio root 'rclone-test-zewigep9hufe': Sending zero length chunk
2026/09/08 06:05:43 DEBUG : pacer: low level retry 1/10 (error unexpected status code (500) response while doing PATCH to https://s103.put.io/files/7c771e3d603fbc35bbe553390a59c274a5adbea5)
2026/09/08 06:05:43 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/09/08 06:05:43 DEBUG : Putio root 'rclone-test-zewigep9hufe': Sending zero length chunk
2026/09/08 06:05:43 DEBUG : pacer: Reducing sleep to 10ms
2026/09/08 06:05:43 ERROR : only19: Failed to copy: unexpected status code (404) response while doing PATCH to https://s103.put.io/files/7c771e3d603fbc35bbe553390a59c274a5adbea5
2026/09/08 06:05:43 DEBUG : Putio root 'rclone-test-zewigep9hufe': Sending zero length chunk
2026/09/08 06:05:43 DEBUG : only15: size = 0 OK
2026/09/08 06:05:43 DEBUG : only15: crc32 = 00000000 OK
2026/09/08 06:05:43 INFO  : only15: Copied (replaced existing)
2026/09/08 06:05:44 DEBUG : Putio root 'rclone-test-zewigep9hufe': Sending zero length chunk
2026/09/08 06:05:44 DEBUG : only6: size = 0 OK
2026/09/08 06:05:44 DEBUG : only6: crc32 = 00000000 OK
2026/09/08 06:05:44 INFO  : only6: Copied (replaced existing)
2026/09/08 06:05:44 DEBUG : only9: size = 0 OK
2026/09/08 06:05:44 DEBUG : only9: crc32 = 00000000 OK
2026/09/08 06:05:44 INFO  : only9: Copied (replaced existing)
2026/09/08 06:05:44 ERROR : Putio root 'rclone-test-zewigep9hufe': not deleting files as there were IO errors
2026/09/08 06:05:44 ERROR : Putio root 'rclone-test-zewigep9hufe': not deleting directories as there were IO errors
    sync_test.go:2783: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2783
        	            				/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2793
        	Error:      	Received unexpected error:
        	            	unexpected status code (404) response while doing PATCH to https://s103.put.io/files/7c771e3d603fbc35bbe553390a59c274a5adbea5
        	Test:       	TestSyncConcurrentTruncate
        	Messages:   	Sync must not return a error
    sync_test.go:2784: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2784
        	            				/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2793
        	Error:      	Should be false
        	Test:       	TestSyncConcurrentTruncate
        	Messages:   	Low level errors must not have happened
    fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3
    fstest.go:302: Flushing the directory cache
    fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3
    fstest.go:302: Flushing the directory cache
    fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3
    fstest.go:302: Flushing the directory cache
    fstest.go:306: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:306
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:339
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:351
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:358
        	            				/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2785
        	            				/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2793
        	            				/usr/local/go/src/runtime/asm_amd64.s:1264
        	Error:      	Should be true
        	Test:       	TestSyncConcurrentTruncate
        	Messages:   	listing wrong, want
        	            	  both0 (6), both1 (6), both10 (6), both11 (6), both12 (6), both13 (6), both14 (6), both15 (6), both16 (6), both17 (6), both18 (6), both19 (6), both2 (6), both3 (6), both4 (6), both5 (6), both6 (6), both7 (6), both8 (6), both9 (6), only0 (0), only1 (0), only10 (0), only11 (0), only12 (0), only13 (0), only14 (0), only15 (0), only16 (0), only17 (0), only18 (0), only19 (0), only2 (0), only3 (0), only4 (0), only5 (0), only6 (0), only7 (0), only8 (0), only9 (0) got
        	            	  both0 (6), both1 (6), both10 (6), both11 (6), both12 (6), both13 (6), both14 (6), both15 (6), both16 (6), both17 (6), both18 (6), both19 (6), both2 (6), both3 (6), both4 (6), both5 (6), both6 (6), both7 (6), both8 (6), both9 (6), only0 (0), only1 (0), only10 (0), only11 (0), only12 (0), only13 (0), only15 (0), only16 (0), only17 (0), only18 (0), only2 (0), only3 (0), only4 (0), only5 (0), only6 (0), only7 (0), only8 (0), only9 (0)
    fstest.go:204: Not found "only19"
    fstest.go:204: Not found "only14"
    fstest.go:207: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:207
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:311
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:339
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:351
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:358
        	            				/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2785
        	            				/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2793
        	Error:      	Not equal: 
        	            	expected: 0
        	            	actual  : 2
        	Test:       	TestSyncConcurrentTruncate
        	Messages:   	2 objects not found
--- FAIL: TestSyncConcurrentTruncate (60.31s)
FAIL
2026/09/08 06:05:55 DEBUG : Putio root 'rclone-test-zewigep9hufe': Purge remote
"./sync.test -test.v -test.timeout 2h0m0s -remote TestPutio: -verbose -test.run '^TestSyncConcurrentTruncate$'" - Finished ERROR in 1m0.711200648s (try 3/5): exit status 1: Failed [TestSyncConcurrentTruncate]