"./sync.test -test.v -test.timeout 1h0m0s -remote TestPixeldrain: -verbose -test.run '^TestNothingToTransferWithEmptyDirs$'" - Starting (try 5/5)
2026/03/29 04:43:37 DEBUG : Creating backend with remote "TestPixeldrain:rclone-test-wivifip9nodi"
2026/03/29 04:43:37 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/03/29 04:43:37 INFO  : pixeldrain root 'rclone-test-wivifip9nodi': Logged in as 'ncw', subscription 'Prepaid', storage limit -1
2026/03/29 04:43:37 DEBUG : Creating backend with remote "/tmp/rclone3412659617"
=== RUN   TestNothingToTransferWithEmptyDirs
    run.go:185: Remote "pixeldrain root 'rclone-test-wivifip9nodi'", Local "Local file system at /tmp/rclone3412659617", Modify Window "1ms"
2026/03/29 04:43:37 INFO  : sub dir: Set directory modification time (using DirSetModTime)
2026/03/29 04:43:37 INFO  : sub dir: Making directory
2026/03/29 04:43:37 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 |-257.464458ms| > 1ms (want 2026-03-29 04:43:37.163535542 +0000 UTC vs got 2026-03-29 04:43:37.421 +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 |-281.464458ms| > 1ms (want 2026-03-29 04:43:37.163535542 +0000 UTC vs got 2026-03-29 04:43:37.445 +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 |-602.464458ms| > 1ms (want 2026-03-29 04:43:37.163535542 +0000 UTC vs got 2026-03-29 04:43:37.766 +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:38 INFO  : sub dir2/very: Set directory modification time (using DirSetModTime)
        	            	2026/03/29 04:43:38 INFO  : sub dir2/very/very: Set directory modification time (using DirSetModTime)
        	            	2026/03/29 04:43:38 INFO  : sub dir2/very/very/very: Set directory modification time (using DirSetModTime)
        	            	2026/03/29 04:43:38 INFO  : sub dir2/very/very/very/very: Set directory modification time (using DirSetModTime)
        	            	2026/03/29 04:43:38 INFO  : sub dir2/very/very/very/very/very: Set directory modification time (using DirSetModTime)
        	            	2026/03/29 04:43:38 INFO  : sub dir2/very/very/very/very/very/nested: Set directory modification time (using DirSetModTime)
        	            	2026/03/29 04:43:38 INFO  : sub dir2/very/very/very/very/very/nested/subdir: Set directory modification time (using DirSetModTime)
        	            	2026/03/29 04:43:38 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:38 INFO  : sub dir2/very: Set directory modification time (using DirSetModTime)
        	            	2026/03/29 04:43:38 INFO  : sub dir2/very/very: Set directory modification time (using DirSetModTime)
        	            	2026/03/29 04:43:38 INFO  : sub dir2/very/very/very: Set directory modification time (using DirSetModTime)
        	            	2026/03/29 04:43:38 INFO  : sub dir2/very/very/very/very: Set directory modification time (using DirSetModTime)
        	            	2026/03/29 04:43:38 INFO  : sub dir2/very/very/very/very/very: Set directory modification time (using DirSetModTime)
        	            	2026/03/29 04:43:38 INFO  : sub dir2/very/very/very/very/very/nested: Set directory modification time (using DirSetModTime)
        	            	2026/03/29 04:43:38 INFO  : sub dir2/very/very/very/very/very/nested/subdir: Set directory modification time (using DirSetModTime)
        	            	2026/03/29 04:43:38 INFO  : There was nothing to transfer
2026/03/29 04:43:39 INFO  : sub dir2: Set directory modification time (using DirSetModTime)
2026/03/29 04:43:39 INFO  : sub dir2: Set directory modification time (using DirSetModTime)
2026/03/29 04:43:39 INFO  : sub dirEmpty/sub dirEmpty2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z)
2026/03/29 04:43:39 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 |-132.442756ms| > 1ms (want 2026-03-29 04:43:39.224557244 +0000 UTC vs got 2026-03-29 04:43:39.357 +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 |-124983h43m40.131543211s| > 1ms (want 2011-12-25 12:59:59.123456789 +0000 UTC vs got 2026-03-29 04:43:39.255 +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 |-124983h43m40.192543211s| > 1ms (want 2011-12-25 12:59:59.123456789 +0000 UTC vs got 2026-03-29 04:43:39.316 +0000 UTC) (precision 1ms)
--- FAIL: TestNothingToTransferWithEmptyDirs (3.78s)
FAIL
2026/03/29 04:43:40 DEBUG : pixeldrain root 'rclone-test-wivifip9nodi': Purge remote
"./sync.test -test.v -test.timeout 1h0m0s -remote TestPixeldrain: -verbose -test.run '^TestNothingToTransferWithEmptyDirs$'" - Finished ERROR in 3.997912612s (try 5/5): exit status 1: Failed [TestNothingToTransferWithEmptyDirs]