"./operations.test -test.v -test.timeout 1h0m0s -remote TestChunkerChunk50bYandex: -verbose -size-limit 1024 -test.run '^(TestCheckSumDownload|TestDelete|TestListDirSorted|TestLsLong|TestPurge|TestReadFile)$'" - Starting (try 2/5)
2025/01/15 02:05:22 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-qawukap0tiku"
2025/01/15 02:05:22 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/01/15 02:05:22 DEBUG : Creating backend with remote "TestYandex:rclone-test-qawukap0tiku"
2025/01/15 02:05:22 DEBUG : Reset feature "ListR"
2025/01/15 02:05:22 DEBUG : Creating backend with remote "/tmp/rclone1924820586"
=== RUN   TestCheckSumDownload
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-qawukap0tiku'", Local "Local file system at /tmp/rclone1924820586", Modify Window "1ns"
2025/01/15 02:05:22 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-qawukap0tiku/data"
2025/01/15 02:05:22 DEBUG : Creating backend with remote "TestYandex:rclone-test-qawukap0tiku/data"
2025/01/15 02:05:23 DEBUG : Reset feature "ListR"
2025/01/15 02:05:30 INFO  : data/banana.rclone_chunk.001_43e2os: Moved (server-side) to: data/banana
2025/01/15 02:05:36 INFO  : test.sum.rclone_chunk.001_43e8zy: Moved (server-side) to: test.sum
=== RUN   TestCheckSumDownload/subtest1
2025/01/15 02:05:49 INFO  : data/potato.rclone_chunk.001_43elig: Moved (server-side) to: data/potato
2025/01/15 02:05:58 INFO  : test.sum: Deleted
2025/01/15 02:06:00 INFO  : test.sum.rclone_chunk.001_43esfw: Moved (server-side) to: test.sum
=== RUN   TestCheckSumDownload/subtest2
2025/01/15 02:06:12 INFO  : test.sum.rclone_chunk.001_43f6vi: Moved (server-side) to: test.sum.rclone_chunk.001
2025/01/15 02:06:14 INFO  : test.sum.rclone_chunk.002_43f6vi: Moved (server-side) to: test.sum.rclone_chunk.002
2025/01/15 02:06:16 DEBUG : pacer: low level retry 1/10 (error [500 - InternalServerError] Internal Server Error (Ошибка сервера.))
2025/01/15 02:06:16 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2025/01/15 02:06:17 DEBUG : pacer: Reducing sleep to 15ms
2025/01/15 02:06:18 DEBUG : pacer: Reducing sleep to 11.25ms
2025/01/15 02:06:18 DEBUG : pacer: Reducing sleep to 10ms
=== RUN   TestCheckSumDownload/subtest3
2025/01/15 02:06:41 INFO  : test.sum.rclone_chunk.001_43fr1c: Moved (server-side) to: test.sum.rclone_chunk.001
2025/01/15 02:06:43 INFO  : test.sum.rclone_chunk.002_43fr1c: Moved (server-side) to: test.sum.rclone_chunk.002
=== RUN   TestCheckSumDownload/subtest4
2025/01/15 02:07:14 INFO  : test.sum.rclone_chunk.001_43gmkx: Moved (server-side) to: test.sum.rclone_chunk.001
2025/01/15 02:07:16 INFO  : test.sum.rclone_chunk.002_43gmkx: Moved (server-side) to: test.sum.rclone_chunk.002
2025/01/15 02:07:18 INFO  : test.sum.rclone_chunk.003_43gmkx: Moved (server-side) to: test.sum.rclone_chunk.003
=== RUN   TestCheckSumDownload/subtest5
2025/01/15 02:07:53 INFO  : test.sum.rclone_chunk.001_43hja7: Moved (server-side) to: test.sum.rclone_chunk.001
2025/01/15 02:07:55 INFO  : test.sum.rclone_chunk.002_43hja7: Moved (server-side) to: test.sum.rclone_chunk.002
2025/01/15 02:07:58 INFO  : test.sum.rclone_chunk.003_43hja7: Moved (server-side) to: test.sum.rclone_chunk.003
=== RUN   TestCheckSumDownload/subtest6
2025/01/15 02:08:15 INFO  : data/banana: Deleted
2025/01/15 02:08:17 INFO  : data/banana.rclone_chunk.001_43il86: Moved (server-side) to: data/banana
2025/01/15 02:08:26 INFO  : data/potato: Deleted
2025/01/15 02:08:28 INFO  : data/potato.rclone_chunk.001_43iwts: Moved (server-side) to: data/potato
2025/01/15 02:08:34 DEBUG : pacer: low level retry 1/10 (error [500 - InternalServerError] Internal Server Error (Ошибка сервера.))
2025/01/15 02:08:34 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2025/01/15 02:08:35 DEBUG : pacer: Reducing sleep to 15ms
2025/01/15 02:08:36 DEBUG : pacer: Reducing sleep to 11.25ms
2025/01/15 02:08:36 DEBUG : pacer: Reducing sleep to 10ms
2025/01/15 02:08:50 INFO  : test.sum.rclone_chunk.001_43j817: Moved (server-side) to: test.sum.rclone_chunk.001
2025/01/15 02:08:52 INFO  : test.sum.rclone_chunk.002_43j817: Moved (server-side) to: test.sum.rclone_chunk.002
=== RUN   TestCheckSumDownload/subtest7
--- PASS: TestCheckSumDownload (244.99s)
    --- PASS: TestCheckSumDownload/subtest1 (2.39s)
    --- PASS: TestCheckSumDownload/subtest2 (2.28s)
    --- PASS: TestCheckSumDownload/subtest3 (3.83s)
    --- PASS: TestCheckSumDownload/subtest4 (3.95s)
    --- PASS: TestCheckSumDownload/subtest5 (3.94s)
    --- PASS: TestCheckSumDownload/subtest6 (4.07s)
    --- PASS: TestCheckSumDownload/subtest7 (3.59s)
=== RUN   TestListDirSorted
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-qawukap0tiku'", Local "Local file system at /tmp/rclone1924820586", Modify Window "1ns"
2025/01/15 02:09:36 INFO  : a.txt.rclone_chunk.001_43ku0z: Moved (server-side) to: a.txt
2025/01/15 02:09:42 INFO  : zend.txt.rclone_chunk.001_43l2r3: Moved (server-side) to: zend.txt
2025/01/15 02:09:49 INFO  : sub dir/hello world.rclone_chunk.001_43l850: Moved (server-side) to: sub dir/hello world
2025/01/15 02:09:58 INFO  : sub dir/hello world2.rclone_chunk.001_43lffm: Moved (server-side) to: sub dir/hello world2
2025/01/15 02:10:04 INFO  : sub dir/ignore dir/.ignore.rclone_chunk.001_43lp17: Moved (server-side) to: sub dir/ignore dir/.ignore
2025/01/15 02:10:11 INFO  : sub dir/ignore dir/should be ignored.rclone_chunk.001_43lvuh: Moved (server-side) to: sub dir/ignore dir/should be ignored
2025/01/15 02:10:18 INFO  : sub dir/sub sub dir/hello world3.rclone_chunk.001_43m2ii: Moved (server-side) to: sub dir/sub sub dir/hello world3
    fstest.go:121: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:121
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:126
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:145
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:189
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:302
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:344
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:340
        	            				/home/rclone/go/src/github.com/rclone/rclone/fs/operations/listdirsorted_test.go:36
        	Error:      	Should be true
        	Test:       	TestListDirSorted
        	Messages:   	sub dir/hello world: Modification time difference too big |-209926h4m40.500000001s| > 1ns (want 2001-02-03 04:05:06.499999999 +0000 UTC vs got 2025-01-15 02:09:47 +0000 UTC) (precision 1ns)
2025/01/15 02:10:20 DEBUG : a.txt: Excluded (Size Filter)
2025/01/15 02:10:20 DEBUG : a.txt: Excluded
2025/01/15 02:10:21 DEBUG : sub dir/hello world: Excluded (Size Filter)
2025/01/15 02:10:21 DEBUG : sub dir/hello world: Excluded
2025/01/15 02:10:21 DEBUG : sub dir/hello world2: Excluded (Size Filter)
2025/01/15 02:10:21 DEBUG : sub dir/hello world2: Excluded
2025/01/15 02:10:21 DEBUG : sub dir/hello world: Excluded (Size Filter)
2025/01/15 02:10:21 DEBUG : sub dir/hello world: Excluded
2025/01/15 02:10:21 DEBUG : sub dir/hello world2: Excluded (Size Filter)
2025/01/15 02:10:21 DEBUG : sub dir/hello world2: Excluded
2025/01/15 02:10:22 DEBUG : sub dir/ignore dir: Excluded
2025/01/15 02:10:23 DEBUG : sub dir/ignore dir: Excluded
--- FAIL: TestListDirSorted (101.46s)
=== RUN   TestLsLong
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-qawukap0tiku'", Local "Local file system at /tmp/rclone1924820586", Modify Window "1ns"
2025/01/15 02:11:16 INFO  : potato2.rclone_chunk.001_43nnai: Moved (server-side) to: potato2.rclone_chunk.001
2025/01/15 02:11:18 INFO  : potato2.rclone_chunk.002_43nnai: Moved (server-side) to: potato2.rclone_chunk.002
2025/01/15 02:11:35 INFO  : empty space.rclone_chunk.001_43nzns: Moved (server-side) to: empty space
--- PASS: TestLsLong (44.63s)
=== RUN   TestDelete
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-qawukap0tiku'", Local "Local file system at /tmp/rclone1924820586", Modify Window "1ns"
2025/01/15 02:11:59 INFO  : small.rclone_chunk.001_43owvk: Moved (server-side) to: small
2025/01/15 02:12:10 INFO  : medium.rclone_chunk.001_43p1ct: Moved (server-side) to: medium.rclone_chunk.001
2025/01/15 02:12:12 INFO  : medium.rclone_chunk.002_43p1ct: Moved (server-side) to: medium.rclone_chunk.002
2025/01/15 02:12:22 INFO  : large.rclone_chunk.001_43phjp: Moved (server-side) to: large.rclone_chunk.001
2025/01/15 02:12:24 INFO  : large.rclone_chunk.002_43phjp: Moved (server-side) to: large.rclone_chunk.002
2025/01/15 02:12:28 DEBUG : Waiting for deletions to finish
2025/01/15 02:12:29 DEBUG : large: Excluded (Size Filter)
2025/01/15 02:12:29 DEBUG : large: Excluded
2025/01/15 02:12:33 INFO  : small: Deleted
2025/01/15 02:12:42 INFO  : medium: Deleted
--- PASS: TestDelete (62.39s)
=== RUN   TestReadFile
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-qawukap0tiku'", Local "Local file system at /tmp/rclone1924820586", Modify Window "1ns"
2025/01/15 02:13:02 INFO  : ReadFile.rclone_chunk.001_43qmas: Moved (server-side) to: ReadFile
--- PASS: TestReadFile (14.73s)
=== RUN   TestPurge
2025/01/15 02:13:11 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-kiwamaq3vopi"
2025/01/15 02:13:11 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/01/15 02:13:11 DEBUG : Creating backend with remote "TestYandex:rclone-test-kiwamaq3vopi"
2025/01/15 02:13:11 DEBUG : Reset feature "ListR"
2025/01/15 02:13:11 DEBUG : Creating backend with remote "/tmp/rclone475212503"
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-kiwamaq3vopi'", Local "Local file system at /tmp/rclone475212503", Modify Window "1ns"
2025/01/15 02:13:20 INFO  : A1/B1/C1/one.rclone_chunk.001_43r3uj: Moved (server-side) to: A1/B1/C1/one
2025/01/15 02:13:20 INFO  : A2: Making directory
2025/01/15 02:13:21 INFO  : A1/B2: Making directory
2025/01/15 02:13:21 INFO  : A1/B2/C2: Making directory
2025/01/15 02:13:22 INFO  : A1/B1/C3: Making directory
2025/01/15 02:13:22 INFO  : A3: Making directory
2025/01/15 02:13:23 INFO  : A3/B3: Making directory
2025/01/15 02:13:23 INFO  : A3/B3/C4: Making directory
2025/01/15 02:13:30 INFO  : A1/two.rclone_chunk.001_43re4r: Moved (server-side) to: A1/two
2025/01/15 02:13:54 ERROR : : error listing: directory not found
2025/01/15 02:13:54 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-kiwamaq3vopi': Purge remote
2025/01/15 02:13:55 NOTICE: purge failed: [404 - DiskNotFoundError] Resource not found. (Не удалось найти запрошенный ресурс.)
--- PASS: TestPurge (44.06s)
FAIL
2025/01/15 02:13:55 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-qawukap0tiku': Purge remote
"./operations.test -test.v -test.timeout 1h0m0s -remote TestChunkerChunk50bYandex: -verbose -size-limit 1024 -test.run '^(TestCheckSumDownload|TestDelete|TestListDirSorted|TestLsLong|TestPurge|TestReadFile)$'" - Finished ERROR in 8m39.459597259s (try 2/5): exit status 1: Failed [TestListDirSorted]