"./sync.test -test.v -test.timeout 1h0m0s -remote TestPixeldrain: -verbose -test.run '^(TestCopy|TestCopyMetadata|TestCopyMetadataNoEmptyDirs)$'" - Starting (try 2/5)
2026/05/03 04:44:42 DEBUG : Creating backend with remote "TestPixeldrain:rclone-test-doyidur8repa"
2026/05/03 04:44:42 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/05/03 04:44:42 INFO  : pixeldrain root 'rclone-test-doyidur8repa': Logged in as 'ncw', subscription 'Prepaid', storage limit -1
2026/05/03 04:44:42 DEBUG : Creating backend with remote "/tmp/rclone3939230414"
=== RUN   TestCopy
    run.go:185: Remote "pixeldrain root 'rclone-test-doyidur8repa'", Local "Local file system at /tmp/rclone3939230414", Modify Window "1ms"
2026/05/03 04:44:42 INFO  : sub dir: Set directory modification time (using DirSetModTime)
2026/05/03 04:44:42 DEBUG : Added delayed dir = "sub dir", newDst=<nil>
2026/05/03 04:44:42 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2026/05/03 04:44:42 DEBUG : pixeldrain root 'rclone-test-doyidur8repa': Waiting for checks to finish
2026/05/03 04:44:42 DEBUG : pixeldrain root 'rclone-test-doyidur8repa': Waiting for transfers to finish
2026/05/03 04:44:42 DEBUG : sub dir/hello world: size = 11 OK
2026/05/03 04:44:42 DEBUG : sub dir/hello world: sha256 = b94d27b9934d3e08a52e52d7da7dabfac484efe37a5380ee9088f7ace2efcde9 OK
2026/05/03 04:44:42 INFO  : sub dir/hello world: Copied (new)
2026/05/03 04:44:42 INFO  : sub dir: Set directory modification time (using DirSetModTime)
--- PASS: TestCopy (0.36s)
=== RUN   TestCopyMetadata
    run.go:185: Remote "pixeldrain root 'rclone-test-doyidur8repa'", Local "Local file system at /tmp/rclone3939230414", Modify Window "1ms"
2026/05/03 04:44:43 DEBUG : metadata sub dir: Making directory with metadata
2026/05/03 04:44:43 INFO  : metadata sub dir: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z)
2026/05/03 04:44:43 DEBUG : empty metadata sub dir: Making directory with metadata
2026/05/03 04:44:43 INFO  : empty metadata sub dir: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z)
2026/05/03 04:44:43 DEBUG : Local file system at /tmp/rclone3939230414: File to upload is small (21 bytes), uploading instead of streaming
2026/05/03 04:44:43 DEBUG : metadata sub dir/hello metadata world: size = 21 OK
2026/05/03 04:44:43 DEBUG : metadata sub dir/hello metadata world: md5 = baa7a013449dbba2b1ed02b5cfb4e1a2 OK
2026/05/03 04:44:43 DEBUG : metadata sub dir/hello metadata world: Size and md5 of src and dst objects identical
2026/05/03 04:44:43 INFO  : metadata sub dir: Set directory modification time (using DirSetModTime)
2026/05/03 04:44:43 INFO  : empty metadata sub dir: Making directory
2026/05/03 04:44:43 INFO  : empty metadata sub dir: Made directory with modification time 2011-12-25 12:59:59.123456789 +0000 UTC
2026/05/03 04:44:43 INFO  : metadata sub dir: Making directory
2026/05/03 04:44:43 INFO  : metadata sub dir: Made directory with modification time 2011-12-25 12:59:59.123456789 +0000 UTC
2026/05/03 04:44:43 DEBUG : metadata sub dir/hello metadata world: Need to transfer - File not found at Destination
2026/05/03 04:44:43 DEBUG : pixeldrain root 'rclone-test-doyidur8repa': Waiting for checks to finish
2026/05/03 04:44:43 DEBUG : pixeldrain root 'rclone-test-doyidur8repa': Waiting for transfers to finish
2026/05/03 04:44:43 DEBUG : metadata sub dir/hello metadata world: size = 21 OK
2026/05/03 04:44:43 DEBUG : metadata sub dir/hello metadata world: sha256 = 64d5652158c99e4dd08097075e4e76b287f3b63667c0968c7867720a543c6916 OK
2026/05/03 04:44:43 INFO  : metadata sub dir/hello metadata world: Copied (new)
--- PASS: TestCopyMetadata (0.53s)
=== RUN   TestCopyMetadataNoEmptyDirs
    run.go:185: Remote "pixeldrain root 'rclone-test-doyidur8repa'", Local "Local file system at /tmp/rclone3939230414", Modify Window "1ms"
2026/05/03 04:44:43 DEBUG : metadata sub dir: Making directory with metadata
2026/05/03 04:44:43 INFO  : metadata sub dir: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z)
2026/05/03 04:44:43 DEBUG : empty metadata sub dir: Making directory with metadata
2026/05/03 04:44:43 INFO  : empty metadata sub dir: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z)
2026/05/03 04:44:43 DEBUG : Local file system at /tmp/rclone3939230414: File to upload is small (21 bytes), uploading instead of streaming
2026/05/03 04:44:43 DEBUG : metadata sub dir/hello metadata world: size = 21 OK
2026/05/03 04:44:43 DEBUG : metadata sub dir/hello metadata world: md5 = baa7a013449dbba2b1ed02b5cfb4e1a2 OK
2026/05/03 04:44:43 DEBUG : metadata sub dir/hello metadata world: Size and md5 of src and dst objects identical
2026/05/03 04:44:43 INFO  : metadata sub dir: Set directory modification time (using DirSetModTime)
2026/05/03 04:44:43 DEBUG : Added delayed dir = "empty metadata sub dir", newDst=<nil>
2026/05/03 04:44:43 DEBUG : Added delayed dir = "metadata sub dir", newDst=<nil>
2026/05/03 04:44:43 DEBUG : metadata sub dir/hello metadata world: Need to transfer - File not found at Destination
2026/05/03 04:44:43 DEBUG : pixeldrain root 'rclone-test-doyidur8repa': Waiting for checks to finish
2026/05/03 04:44:43 DEBUG : pixeldrain root 'rclone-test-doyidur8repa': Waiting for transfers to finish
2026/05/03 04:44:43 DEBUG : metadata sub dir/hello metadata world: size = 21 OK
2026/05/03 04:44:43 DEBUG : metadata sub dir/hello metadata world: sha256 = 64d5652158c99e4dd08097075e4e76b287f3b63667c0968c7867720a543c6916 OK
2026/05/03 04:44:43 INFO  : metadata sub dir/hello metadata world: Copied (new)
2026/05/03 04:44:43 INFO  : metadata sub dir: Set directory modification time (using DirSetModTime)
--- PASS: TestCopyMetadataNoEmptyDirs (0.38s)
PASS
2026/05/03 04:44:44 DEBUG : pixeldrain root 'rclone-test-doyidur8repa': Purge remote
"./sync.test -test.v -test.timeout 1h0m0s -remote TestPixeldrain: -verbose -test.run '^(TestCopy|TestCopyMetadata|TestCopyMetadataNoEmptyDirs)$'" - Finished OK in 1.48219342s (try 2/5)