"./vfs.test -test.v -test.timeout 1h0m0s -remote TestPikPak: -verbose -test.run '^(TestCaseSensitivity|TestFileOpenRead|TestUnicodeNormalization)$'" - Starting (try 3/5)
2024/12/15 06:33:11 DEBUG : Creating backend with remote "TestPikPak:rclone-test-murozel3qive"
2024/12/15 06:33:11 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2024/12/15 06:33:12 DEBUG : Creating backend with remote "/tmp/rclone434836602"
=== RUN   TestFileOpenRead
    run.go:180: Remote "PikPak root 'rclone-test-murozel3qive'", Local "Local file system at /tmp/rclone434836602", Modify Window "876000h0m0s"
2024/12/15 06:33:12 INFO  : PikPak root 'rclone-test-murozel3qive': poll-interval is not supported by this remote
2024/12/15 06:33:12 NOTICE: PikPak root 'rclone-test-murozel3qive': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
2024/12/15 06:33:14 DEBUG : dir/file1: calculating gcid: failed to unwrap object from src: dir/file1
2024/12/15 06:33:14 DEBUG : dir/file1: gcid = 3823e2b83a3ba8d1137efc1fe13e3a2e7cfb8d82
2024/12/15 06:33:15 DEBUG : dir/file1: Open: flags=O_RDONLY
2024/12/15 06:33:15 DEBUG : dir/file1: >Open: fd=dir/file1 (r), err=<nil>
2024/12/15 06:33:15 DEBUG : dir/file1: ChunkedReader.openRange at 0 length 134217728
2024/12/15 06:33:18 DEBUG : dir/file1: ChunkedReader.Read at 0 length 512 chunkOffset 0 chunkSize 134217728
2024/12/15 06:33:18 DEBUG : WaitForWriters: timeout=30s
2024/12/15 06:33:18 DEBUG : dir: Looking for writers
2024/12/15 06:33:18 DEBUG : file1: reading active writers
2024/12/15 06:33:18 DEBUG : : Looking for writers
2024/12/15 06:33:18 DEBUG : dir: reading active writers
2024/12/15 06:33:18 DEBUG : >WaitForWriters: 
2024/12/15 06:33:20 DEBUG : dir: Rmdir: contains trashed file: "file1"
--- PASS: TestFileOpenRead (8.84s)
=== RUN   TestCaseSensitivity
    run.go:180: Remote "PikPak root 'rclone-test-murozel3qive'", Local "Local file system at /tmp/rclone434836602", Modify Window "876000h0m0s"
2024/12/15 06:33:21 DEBUG : FiLeA: calculating gcid: failed to unwrap object from src: FiLeA
2024/12/15 06:33:21 DEBUG : FiLeA: gcid = ed1267439bbc430e2a3c002a4a39ac8869a858d5
2024/12/15 06:33:22 DEBUG : FiLeB: calculating gcid: failed to unwrap object from src: FiLeB
2024/12/15 06:33:22 DEBUG : FiLeB: gcid = c0a8962153624d521cfc28e9e9437c2a7ddddffd
2024/12/15 06:33:23 DEBUG : FilEb: calculating gcid: failed to unwrap object from src: FilEb
2024/12/15 06:33:23 DEBUG : FilEb: gcid = d9d37e3253e48da9b2bbbe117f2ddc5b8654d660
2024/12/15 06:33:23 INFO  : PikPak root 'rclone-test-murozel3qive': poll-interval is not supported by this remote
2024/12/15 06:33:23 NOTICE: PikPak root 'rclone-test-murozel3qive': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
2024/12/15 06:33:23 INFO  : PikPak root 'rclone-test-murozel3qive': poll-interval is not supported by this remote
2024/12/15 06:33:23 NOTICE: PikPak root 'rclone-test-murozel3qive': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
2024/12/15 06:33:23 DEBUG : FiLeA: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx
2024/12/15 06:33:24 DEBUG : FiLeA: Open: flags=O_RDONLY
2024/12/15 06:33:24 DEBUG : FiLeA: >Open: fd=FiLeA (r), err=<nil>
2024/12/15 06:33:24 DEBUG : FiLeA: >OpenFile: fd=FiLeA (r), err=<nil>
2024/12/15 06:33:24 DEBUG : FiLeA: ChunkedReader.openRange at 0 length 134217728
2024/12/15 06:33:26 DEBUG : FiLeA: ChunkedReader.Read at 0 length 5 chunkOffset 0 chunkSize 134217728
2024/12/15 06:33:26 DEBUG : FiLeA: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx
2024/12/15 06:33:26 DEBUG : FiLeA: Open: flags=O_RDONLY
2024/12/15 06:33:26 DEBUG : FiLeA: >Open: fd=FiLeA (r), err=<nil>
2024/12/15 06:33:26 DEBUG : FiLeA: >OpenFile: fd=FiLeA (r), err=<nil>
2024/12/15 06:33:26 DEBUG : FiLeA: ChunkedReader.openRange at 0 length 134217728
2024/12/15 06:33:29 DEBUG : FiLeA: ChunkedReader.Read at 0 length 5 chunkOffset 0 chunkSize 134217728
2024/12/15 06:33:29 DEBUG : FiLeA: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx
2024/12/15 06:33:29 DEBUG : FiLeA: Open: flags=O_RDONLY
2024/12/15 06:33:29 DEBUG : FiLeA: >Open: fd=FiLeA (r), err=<nil>
2024/12/15 06:33:29 DEBUG : FiLeA: >OpenFile: fd=FiLeA (r), err=<nil>
2024/12/15 06:33:29 DEBUG : FiLeA: ChunkedReader.openRange at 0 length 134217728
2024/12/15 06:33:29 DEBUG : FiLeA: ChunkedReader.Read at 0 length 5 chunkOffset 0 chunkSize 134217728
2024/12/15 06:33:29 DEBUG : FiLeB: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx
2024/12/15 06:33:29 DEBUG : FiLeB: Open: flags=O_RDONLY
2024/12/15 06:33:29 DEBUG : FiLeB: >Open: fd=FiLeB (r), err=<nil>
2024/12/15 06:33:29 DEBUG : FiLeB: >OpenFile: fd=FiLeB (r), err=<nil>
2024/12/15 06:33:29 DEBUG : FiLeB: ChunkedReader.openRange at 0 length 134217728
2024/12/15 06:33:32 DEBUG : FiLeB: ChunkedReader.Read at 0 length 5 chunkOffset 0 chunkSize 134217728
2024/12/15 06:33:32 DEBUG : FilEb: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx
2024/12/15 06:33:32 DEBUG : FilEb: Open: flags=O_RDONLY
2024/12/15 06:33:32 DEBUG : FilEb: >Open: fd=FilEb (r), err=<nil>
2024/12/15 06:33:32 DEBUG : FilEb: >OpenFile: fd=FilEb (r), err=<nil>
2024/12/15 06:33:32 DEBUG : FilEb: ChunkedReader.openRange at 0 length 134217728
2024/12/15 06:33:32 DEBUG : FilEb: ChunkedReader.Read at 0 length 5 chunkOffset 0 chunkSize 134217728
2024/12/15 06:33:33 DEBUG : FiLeA: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx
2024/12/15 06:33:33 DEBUG : FiLeA: Open: flags=O_RDONLY
2024/12/15 06:33:33 DEBUG : FiLeA: >Open: fd=FiLeA (r), err=<nil>
2024/12/15 06:33:33 DEBUG : FiLeA: >OpenFile: fd=FiLeA (r), err=<nil>
2024/12/15 06:33:33 DEBUG : FiLeA: ChunkedReader.openRange at 0 length 134217728
2024/12/15 06:33:33 DEBUG : FiLeA: ChunkedReader.Read at 0 length 5 chunkOffset 0 chunkSize 134217728
2024/12/15 06:33:33 DEBUG : fileA: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx
2024/12/15 06:33:33 DEBUG : FiLeA: Open: flags=O_RDONLY
2024/12/15 06:33:33 DEBUG : FiLeA: >Open: fd=FiLeA (r), err=<nil>
2024/12/15 06:33:33 DEBUG : fileA: >OpenFile: fd=FiLeA (r), err=<nil>
2024/12/15 06:33:33 DEBUG : FiLeA: ChunkedReader.openRange at 0 length 134217728
2024/12/15 06:33:33 DEBUG : FiLeA: ChunkedReader.Read at 0 length 5 chunkOffset 0 chunkSize 134217728
2024/12/15 06:33:33 DEBUG : filea: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx
2024/12/15 06:33:33 DEBUG : FiLeA: Open: flags=O_RDONLY
2024/12/15 06:33:33 DEBUG : FiLeA: >Open: fd=FiLeA (r), err=<nil>
2024/12/15 06:33:33 DEBUG : filea: >OpenFile: fd=FiLeA (r), err=<nil>
2024/12/15 06:33:33 DEBUG : FiLeA: ChunkedReader.openRange at 0 length 134217728
2024/12/15 06:33:34 DEBUG : FiLeA: ChunkedReader.Read at 0 length 5 chunkOffset 0 chunkSize 134217728
2024/12/15 06:33:34 DEBUG : FILEA: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx
2024/12/15 06:33:34 DEBUG : FiLeA: Open: flags=O_RDONLY
2024/12/15 06:33:34 DEBUG : FiLeA: >Open: fd=FiLeA (r), err=<nil>
2024/12/15 06:33:34 DEBUG : FILEA: >OpenFile: fd=FiLeA (r), err=<nil>
2024/12/15 06:33:34 DEBUG : FiLeA: ChunkedReader.openRange at 0 length 134217728
2024/12/15 06:33:34 DEBUG : FiLeA: ChunkedReader.Read at 0 length 5 chunkOffset 0 chunkSize 134217728
2024/12/15 06:33:34 DEBUG : FiLeB: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx
2024/12/15 06:33:34 DEBUG : FiLeB: Open: flags=O_RDONLY
2024/12/15 06:33:34 DEBUG : FiLeB: >Open: fd=FiLeB (r), err=<nil>
2024/12/15 06:33:34 DEBUG : FiLeB: >OpenFile: fd=FiLeB (r), err=<nil>
2024/12/15 06:33:34 DEBUG : FiLeB: ChunkedReader.openRange at 0 length 134217728
2024/12/15 06:33:34 DEBUG : FiLeB: ChunkedReader.Read at 0 length 5 chunkOffset 0 chunkSize 134217728
2024/12/15 06:33:34 DEBUG : FilEb: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx
2024/12/15 06:33:34 DEBUG : FilEb: Open: flags=O_RDONLY
2024/12/15 06:33:34 DEBUG : FilEb: >Open: fd=FilEb (r), err=<nil>
2024/12/15 06:33:34 DEBUG : FilEb: >OpenFile: fd=FilEb (r), err=<nil>
2024/12/15 06:33:34 DEBUG : FilEb: ChunkedReader.openRange at 0 length 134217728
2024/12/15 06:33:35 DEBUG : FilEb: ChunkedReader.Read at 0 length 5 chunkOffset 0 chunkSize 134217728
2024/12/15 06:33:35 DEBUG : fileb: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx
2024/12/15 06:33:35 ERROR : /: Dir.Stat error: duplicate filename "fileb" detected with case/unicode normalization settings
2024/12/15 06:33:35 DEBUG : fileb: >OpenFile: fd=<nil>, err=duplicate filename "fileb" detected with case/unicode normalization settings
2024/12/15 06:33:35 DEBUG : FILEB: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx
2024/12/15 06:33:35 ERROR : /: Dir.Stat error: duplicate filename "FILEB" detected with case/unicode normalization settings
2024/12/15 06:33:35 DEBUG : FILEB: >OpenFile: fd=<nil>, err=duplicate filename "FILEB" detected with case/unicode normalization settings
2024/12/15 06:33:35 DEBUG : FiLeA: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx
2024/12/15 06:33:35 DEBUG : FiLeA: Open: flags=O_RDONLY
2024/12/15 06:33:35 DEBUG : FiLeA: >Open: fd=FiLeA (r), err=<nil>
2024/12/15 06:33:35 DEBUG : FiLeA: >OpenFile: fd=FiLeA (r), err=<nil>
2024/12/15 06:33:35 DEBUG : FiLeA: ChunkedReader.openRange at 0 length 134217728
2024/12/15 06:33:36 DEBUG : FiLeA: ChunkedReader.Read at 0 length 5 chunkOffset 0 chunkSize 134217728
2024/12/15 06:33:36 DEBUG : fileA: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx
2024/12/15 06:33:36 DEBUG : fileA: >OpenFile: fd=<nil>, err=file does not exist
2024/12/15 06:33:36 DEBUG : filea: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx
2024/12/15 06:33:36 DEBUG : filea: >OpenFile: fd=<nil>, err=file does not exist
2024/12/15 06:33:36 DEBUG : FILEA: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx
2024/12/15 06:33:36 DEBUG : FILEA: >OpenFile: fd=<nil>, err=file does not exist
2024/12/15 06:33:36 DEBUG : FiLeB: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx
2024/12/15 06:33:36 DEBUG : FiLeB: Open: flags=O_RDONLY
2024/12/15 06:33:36 DEBUG : FiLeB: >Open: fd=FiLeB (r), err=<nil>
2024/12/15 06:33:36 DEBUG : FiLeB: >OpenFile: fd=FiLeB (r), err=<nil>
2024/12/15 06:33:36 DEBUG : FiLeB: ChunkedReader.openRange at 0 length 134217728
2024/12/15 06:33:36 DEBUG : FiLeB: ChunkedReader.Read at 0 length 5 chunkOffset 0 chunkSize 134217728
2024/12/15 06:33:36 DEBUG : FilEb: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx
2024/12/15 06:33:36 DEBUG : FilEb: Open: flags=O_RDONLY
2024/12/15 06:33:36 DEBUG : FilEb: >Open: fd=FilEb (r), err=<nil>
2024/12/15 06:33:36 DEBUG : FilEb: >OpenFile: fd=FilEb (r), err=<nil>
2024/12/15 06:33:36 DEBUG : FilEb: ChunkedReader.openRange at 0 length 134217728
2024/12/15 06:33:36 DEBUG : FilEb: ChunkedReader.Read at 0 length 5 chunkOffset 0 chunkSize 134217728
2024/12/15 06:33:36 DEBUG : fileb: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx
2024/12/15 06:33:36 DEBUG : fileb: >OpenFile: fd=<nil>, err=file does not exist
2024/12/15 06:33:36 DEBUG : FILEB: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx
2024/12/15 06:33:36 DEBUG : FILEB: >OpenFile: fd=<nil>, err=file does not exist
2024/12/15 06:33:36 DEBUG : WaitForWriters: timeout=30s
2024/12/15 06:33:36 DEBUG : : Looking for writers
2024/12/15 06:33:36 DEBUG : FilEb: reading active writers
2024/12/15 06:33:36 DEBUG : FiLeA: reading active writers
2024/12/15 06:33:36 DEBUG : FiLeB: reading active writers
2024/12/15 06:33:36 DEBUG : >WaitForWriters: 
2024/12/15 06:33:36 DEBUG : WaitForWriters: timeout=30s
2024/12/15 06:33:36 DEBUG : : Looking for writers
2024/12/15 06:33:36 DEBUG : FiLeA: reading active writers
2024/12/15 06:33:36 DEBUG : FiLeB: reading active writers
2024/12/15 06:33:36 DEBUG : FilEb: reading active writers
2024/12/15 06:33:36 DEBUG : >WaitForWriters: 
--- PASS: TestCaseSensitivity (19.04s)
=== RUN   TestUnicodeNormalization
    run.go:180: Remote "PikPak root 'rclone-test-murozel3qive'", Local "Local file system at /tmp/rclone434836602", Modify Window "876000h0m0s"
2024/12/15 06:33:40 DEBUG : normal name with no special characters.txt: calculating gcid: failed to unwrap object from src: normal name with no special characters.txt
2024/12/15 06:33:40 DEBUG : normal name with no special characters.txt: gcid = ed1267439bbc430e2a3c002a4a39ac8869a858d5
2024/12/15 06:33:41 DEBUG : 測試_Русский___ě_áñ: calculating gcid: failed to unwrap object from src: 測試_Русский___ě_áñ
2024/12/15 06:33:41 DEBUG : 測試_Русский___ě_áñ: gcid = c0a8962153624d521cfc28e9e9437c2a7ddddffd
2024/12/15 06:33:42 INFO  : PikPak root 'rclone-test-murozel3qive': poll-interval is not supported by this remote
2024/12/15 06:33:42 NOTICE: PikPak root 'rclone-test-murozel3qive': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
2024/12/15 06:33:42 DEBUG : normal name with no special characters.txt: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx
2024/12/15 06:33:42 DEBUG : normal name with no special characters.txt: Open: flags=O_RDONLY
2024/12/15 06:33:42 DEBUG : normal name with no special characters.txt: >Open: fd=normal name with no special characters.txt (r), err=<nil>
2024/12/15 06:33:42 DEBUG : normal name with no special characters.txt: >OpenFile: fd=normal name with no special characters.txt (r), err=<nil>
2024/12/15 06:33:42 DEBUG : normal name with no special characters.txt: ChunkedReader.openRange at 0 length 134217728
2024/12/15 06:33:43 DEBUG : normal name with no special characters.txt: ChunkedReader.Read at 0 length 5 chunkOffset 0 chunkSize 134217728
2024/12/15 06:33:43 DEBUG : 測試_Русский___ě_áñ: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx
2024/12/15 06:33:43 DEBUG : 測試_Русский___ě_áñ: Open: flags=O_RDONLY
2024/12/15 06:33:43 DEBUG : 測試_Русский___ě_áñ: >Open: fd=測試_Русский___ě_áñ (r), err=<nil>
2024/12/15 06:33:43 DEBUG : 測試_Русский___ě_áñ: >OpenFile: fd=測試_Русский___ě_áñ (r), err=<nil>
2024/12/15 06:33:43 DEBUG : 測試_Русский___ě_áñ: ChunkedReader.openRange at 0 length 134217728
2024/12/15 06:33:43 DEBUG : 測試_Русский___ě_áñ: ChunkedReader.Read at 0 length 5 chunkOffset 0 chunkSize 134217728
2024/12/15 06:33:43 DEBUG : normal name with no special characters.txt: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx
2024/12/15 06:33:43 DEBUG : normal name with no special characters.txt: Open: flags=O_RDONLY
2024/12/15 06:33:43 DEBUG : normal name with no special characters.txt: >Open: fd=normal name with no special characters.txt (r), err=<nil>
2024/12/15 06:33:43 DEBUG : normal name with no special characters.txt: >OpenFile: fd=normal name with no special characters.txt (r), err=<nil>
2024/12/15 06:33:43 DEBUG : normal name with no special characters.txt: ChunkedReader.openRange at 0 length 134217728
2024/12/15 06:33:44 DEBUG : normal name with no special characters.txt: ChunkedReader.Read at 0 length 5 chunkOffset 0 chunkSize 134217728
2024/12/15 06:33:44 DEBUG : 測試_Русский___ě_áñ: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx
2024/12/15 06:33:44 DEBUG : 測試_Русский___ě_áñ: >OpenFile: fd=<nil>, err=file does not exist
2024/12/15 06:33:44 DEBUG : WaitForWriters: timeout=30s
2024/12/15 06:33:44 DEBUG : : Looking for writers
2024/12/15 06:33:44 DEBUG : normal name with no special characters.txt: reading active writers
2024/12/15 06:33:44 DEBUG : 測試_Русский___ě_áñ: reading active writers
2024/12/15 06:33:44 DEBUG : >WaitForWriters: 
--- PASS: TestUnicodeNormalization (6.19s)
PASS
2024/12/15 06:33:46 DEBUG : PikPak root 'rclone-test-murozel3qive': Purge remote
"./vfs.test -test.v -test.timeout 1h0m0s -remote TestPikPak: -verbose -test.run '^(TestCaseSensitivity|TestFileOpenRead|TestUnicodeNormalization)$'" - Finished OK in 36.525948237s (try 3/5)