"./sync.test -test.v -test.timeout 1h0m0s -remote TestPixeldrain: -verbose -test.run '^TestNothingToTransferWithEmptyDirs$'" - Starting (try 3/5)
2026/03/29 04:43:28 DEBUG : Creating backend with remote "TestPixeldrain:rclone-test-lihejis0kasu"
2026/03/29 04:43:28 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/03/29 04:43:28 INFO  : pixeldrain root 'rclone-test-lihejis0kasu': Logged in as 'ncw', subscription 'Prepaid', storage limit -1
2026/03/29 04:43:28 DEBUG : Creating backend with remote "/tmp/rclone3259297382"
=== RUN   TestNothingToTransferWithEmptyDirs
    run.go:185: Remote "pixeldrain root 'rclone-test-lihejis0kasu'", Local "Local file system at /tmp/rclone3259297382", Modify Window "1ms"
2026/03/29 04:43:28 INFO  : sub dir: Set directory modification time (using DirSetModTime)
2026/03/29 04:43:28 INFO  : sub dir: Making directory
2026/03/29 04:43:29 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 |-267.550851ms| > 1ms (want 2026-03-29 04:43:28.958449149 +0000 UTC vs got 2026-03-29 04:43:29.226 +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 |-289.550851ms| > 1ms (want 2026-03-29 04:43:28.958449149 +0000 UTC vs got 2026-03-29 04:43:29.248 +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 |-610.55084ms| > 1ms (want 2026-03-29 04:43:28.95944916 +0000 UTC vs got 2026-03-29 04:43:29.57 +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:30 INFO  : sub dir2/very: Set directory modification time (using DirSetModTime)
        	            	2026/03/29 04:43:30 INFO  : sub dir2/very/very: Set directory modification time (using DirSetModTime)
        	            	2026/03/29 04:43:30 INFO  : sub dir2/very/very/very: Set directory modification time (using DirSetModTime)
        	            	2026/03/29 04:43:30 INFO  : sub dir2/very/very/very/very: Set directory modification time (using DirSetModTime)
        	            	2026/03/29 04:43:30 INFO  : sub dir2/very/very/very/very/very: Set directory modification time (using DirSetModTime)
        	            	2026/03/29 04:43:30 INFO  : sub dir2/very/very/very/very/very/nested: Set directory modification time (using DirSetModTime)
        	            	2026/03/29 04:43:30 INFO  : sub dir2/very/very/very/very/very/nested/subdir: Set directory modification time (using DirSetModTime)
        	            	2026/03/29 04:43:30 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:30 INFO  : sub dir2/very: Set directory modification time (using DirSetModTime)
        	            	2026/03/29 04:43:30 INFO  : sub dir2/very/very: Set directory modification time (using DirSetModTime)
        	            	2026/03/29 04:43:30 INFO  : sub dir2/very/very/very: Set directory modification time (using DirSetModTime)
        	            	2026/03/29 04:43:30 INFO  : sub dir2/very/very/very/very: Set directory modification time (using DirSetModTime)
        	            	2026/03/29 04:43:30 INFO  : sub dir2/very/very/very/very/very: Set directory modification time (using DirSetModTime)
        	            	2026/03/29 04:43:30 INFO  : sub dir2/very/very/very/very/very/nested: Set directory modification time (using DirSetModTime)
        	            	2026/03/29 04:43:30 INFO  : sub dir2/very/very/very/very/very/nested/subdir: Set directory modification time (using DirSetModTime)
        	            	2026/03/29 04:43:30 INFO  : There was nothing to transfer
2026/03/29 04:43:31 INFO  : sub dir2: Set directory modification time (using DirSetModTime)
2026/03/29 04:43:31 INFO  : sub dir2: Set directory modification time (using DirSetModTime)
2026/03/29 04:43:31 INFO  : sub dirEmpty/sub dirEmpty2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z)
2026/03/29 04:43:31 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 |-133.52874ms| > 1ms (want 2026-03-29 04:43:31.05847126 +0000 UTC vs got 2026-03-29 04:43:31.192 +0000 UTC) (precision 1ms)
--- FAIL: TestNothingToTransferWithEmptyDirs (3.83s)
FAIL
2026/03/29 04:43:32 DEBUG : pixeldrain root 'rclone-test-lihejis0kasu': Purge remote
"./sync.test -test.v -test.timeout 1h0m0s -remote TestPixeldrain: -verbose -test.run '^TestNothingToTransferWithEmptyDirs$'" - Finished ERROR in 4.092938993s (try 3/5): exit status 1: Failed [TestNothingToTransferWithEmptyDirs]