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