"./sync.test -test.v -test.timeout 1h0m0s -remote TestPixeldrain: -verbose -test.run '^TestNothingToTransferWithEmptyDirs$'" - Starting (try 4/5)
2026/03/29 04:43:32 DEBUG : Creating backend with remote "TestPixeldrain:rclone-test-gogamug5zido"
2026/03/29 04:43:32 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/03/29 04:43:32 INFO  : pixeldrain root 'rclone-test-gogamug5zido': Logged in as 'ncw', subscription 'Prepaid', storage limit -1
2026/03/29 04:43:33 DEBUG : Creating backend with remote "/tmp/rclone1301284554"
=== RUN   TestNothingToTransferWithEmptyDirs
    run.go:185: Remote "pixeldrain root 'rclone-test-gogamug5zido'", Local "Local file system at /tmp/rclone1301284554", Modify Window "1ms"
2026/03/29 04:43:33 INFO  : sub dir: Set directory modification time (using DirSetModTime)
2026/03/29 04:43:33 INFO  : sub dir: Making directory
2026/03/29 04:43:33 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:378
        	            				/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/very: Modification time difference too big |-270.50805ms| > 1ms (want 2026-03-29 04:43:33.02349195 +0000 UTC vs got 2026-03-29 04:43:33.294 +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:378
        	            				/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/very/very: Modification time difference too big |-292.508039ms| > 1ms (want 2026-03-29 04:43:33.024491961 +0000 UTC vs got 2026-03-29 04:43:33.317 +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:378
        	            				/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/very/very/very/very/very/nested/subdir: Modification time difference too big |-694.508039ms| > 1ms (want 2026-03-29 04:43:33.024491961 +0000 UTC vs got 2026-03-29 04:43:33.719 +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/03/29 04:43:34 INFO  : sub dir2/very: Set directory modification time (using DirSetModTime)
        	            	2026/03/29 04:43:34 INFO  : sub dir2/very/very: Set directory modification time (using DirSetModTime)
        	            	2026/03/29 04:43:34 INFO  : sub dir2/very/very/very: Set directory modification time (using DirSetModTime)
        	            	2026/03/29 04:43:34 INFO  : sub dir2/very/very/very/very: Set directory modification time (using DirSetModTime)
        	            	2026/03/29 04:43:34 INFO  : sub dir2/very/very/very/very/very: Set directory modification time (using DirSetModTime)
        	            	2026/03/29 04:43:34 INFO  : sub dir2/very/very/very/very/very/nested: Set directory modification time (using DirSetModTime)
        	            	2026/03/29 04:43:34 INFO  : sub dir2/very/very/very/very/very/nested/subdir: Set directory modification time (using DirSetModTime)
        	            	2026/03/29 04:43:34 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/03/29 04:43:34 INFO  : sub dir2/very: Set directory modification time (using DirSetModTime)
        	            	2026/03/29 04:43:34 INFO  : sub dir2/very/very: Set directory modification time (using DirSetModTime)
        	            	2026/03/29 04:43:34 INFO  : sub dir2/very/very/very: Set directory modification time (using DirSetModTime)
        	            	2026/03/29 04:43:34 INFO  : sub dir2/very/very/very/very: Set directory modification time (using DirSetModTime)
        	            	2026/03/29 04:43:34 INFO  : sub dir2/very/very/very/very/very: Set directory modification time (using DirSetModTime)
        	            	2026/03/29 04:43:34 INFO  : sub dir2/very/very/very/very/very/nested: Set directory modification time (using DirSetModTime)
        	            	2026/03/29 04:43:34 INFO  : sub dir2/very/very/very/very/very/nested/subdir: Set directory modification time (using DirSetModTime)
        	            	2026/03/29 04:43:34 INFO  : There was nothing to transfer
2026/03/29 04:43:35 INFO  : sub dir2: Set directory modification time (using DirSetModTime)
2026/03/29 04:43:35 INFO  : sub dir2: Set directory modification time (using DirSetModTime)
2026/03/29 04:43:35 INFO  : sub dirEmpty/sub dirEmpty2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z)
2026/03/29 04:43:35 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:378
        	            				/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2965
        	            				/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2977
        	Error:      	Should be true
        	Test:       	TestNothingToTransferWithEmptyDirs
        	Messages:   	sub dir2/sub dir3: Modification time difference too big |-138.484906ms| > 1ms (want 2026-03-29 04:43:35.221515094 +0000 UTC vs got 2026-03-29 04:43:35.36 +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:378
        	            				/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 |-124983h43m36.128543211s| > 1ms (want 2011-12-25 12:59:59.123456789 +0000 UTC vs got 2026-03-29 04:43:35.252 +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:378
        	            				/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 |-124983h43m36.189543211s| > 1ms (want 2011-12-25 12:59:59.123456789 +0000 UTC vs got 2026-03-29 04:43:35.313 +0000 UTC) (precision 1ms)
--- FAIL: TestNothingToTransferWithEmptyDirs (3.92s)
FAIL
2026/03/29 04:43:36 DEBUG : pixeldrain root 'rclone-test-gogamug5zido': Purge remote
"./sync.test -test.v -test.timeout 1h0m0s -remote TestPixeldrain: -verbose -test.run '^TestNothingToTransferWithEmptyDirs$'" - Finished ERROR in 4.156541288s (try 4/5): exit status 1: Failed [TestNothingToTransferWithEmptyDirs]