"./sync.test -test.v -test.timeout 1h0m0s -remote TestPixeldrain: -verbose -test.run '^(TestCopy|TestCopyEmptyDirectories|TestCopyMetadata|TestCopyMetadataNoEmptyDirs|TestNothingToTransferWithEmptyDirs|TestNothingToTransferWithoutEmptyDirs)$'" - Starting (try 5/5)
2026/07/10 03:01:48 DEBUG : Creating backend with remote "TestPixeldrain:rclone-test-cajirar6bibi"
2026/07/10 03:01:48 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/07/10 03:01:48 INFO  : pixeldrain root 'rclone-test-cajirar6bibi': Logged in as 'ncw', subscription 'Prepaid', storage limit -1
2026/07/10 03:01:49 DEBUG : Creating backend with remote "/tmp/rclone2733966209"
=== RUN   TestCopy
    run.go:198: Remote "pixeldrain root 'rclone-test-cajirar6bibi'", Local "Local file system at /tmp/rclone2733966209", Modify Window "1ms"
2026/07/10 03:01:49 INFO  : sub dir: Set directory modification time (using DirSetModTime)
2026/07/10 03:01:49 DEBUG : Added delayed dir = "sub dir", newDst=<nil>
2026/07/10 03:01:49 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2026/07/10 03:01:49 DEBUG : pixeldrain root 'rclone-test-cajirar6bibi': Waiting for checks to finish
2026/07/10 03:01:49 DEBUG : pixeldrain root 'rclone-test-cajirar6bibi': Waiting for transfers to finish
2026/07/10 03:01:49 DEBUG : sub dir/hello world: size = 11 OK
2026/07/10 03:01:49 DEBUG : sub dir/hello world: sha256 = b94d27b9934d3e08a52e52d7da7dabfac484efe37a5380ee9088f7ace2efcde9 OK
2026/07/10 03:01:49 INFO  : sub dir/hello world: Copied (new)
2026/07/10 03:01:49 INFO  : sub dir: Set directory modification time (using DirSetModTime)
--- PASS: TestCopy (0.36s)
=== RUN   TestCopyMetadata
    run.go:198: Remote "pixeldrain root 'rclone-test-cajirar6bibi'", Local "Local file system at /tmp/rclone2733966209", Modify Window "1ms"
2026/07/10 03:01:49 DEBUG : metadata sub dir: Making directory with metadata
2026/07/10 03:01:49 INFO  : metadata sub dir: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z)
2026/07/10 03:01:49 DEBUG : empty metadata sub dir: Making directory with metadata
2026/07/10 03:01:49 INFO  : empty metadata sub dir: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z)
2026/07/10 03:01:49 DEBUG : Local file system at /tmp/rclone2733966209: File to upload is small (21 bytes), uploading instead of streaming
2026/07/10 03:01:49 DEBUG : metadata sub dir/hello metadata world: size = 21 OK
2026/07/10 03:01:49 DEBUG : metadata sub dir/hello metadata world: md5 = baa7a013449dbba2b1ed02b5cfb4e1a2 OK
2026/07/10 03:01:49 DEBUG : metadata sub dir/hello metadata world: Size and md5 of src and dst objects identical
2026/07/10 03:01:49 INFO  : metadata sub dir: Set directory modification time (using DirSetModTime)
2026/07/10 03:01:49 INFO  : empty metadata sub dir: Making directory
2026/07/10 03:01:49 INFO  : empty metadata sub dir: Made directory with modification time 2011-12-25 12:59:59.123456789 +0000 UTC
2026/07/10 03:01:49 INFO  : metadata sub dir: Making directory
2026/07/10 03:01:49 INFO  : metadata sub dir: Made directory with modification time 2011-12-25 12:59:59.123456789 +0000 UTC
2026/07/10 03:01:49 DEBUG : metadata sub dir/hello metadata world: Need to transfer - File not found at Destination
2026/07/10 03:01:49 DEBUG : pixeldrain root 'rclone-test-cajirar6bibi': Waiting for checks to finish
2026/07/10 03:01:49 DEBUG : pixeldrain root 'rclone-test-cajirar6bibi': Waiting for transfers to finish
2026/07/10 03:01:49 DEBUG : metadata sub dir/hello metadata world: size = 21 OK
2026/07/10 03:01:49 DEBUG : metadata sub dir/hello metadata world: sha256 = 64d5652158c99e4dd08097075e4e76b287f3b63667c0968c7867720a543c6916 OK
2026/07/10 03:01:49 INFO  : metadata sub dir/hello metadata world: Copied (new)
--- PASS: TestCopyMetadata (0.52s)
=== RUN   TestCopyMetadataNoEmptyDirs
    run.go:198: Remote "pixeldrain root 'rclone-test-cajirar6bibi'", Local "Local file system at /tmp/rclone2733966209", Modify Window "1ms"
2026/07/10 03:01:49 DEBUG : metadata sub dir: Making directory with metadata
2026/07/10 03:01:49 INFO  : metadata sub dir: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z)
2026/07/10 03:01:49 DEBUG : empty metadata sub dir: Making directory with metadata
2026/07/10 03:01:49 INFO  : empty metadata sub dir: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z)
2026/07/10 03:01:49 DEBUG : Local file system at /tmp/rclone2733966209: File to upload is small (21 bytes), uploading instead of streaming
2026/07/10 03:01:49 DEBUG : metadata sub dir/hello metadata world: size = 21 OK
2026/07/10 03:01:49 DEBUG : metadata sub dir/hello metadata world: md5 = baa7a013449dbba2b1ed02b5cfb4e1a2 OK
2026/07/10 03:01:49 DEBUG : metadata sub dir/hello metadata world: Size and md5 of src and dst objects identical
2026/07/10 03:01:49 INFO  : metadata sub dir: Set directory modification time (using DirSetModTime)
2026/07/10 03:01:49 DEBUG : Added delayed dir = "empty metadata sub dir", newDst=<nil>
2026/07/10 03:01:49 DEBUG : Added delayed dir = "metadata sub dir", newDst=<nil>
2026/07/10 03:01:49 DEBUG : metadata sub dir/hello metadata world: Need to transfer - File not found at Destination
2026/07/10 03:01:49 DEBUG : pixeldrain root 'rclone-test-cajirar6bibi': Waiting for checks to finish
2026/07/10 03:01:49 DEBUG : pixeldrain root 'rclone-test-cajirar6bibi': Waiting for transfers to finish
2026/07/10 03:01:49 DEBUG : metadata sub dir/hello metadata world: size = 21 OK
2026/07/10 03:01:49 DEBUG : metadata sub dir/hello metadata world: sha256 = 64d5652158c99e4dd08097075e4e76b287f3b63667c0968c7867720a543c6916 OK
2026/07/10 03:01:49 INFO  : metadata sub dir/hello metadata world: Copied (new)
2026/07/10 03:01:49 INFO  : metadata sub dir: Set directory modification time (using DirSetModTime)
--- PASS: TestCopyMetadataNoEmptyDirs (0.38s)
=== RUN   TestCopyEmptyDirectories
    run.go:198: Remote "pixeldrain root 'rclone-test-cajirar6bibi'", Local "Local file system at /tmp/rclone2733966209", Modify Window "1ms"
2026/07/10 03:01:50 DEBUG : sub dir2/sub sub dir2: Making directory with metadata
2026/07/10 03:01:50 INFO  : sub dir2/sub sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z)
2026/07/10 03:01:50 INFO  : sub dir2: Set directory modification time (using DirSetModTime)
2026/07/10 03:01:50 INFO  : sub dir: Set directory modification time (using DirSetModTime)
2026/07/10 03:01:50 INFO  : sub dir2: Making directory
2026/07/10 03:01:50 INFO  : sub dir2: Made directory with modification time 2011-12-25 12:59:59.123456789 +0000 UTC
2026/07/10 03:01:50 INFO  : sub dir: Making directory
2026/07/10 03:01:50 INFO  : sub dir: Made directory with modification time 2001-02-03 04:05:06.499999999 +0000 UTC
2026/07/10 03:01:50 INFO  : sub dir2/sub sub dir2: Making directory
2026/07/10 03:01:50 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2026/07/10 03:01:50 DEBUG : sub dir/hello world: size = 11 OK
2026/07/10 03:01:50 DEBUG : sub dir/hello world: sha256 = b94d27b9934d3e08a52e52d7da7dabfac484efe37a5380ee9088f7ace2efcde9 OK
2026/07/10 03:01:50 INFO  : sub dir/hello world: Copied (new)
2026/07/10 03:01:50 INFO  : sub dir2/sub sub dir2: Made directory with modification time 2011-12-25 12:59:59.123456789 +0000 UTC
2026/07/10 03:01:50 DEBUG : pixeldrain root 'rclone-test-cajirar6bibi': Waiting for checks to finish
2026/07/10 03:01:50 DEBUG : pixeldrain root 'rclone-test-cajirar6bibi': Waiting for transfers to finish
--- PASS: TestCopyEmptyDirectories (0.59s)
=== RUN   TestNothingToTransferWithEmptyDirs
    run.go:198: Remote "pixeldrain root 'rclone-test-cajirar6bibi'", Local "Local file system at /tmp/rclone2733966209", Modify Window "1ms"
2026/07/10 03:01:50 INFO  : sub dir: Set directory modification time (using DirSetModTime)
2026/07/10 03:01:50 INFO  : sub dir: Making directory
2026/07/10 03:01:50 INFO  : sub dir: Made directory with modification time 2011-12-30 12:59:59 +0000 UTC
    fstest.go:127: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:127
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:639
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:391
        	            				/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2906
        	            				/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2977
        	Error:      	Should be true
        	Test:       	TestNothingToTransferWithEmptyDirs
        	Messages:   	sub dir2: Modification time difference too big |-66.350807ms| > 1ms (want 2026-07-10 03:01:50.858649193 +0000 UTC vs got 2026-07-10 03:01:50.925 +0000 UTC) (precision 1ms)
    sync_test.go:2933: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2933
        	            				/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2977
        	Error:      	Should be false
        	Test:       	TestNothingToTransferWithEmptyDirs
        	Messages:   	expected to find no "Set directory modification time" logs, but found one: 2026/07/10 03:01:51 INFO  : sub dir2: Set directory modification time (using DirSetModTime)
        	            	2026/07/10 03:01:52 INFO  : There was nothing to transfer
    sync_test.go:2935: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2935
        	            				/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2977
        	Error:      	Should be false
        	Test:       	TestNothingToTransferWithEmptyDirs
        	Messages:   	expected to find no "directory"-related logs, but found one: 2026/07/10 03:01:51 INFO  : sub dir2: Set directory modification time (using DirSetModTime)
        	            	2026/07/10 03:01:52 INFO  : There was nothing to transfer
2026/07/10 03:01:52 INFO  : sub dir2: Set directory modification time (using DirSetModTime)
2026/07/10 03:01:52 INFO  : sub dir2: Set directory modification time (using DirSetModTime)
2026/07/10 03:01:52 INFO  : sub dirEmpty/sub dirEmpty2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z)
2026/07/10 03:01:52 INFO  : sub dirEmpty: Set directory modification time (using DirSetModTime)
    fstest.go:127: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:127
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:639
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:391
        	            				/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2967
        	            				/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2977
        	Error:      	Should be true
        	Test:       	TestNothingToTransferWithEmptyDirs
        	Messages:   	sub dirEmpty: Modification time difference too big |-127454h1m53.557543211s| > 1ms (want 2011-12-25 12:59:59.123456789 +0000 UTC vs got 2026-07-10 03:01:52.681 +0000 UTC) (precision 1ms)
    fstest.go:127: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:127
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:639
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:391
        	            				/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2967
        	            				/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2977
        	Error:      	Should be true
        	Test:       	TestNothingToTransferWithEmptyDirs
        	Messages:   	sub dirEmpty/sub dirEmpty2: Modification time difference too big |-127454h1m53.644543211s| > 1ms (want 2011-12-25 12:59:59.123456789 +0000 UTC vs got 2026-07-10 03:01:52.768 +0000 UTC) (precision 1ms)
--- FAIL: TestNothingToTransferWithEmptyDirs (3.46s)
=== RUN   TestNothingToTransferWithoutEmptyDirs
    run.go:198: Remote "pixeldrain root 'rclone-test-cajirar6bibi'", Local "Local file system at /tmp/rclone2733966209", Modify Window "1ms"
2026/07/10 03:01:54 INFO  : sub dir: Set directory modification time (using DirSetModTime)
2026/07/10 03:01:54 INFO  : sub dir: Making directory
2026/07/10 03:01:54 INFO  : sub dir: Made directory with modification time 2011-12-30 12:59:59 +0000 UTC
2026/07/10 03:01:55 INFO  : sub dir2: Set directory modification time (using DirSetModTime)
2026/07/10 03:01:55 INFO  : sub dir2: Set directory modification time (using DirSetModTime)
2026/07/10 03:01:55 INFO  : sub dirEmpty/sub dirEmpty2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z)
2026/07/10 03:01:55 INFO  : sub dirEmpty: Set directory modification time (using DirSetModTime)
--- PASS: TestNothingToTransferWithoutEmptyDirs (2.97s)
FAIL
2026/07/10 03:01:57 DEBUG : pixeldrain root 'rclone-test-cajirar6bibi': Purge remote
"./sync.test -test.v -test.timeout 1h0m0s -remote TestPixeldrain: -verbose -test.run '^(TestCopy|TestCopyEmptyDirectories|TestCopyMetadata|TestCopyMetadataNoEmptyDirs|TestNothingToTransferWithEmptyDirs|TestNothingToTransferWithoutEmptyDirs)$'" - Finished ERROR in 8.484607937s (try 5/5): exit status 1: Failed [TestNothingToTransferWithEmptyDirs]