Нагрузочное тестирование SuperClassic FB3 на Windows 10

Следуя настоятельным рекомендациям (по поводу CORE-5632), занялся очень мутной работой — тестированием оперативной памяти. Каждой планки (8x16GB) по отдельности.

В качестве тестов — сначала (для проформы) MemTest86+ 5.01.

Потом, уже под Windows 10 Pro (1703), группа тестов провайдера, которая использовалась (успешно) для выявления битой памяти ноутбука. FB3 был сконфигурирован как SuperClassic — ну, чтобы памяти побольше ел.

Тесты запускались в 20 потоков. Каждый запуск начинался с чистой базы.

——
Первую планку я мучал около 12 часов. Все хорошо. База выросла до 173 гиг.

Вторую планку я мучал около 20 часов. Все хорошо. База выросла до 261 гига.

Здесь я посмотрел износ системного SSD и вздохнул: +3.5 терры. Это столько данных во временные файлы провайдера записалось. Сама база лежит на десятом рейде из HDD.

Тестирование третьей планки запустил вчера утром, в 8:30 и оставил на ночь. Уже начало надоедать.

Сегодня утром обнаружил что тесты валят ошибки. Ну, думаю, началось. Полез в лог тестов:

[THR:011216] [14.10.2017 03:29:13] ERROR: [octets.003.blob.synch.change_rs.TBL_CS__OCTETS.COL_BLOB.len_65535.bind__ss.block_127] Insert row
1. [LCPI.IBProvider.3]: Ошибка формирования ib-значения входящего параметра. Позиция 1.

I/O ошибка в процессе выполнения операции «WriteFile» для файла «D:\DATABASE\FB_03_0_0\IBP_TEST_FB30_D3.GDB».
В процессе записи в файл произошла ошибка.
Запрошенная операция не может быть завершена из-за ограничения файловой системы
2. [LCPI.IBProvider.3]: Ошибка формирования ib-значения входящего параметра. Позиция 1.

[Подсистема: remote_fb.p13] Запись BLOB: Ошибка записи данных.
3. [LCPI.IBProvider.3]: Ошибка формирования ib-значения входящего параметра. Позиция 1.

Ошибка закрытия BLOB.
4. [LCPI.IBProvider.3]: [IBP.SS] Ошибка выполнения служебного запроса [0].
5. [LCPI.IBProvider.3]: [IBP.SS] [изменение базы] Ошибка добавления ряда.
Неопознанная ошибка

В логе сервера:

HOME4 Sat Oct 14 03:29:12 2017
I/O error during «WriteFile» operation for file «D:\DATABASE\FB_03_0_0\IBP_TEST_FB30_D3.GDB»
Error while trying to write to file
Запрошенная операция не может быть завершена из-за ограничения файловой системы

HOME4 Sat Oct 14 03:29:13 2017
Error extending file «D:\DATABASE\FB_03_0_0\IBP_TEST_FB30_D3.GDB» by 33 page(s).
Currently allocated 18535616 pages, requested page number 18535648

HOME4 Sat Oct 14 03:29:13 2017
I/O error during «WriteFile» operation for file «D:\DATABASE\FB_03_0_0\IBP_TEST_FB30_D3.GDB»
Error while trying to write to file
Запрошенная операция не может быть завершена из-за ограничения файловой системы

HOME4 Sat Oct 14 03:29:13 2017
Error extending file «D:\DATABASE\FB_03_0_0\IBP_TEST_FB30_D3.GDB» by 49 page(s).
Currently allocated 18535632 pages, requested page number 18535680

HOME4 Sat Oct 14 03:29:13 2017
I/O error during «WriteFile» operation for file «D:\DATABASE\FB_03_0_0\IBP_TEST_FB30_D3.GDB»
Error while trying to write to file
Запрошенная операция не может быть завершена из-за ограничения файловой системы

HOME4 Sat Oct 14 03:29:13 2017
Error extending file «D:\DATABASE\FB_03_0_0\IBP_TEST_FB30_D3.GDB» by 64 page(s).
Currently allocated 18535633 pages, requested page number 18535696

HOME4 Sat Oct 14 03:29:13 2017
I/O error during «WriteFile» operation for file «D:\DATABASE\FB_03_0_0\IBP_TEST_FB30_D3.GDB»
Error while trying to write to file
Запрошенная операция не может быть завершена из-за ограничения файловой системы

HOME4 Sat Oct 14 03:29:13 2017
I/O error during «WriteFile» operation for file «D:\DATABASE\FB_03_0_0\IBP_TEST_FB30_D3.GDB»
Error while trying to write to file
Запрошенная операция не может быть завершена из-за ограничения файловой системы

База выросла до 282 гиг (303’687’811’072 байт).

На Windows Vista базы вырастали до 1.8 терры и ничего. А тут — на тебе.

Так что вот.

—
А, ну да. С добрым утром.

—
UPD [2017-10-15]. Четвертая планка. Прошли сутки тестирования. Полет нормальный. База 345GB. Решил тестировать её дальше. С тоской посмотрел сколько сейчас стоит комплект памяти на 64GB …

Dmitry Kovalenko on 14 октября, 2017 | Filed Under Firebird Server, IBProvider

12 комментариев

Dmitry Kovalenko  on 15 октября, 2017

Мы продолжаем репортаж с нашей галеры. База выросла до 440 гиг (473’047’302’144 байт). И снова полезли ошибки:

HOME4 Sun Oct 15 17:25:01 2017
I/O error during «WriteFile» operation for file «D:\DATABASE\FB_03_0_0\IBP_TEST_FB30_D3.GDB»
Error while trying to write to file
Запрошенная операция не может быть завершена из-за ограничения файловой системы

В этот раз полтора дня сервер продержался.

Есть два вопроса.

1. Кто виноват?

2. Что делать?

hvlad  on 15 октября, 2017

Файловая система — NTFS ? Какой размер кластера ?

Dmitry Kovalenko  on 15 октября, 2017

Привет

C:\WINDOWS\system32>fsutil fsinfo ntfsinfo d:
NTFS Volume Serial Number : 0x9e3a750d3a74e3a5
NTFS Version : 3.1
LFS Version : 2.0
Number Sectors : 0x00000001d1698fff
Total Clusters : 0x000000003a2d31ff
Free Clusters : 0x000000001195be73
Total Reserved : 0x00000000000076b2
Bytes Per Sector : 512
Bytes Per Physical Sector : 512
Bytes Per Cluster : 4096
Bytes Per FileRecord Segment : 1024

То есть кластер — 4KB.

Это 10-ый рейд (хотя контроллер называет его 6-ым) из четырех 2TB дисков. Рейд контроллер — RS2BL040. Я обновлял у него FW где-то полгода назад.

Win10 обновлен до упора.

—
Сжатие не включено.

В атрибутах только «файл готов для архивирования» и «разрешено индексирование».

Свободного места (на момент возникновения проблемы) — больше 0.5TB.

Dmitry Kovalenko  on 15 октября, 2017

«heavily fragmented file»

Я вот сейчас понял одну вещь. У меня на Vista был отдельный раздел под тестовые базы.

А тут у меня все перемешано в кучу. Вполне возможно что раздел (D) жутко дефрагментирован. Я же на нем и пересобираю и виртуальные машины держу.

Во блин 🙁

hvlad  on 15 октября, 2017

Я бы кластер меньше 8К не делал.
4К видимо по умолчанию было.

Dmitry Kovalenko  on 16 октября, 2017

Вчера был немного отмороженный (сегодня не лучше) — забыл сказать спасибо за информацию.

Решил повторно протестировать первую планку (она меньше всего тестировалась) — надо убедиться, что с ней тоже такая фигня получится 🙂

Dmitry Kovalenko  on 16 октября, 2017

Дождался результатов повторного тестирования первой планки. То же самое: «Запрошенная операция не может быть завершена …».

В этот раз я даже обрадовался этой проблеме.

Запустил анализ дефрагментации раздела — говорит «Needs optimization (48% fragmented)» 🙂

Dmitry Kovalenko  on 16 октября, 2017

Запустил дефрагментацию этого раздела.

Сижу, втыкаюсь в окно дефрагментатора.

И вдруг вижу что Windows параллельно запустил дефрагментацию системного SSD!

Я настолько опупел, что даже не допер сделать screenshot.

Зато нашел в сети подтверждение увиденному — Microsoft признала дефрагментацию SSD в Windows 8+ и объяснила причину.

Там есть такой абзац:

Кроме того, существует ошибочное мнение, что фрагментация не является проблемой для SSD. Если SSD сильно фрагментирован, можно упереться в потолок фрагментации файлов (когда метаданные не могут отразить больше фрагментов), что приведет к ошибкам при записи/расширении файла.

Признаюсь — я тоже думал что дефрагментация SSD не нужна и даже вредна.

Dmitry Kovalenko  on 20 октября, 2017

Добрался до предпоследней планки.

Послышался первый стук. Правда не понятно с какой стороны.

Два теста вернули ошибки:

[THR:003116] [19.10.2017 11:05:43] ERROR: [octets.002.array.rw_direct.synch.rw_change_no_defer.change_rs.VARCHAR_ARRAY__32.write_array_variant.read_variant.truncate_char_true.array_type_0] select record with wrong id: I8[56722636]. Wait: 56722635.

 

[THR:003224] [19.10.2017 20:59:10] ERROR: [octets.002.array.rw_direct.synch.rw_change_no_defer.cmd.VARCHAR_ARRAY__32.write_array_variant.read_array_variant.truncate_char_true.array_type_17] Wrong number of deleted records: 255. Wait: 256. IDs range [324456865..324457120].

Выполнил запросы (с использованием индекса и без <капец как долго>) — действительно, двух записей нет.

Во втором случае — test_id=324457058.

—
Пришла в голову мысль накатать свой простенький тест памяти и начать гонять его 🙂

Про тестирование оперативной памяти  on 21 октября, 2017

[…] качестве подопытного кролика была планка на 16GB, с которой возникла заминка (см. мой комментарий за […]

Dmitry Kovalenko  on 22 октября, 2017

Закончил тестирование.

Если не считать вышеобозначенных проблем с предпоследней планкой, ничего не нашлось.

Собрал все назад как было. Единственное изменение — обнаружил и содрал лавсановые пленки с эмблем на планках памяти.

Из сделанных выводов — нужно будет улучшать систему диагностики проблем тестовой системы.

Leave a Comment