Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 18.07.2022 13:38
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15606824 kB
MemAvailable 31060452 kB
Buffers 0 kB
Cached 16078496 kB
SwapCached 2488 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.2
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3400
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-07-18 13:37:19
DTS_END                         2022-07-18 13:37:19.6420
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/45862
TRN_ID                          219294
ATT_ID                          643
EXC_UNIT                        2


Test workload details

SETTING_NAME SETTING_VALUE
WORKING_MODE SMALL_03
C_WARES_MAX_ID 400
C_CUSTOMER_DOC_MAX_ROWS 10
C_SUPPLIER_DOC_MAX_ROWS 50
C_CUSTOMER_DOC_MAX_QTY 15
C_SUPPLIER_DOC_MAX_QTY 50
C_NUMBER_OF_AGENTS 50

Indexes for heavy-loaded tables

TAB_NAME IDX_NAME IDX_KEY
XQD_1000_1200 XQD_1000_1200_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_ASC WORKER_ID,DOC_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_DEC WORKER_ID,DOC_ID
XQD_1200_2000 XQD_1200_2000_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2000_3300 XQD_2000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2100_3300 XQD_2100_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_3300_3400 XQD_3300_3400_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID

Performance in TOTAL

ACTION AVG_TIMES_PER_MINUTE AVG_ELAPSED_MS SUCCESSFUL_TIMES_DONE
*** OVERALL *** for 120 minutes: 9139.14 25201 1096697
customer order: creation 172.71 497 20725
customer order: refuse 14.96 489 1795
order to supplier: creation 45.80 1479 5496
order to supplier: removal 3.40 192880 408
invoice (draft): creation 44.15 3356 5298
invoice (draft): removal 52.13 1026 6256
invoice accept: apply 40.58 28014 4869
invoice accept: cancel 47.24 1299 5669
customer reserve: creation 3812.01 262 457441
customer reserve: removal 4614.42 110 553730
realization accept: apply 82.24 305 9869
realization accept: cancel 79.26 188 9511
payment from customer: creation 58.58 200 7030
payment from customer: removal 5.73 154 687
payment to supplier: creation 49.15 231 5898
payment to supplier: removal 3.42 134 410
service: total inventory turnovers 4.08 4347 490
service: total monetary turnovers 8.32 369 999
service: aggregate perf. data 0.92 16779 110
service: refresh index statistics 0.05 251891 6

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 18-07-2022 13:38:36 to 18-07-2022 13:38:38.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4796
TEST_TIME 2 4263
TEST_TIME 3 4495
TEST_TIME 4 4352
TEST_TIME 5 4580
TEST_TIME 6 4970
TEST_TIME 7 5383
TEST_TIME 8 6104
TEST_TIME 9 5963
TEST_TIME 10 6098
TEST_TIME 11 6434
TEST_TIME 12 6352
TEST_TIME 13 6624
TEST_TIME 14 6788
TEST_TIME 15 6933
TEST_TIME 16 6979
TEST_TIME 17 7035
TEST_TIME 18 7099
TEST_TIME 19 7040
TEST_TIME 20 6985
TEST_TIME 21 7057
TEST_TIME 22 7373
TEST_TIME 23 7443
TEST_TIME 24 7667
TEST_TIME 25 7810
TEST_TIME 26 7873
TEST_TIME 27 7836
TEST_TIME 28 7863
TEST_TIME 29 7864
TEST_TIME 30 8315
TEST_TIME 31 8904
TEST_TIME 32 9120
TEST_TIME 33 9302
TEST_TIME 34 9618
TEST_TIME 35 10207
TEST_TIME 36 10645
TEST_TIME 37 11020
TEST_TIME 38 10947
TEST_TIME 39 10847
TEST_TIME 40 10755
TEST_TIME 41 10737
TEST_TIME 42 10626
TEST_TIME 43 10563
TEST_TIME 44 10596
TEST_TIME 45 10458
TEST_TIME 46 10285
TEST_TIME 47 10253
TEST_TIME 48 10195
TEST_TIME 49 10158
TEST_TIME 50 9988
TEST_TIME 51 9982
TEST_TIME 52 10007
TEST_TIME 53 9957
TEST_TIME 54 9898
TEST_TIME 55 9967
TEST_TIME 56 9869
TEST_TIME 57 9809
TEST_TIME 58 9800
TEST_TIME 59 9700
TEST_TIME 60 9858
TEST_TIME 61 9885
TEST_TIME 62 9838
TEST_TIME 63 9813
TEST_TIME 64 9770
TEST_TIME 65 9838
TEST_TIME 66 9749
TEST_TIME 67 9737
TEST_TIME 68 9870
TEST_TIME 69 9954
TEST_TIME 70 9920
TEST_TIME 71 9933
TEST_TIME 72 9946
TEST_TIME 73 9950
TEST_TIME 74 9914
TEST_TIME 75 9844
TEST_TIME 76 9802
TEST_TIME 77 9893
TEST_TIME 78 9810
TEST_TIME 79 9815
TEST_TIME 80 9800
TEST_TIME 81 9711
TEST_TIME 82 9645
TEST_TIME 83 9590
TEST_TIME 84 9619
TEST_TIME 85 9674
TEST_TIME 86 9618
TEST_TIME 87 9560
TEST_TIME 88 9470
TEST_TIME 89 9393
TEST_TIME 90 9411
TEST_TIME 91 9423
TEST_TIME 92 9480
TEST_TIME 93 9434
TEST_TIME 94 9450
TEST_TIME 95 9427
TEST_TIME 96 9501
TEST_TIME 97 9467
TEST_TIME 98 9430
TEST_TIME 99 9455
TEST_TIME 100 9492
TEST_TIME 101 9497
TEST_TIME 102 9550
TEST_TIME 103 9565
TEST_TIME 104 9561
TEST_TIME 105 9522
TEST_TIME 106 9454
TEST_TIME 107 9448
TEST_TIME 108 9410
TEST_TIME 109 9382
TEST_TIME 110 9373
TEST_TIME 111 9350
TEST_TIME 112 9323
TEST_TIME 113 9294
TEST_TIME 114 9262
TEST_TIME 115 9266
TEST_TIME 116 9242
TEST_TIME 117 9226
TEST_TIME 118 9225
TEST_TIME 119 9209
TEST_TIME 120 9155

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 18-07-2022 13:38:40 to 18-07-2022 13:38:43.

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1518323 1518323 0 0.00 0 23431 14 0 0
doc_list_biud 1518323 1518323 0 0.00 0 52536 46 0 0
sp_add_invoice_to_stock 4999 4869 130 2.60 47 206898 28015 0 130
sp_cancel_adding_invoice 5669 5669 0 0.00 4 90932 1300 0 0
sp_cancel_client_order 1795 1795 0 0.00 5 13946 489 0 0
sp_cancel_customer_reserve 553730 553730 0 0.00 1 52537 111 0 0
sp_cancel_pay_from_customer 687 687 0 0.00 4 9029 155 0 0
sp_cancel_pay_to_supplier 410 410 0 0.00 4 1781 135 0 0
sp_cancel_supplier_invoice 6271 6256 15 0.23 2 18649 1027 0 15
sp_cancel_supplier_order 408 408 0 0.00 13 1708262 192881 0 0
sp_cancel_write_off 9511 9511 0 0.00 2 15093 189 0 0
sp_client_order 20725 20725 0 0.00 6 37124 497 0 0
sp_customer_reserve 457441 457441 0 0.00 1 115433 262 0 0
sp_fill_shopping_cart 31515 31515 0 0.00 1 27629 88 0 0
sp_fill_shopping_cart_clo_res 457432 457432 0 0.00 0 13146 19 0 0
sp_get_clo_for_invoice 4836 4836 0 0.00 0 30497 311 0 0
sp_kill_cost_storno 16277 16277 0 0.00 0 13963 83 0 0
sp_kill_qstorno_ret_qs2qd 1123635 1123635 0 0.00 0 52534 46 0 0
sp_kill_qty_storno 592065 592065 0 0.00 0 52536 108 0 0
sp_lock_selected_doc 20432 20432 0 0.00 0 11217 10 0 0
sp_make_cost_storno 27624 27624 0 0.00 0 15658 87 0 0
sp_multiply_rows_for_pdistr 27624 27624 0 0.00 0 15699 43 0 0
sp_multiply_rows_for_qdistr 20725 20725 0 0.00 0 12670 164 0 0
sp_pay_from_customer 7030 7030 0 0.00 6 25630 201 0 0
sp_pay_to_supplier 5898 5898 0 0.00 7 20519 232 0 0
sp_payment_common 12928 12928 0 0.00 2 17857 53 0 0
sp_qd_handle_on_cancel_clo 1795 1795 0 0.00 0 13937 393 0 0
sp_qd_handle_on_invoice_upd_sts 10496 10496 0 0.00 0 29089 586 0 0
sp_qd_handle_on_reserve_upd_sts 9869 9869 0 0.00 0 17602 74 0 0
sp_reserve_write_off 9869 9869 0 0.00 9 22589 306 0 0
sp_supplier_invoice 5298 5298 0 0.00 8 97716 3356 0 0
sp_supplier_order 5496 5496 0 0.00 7 53085 1480 0 0
srv_aggregate_perf_data 111 110 1 0.90 2385 225452 16780 0 1
srv_fill_mon_cache_memory 71 71 0 0.00 732 146784 33841 0 0
srv_find_qd_qs_mism 1048188 1048188 0 0.00 0 23429 10 0 0
srv_make_invnt_saldo 490 490 0 0.00 40 150730 4347 0 0
srv_make_money_saldo 999 999 0 0.00 1 11528 370 0 0
srv_recalc_idx_stat 6 6 0 0.00 92942 412894 251891 0 0
t$perf-abend:sp_add_invoice_to_stock 130 130 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 15 15 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4869 4869 0 0.00 1 15618 1054 0 0
t$perf-norm:sp_cancel_adding_invoice 397 397 0 0.00 1 5964 472 0 0
t$perf-norm:sp_cancel_client_order 1795 1795 0 0.00 0 3059 33 0 0
t$perf-norm:sp_cancel_customer_reserve 685 685 0 0.00 0 3306 33 0 0
t$perf-norm:sp_cancel_pay_from_customer 687 687 0 0.00 0 10194 32 0 0
t$perf-norm:sp_cancel_pay_to_supplier 410 410 0 0.00 0 2005 23 0 0
t$perf-norm:sp_cancel_supplier_invoice 383 383 0 0.00 0 2979 48 0 0
t$perf-norm:sp_cancel_supplier_order 408 408 0 0.00 0 103555 8097 0 0
t$perf-norm:sp_cancel_write_off 971 971 0 0.00 0 1042 20 0 0
t$perf-norm:sp_client_order 20725 20725 0 0.00 0 8388 44 0 0
t$perf-norm:sp_customer_reserve 957 957 0 0.00 0 1153 16 0 0
t$perf-norm:sp_pay_from_customer 7030 7030 0 0.00 0 8273 24 0 0
t$perf-norm:sp_pay_to_supplier 5898 5898 0 0.00 0 7739 25 0 0
t$perf-norm:sp_reserve_write_off 9869 9869 0 0.00 0 10722 29 0 0
t$perf-norm:sp_supplier_invoice 5298 5298 0 0.00 0 11403 36 0 0
t$perf-norm:sp_supplier_order 5496 5496 0 0.00 0 10829 43 0 0
t$perf-norm:srv_aggregate_perf_data 110 110 0 0.00 0 530 24 0 0
t$perf-norm:srv_fill_mon_cache_memory 71 71 0 0.00 0 1392 31 0 0
t$perf-norm:srv_make_invnt_saldo 490 490 0 0.00 0 778 23 0 0
t$perf-norm:srv_make_money_saldo 999 999 0 0.00 0 9764 15 0 0
t$perf-norm:srv_recalc_idx_stat 6 6 0 0.00 3 437 117 0 0
v_add_invoice_to_stock 9914 9654 260 2.62 0 13130 31 0 260
v_all_customers 38094 38094 0 0.00 0 84 4 0 0
v_all_suppliers 30598 30598 0 0.00 0 69 5 0 0
v_all_wares 41450 41450 0 0.00 0 79 4 0 0
v_cancel_adding_invoice 794 794 0 0.00 0 2879 18 0 0
v_cancel_client_order 3590 3590 0 0.00 0 4177 12 0 0
v_cancel_customer_prepayment 1374 1374 0 0.00 0 1030 12 0 0
v_cancel_customer_reserve 1370 1370 0 0.00 0 1116 11 0 0
v_cancel_payment_to_supplier 820 820 0 0.00 0 936 14 0 0
v_cancel_supplier_invoice 796 766 30 3.76 0 579 31 0 30
v_cancel_supplier_order 816 816 0 0.00 0 1250 15 0 0
v_cancel_write_off 1942 1942 0 0.00 0 9037 30 0 0
v_max_id_clo_ord 5496 5496 0 0.00 0 1567 6 0 0
v_max_id_clo_res 957 957 0 0.00 0 763 9 0 0
v_max_id_ord_sup 5294 5294 0 0.00 0 2684 6 0 0
v_max_non_paid_invoice 1389 1389 0 0.00 0 237 9 0 0
v_max_non_paid_realizn 3511 3511 0 0.00 0 848 8 0 0
v_min_id_clo_ord 5496 5496 0 0.00 0 3509 12 0 0
v_min_id_clo_res 957 957 0 0.00 0 7266 21 0 0
v_min_id_ord_sup 5294 5294 0 0.00 0 5452 11 0 0
v_min_non_paid_invoice 5898 5898 0 0.00 0 9718 19 0 0
v_min_non_paid_realizn 7030 7030 0 0.00 0 8782 20 0 0
v_our_firm 10394 10394 0 0.00 0 74 5 0 0
v_reserve_write_off 19738 19738 0 0.00 0 10018 13 0 0
x_lock_depdocs_on_canc_invoice 11542 11542 0 0.00 0 12773 143 0 0
x_lock_depdocs_on_canc_sup_ord 408 408 0 0.00 0 3470 382 0 0
x_make_qstorno_1000_1200 5496 5496 0 0.00 2 53037 1293 0 0
x_make_qstorno_1200_2000 5298 5298 0 0.00 4 86485 3120 0 0
x_make_qstorno_2100_3300 457441 457441 0 0.00 0 115427 229 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 18-07-2022 13:38:44 to 18-07-2022 13:38:48.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
11:07:23 2 8192000000 8796669104 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:24 27021 8192000000 9711069872 10005446656 786074528 8.755 100 88 176 0 814877072 7455552 21346992
11:09:55 19064 8192000000 9752831504 10055864320 823532112 9.134 100 91 182 0 858028448 12944112 21552224
11:11:20 13161 8192000000 9778775616 10094874624 848772272 9.388 100 78 156 0 889091456 22109968 18209216
11:12:38 10321 8192000000 9845450880 10172960768 899840560 9.897 100 81 160 0 953633792 36062480 17730752
11:13:53 6598 8192000000 9893731328 10235244544 944383792 10.336 100 83 165 0 1007840528 45504160 17952576
11:15:02 2353 8192000000 9940878000 10291109888 978050656 10.665 100 84 164 1 1056930272 61705728 17173888
11:16:07 1497 8192000000 9992101728 10350764032 1012160080 10.996 100 87 165 1 1110262736 81371824 16730832
11:17:12 1251 8192000000 10015490560 10381172736 1034781840 11.214 100 86 167 0 1125816864 74904480 16130544
11:18:15 830 8192000000 10037705920 10408325120 1048543280 11.347 100 99 191 1 1157161088 89643488 18974320
11:19:17 1021 8192000000 10036719616 10409914368 1060646128 11.463 100 95 177 2 1160100112 81176128 18277856
11:20:20 1104 8192000000 10030536608 10412126208 1068625056 11.539 100 95 182 1 1152996656 67346464 17025136
11:21:23 913 8192000000 10042015696 10422419456 1075450640 11.604 100 93 180 0 1164141904 70874704 17816560
11:22:25 1337 8192000000 10069426912 10452713472 1083706992 11.683 100 98 188 1 1188299776 86400704 18192080
11:23:27 1046 8192000000 10036041952 10421243904 1087543568 11.719 100 87 166 0 1155420368 51182544 16694256
11:24:30 936 8192000000 10050954432 10439766016 1087741136 11.721 100 90 172 2 1174798640 69772816 17284688
11:25:32 1072 8192000000 10027934640 10416160768 1094871312 11.789 100 93 178 2 1153378128 39031632 19475184
11:26:34 1052 8192000000 10068175520 10457387008 1098822800 11.826 100 97 184 2 1191920832 74016576 19081456
11:27:36 911 8192000000 10068760304 10458234880 1096464208 11.804 100 92 172 3 1191134480 77392384 17277888
11:28:38 966 8192000000 10038986544 10430308352 1097598352 11.815 100 88 166 1 1161187856 46452832 17136672
11:29:40 1127 8192000000 10066868512 10458116096 1104735936 11.883 100 88 159 1 1189338176 67919056 16683184
11:30:43 1588 8192000000 10038776928 10433912832 1092828192 11.770 100 82 161 1 1166210192 55845040 17536960
11:31:47 1408 8192000000 10021455088 10405892096 1074707984 11.597 100 97 172 4 1142793856 49079776 19006096
11:32:50 1104 8192000000 10012960832 10387701760 1049061552 11.352 100 94 174 0 1140106192 72255792 18788848
11:33:52 968 8192000000 10000509264 10373238784 1038656368 11.252 100 85 164 2 1123756848 68865296 16235184
11:34:54 3615 8192000000 9972056672 10346651648 1028579376 11.155 100 72 135 3 1099863264 57070864 14213024
11:35:58 866 8192000000 9993342832 10370756608 1029488400 11.164 100 88 172 0 1110382096 63497840 17395856
11:37:00 924 8192000000 9970456672 10344480768 1037070256 11.236 100 91 168 1 1092181408 38188256 16922896
11:38:04 2671 8192000000 9999940224 10380861440 1055451328 11.413 100 79 151 0 1122157488 51350144 15356016
11:39:14 2272 8192000000 10018231312 10397958144 1064651712 11.501 100 80 150 1 1137493200 58208992 14632496
11:40:25 29058 8192000000 10059273136 10451304448 1086745744 11.712 100 60 117 1 1176207152 77282432 12178976
11:41:59 9176 8192000000 10091306784 10487074816 1097970864 11.818 100 62 113 1 1204250496 93841648 12437984
11:43:15 1869 8192000000 10147042304 10531647488 1112905168 11.960 100 85 158 4 1262027824 131888224 17234432
11:44:22 2664 8192000000 10198881168 10588024832 1125011488 12.074 100 87 167 0 1310627920 167628432 17988000
11:45:29 1953 8192000000 10195830288 10596790272 1138525872 12.202 100 85 156 0 1310149216 154822368 16800976
11:46:39 4001 8192000000 10215051360 10619101184 1151906672 12.327 100 75 148 1 1335478320 167628784 15942864
11:47:53 1110 8192000000 10239327920 10653585408 1154038496 12.347 100 94 174 1 1347487536 173880448 19568592
11:48:59 6327 8192000000 10269708176 10673524736 1161016592 12.413 100 73 146 1 1382969488 205315360 16637536
11:50:12 1392 8192000000 10308139552 10734530560 1169730272 12.494 100 92 178 0 1422172960 231194240 21248448
11:51:17 6304 8192000000 10337431104 10753990656 1159453872 12.398 99 77 151 0 1444438192 268289712 16694608
11:52:37 971 8192000000 10372213600 10785239040 1187400656 12.659 100 94 183 0 1480947232 271871008 21675568
11:53:41 864 8192000000 10391607824 10799308800 1189853888 12.682 100 96 183 0 1500704496 290076496 20774112
11:54:46 1365 8192000000 10457860704 10861944832 1194047472 12.721 100 91 177 0 1566903456 350858032 21997952
11:55:50 1086 8192000000 10505602864 10912153600 1193479808 12.716 100 97 192 1 1610060512 393173680 23407024
11:56:56 1284 8192000000 10527808064 10935242752 1194778512 12.728 100 94 178 0 1635138064 419353712 21005840
11:58:01 943 8192000000 10576771312 11008479232 1195765264 12.737 100 97 192 2 1683942720 464751568 23425888
11:59:04 1383 8192000000 10598052864 11057967104 1198119024 12.759 100 97 191 0 1701082624 479524080 23439520
12:00:08 1173 8192000000 10604265008 11024404480 1199866320 12.775 100 97 191 0 1710221616 487661744 22693552
12:01:12 888 8192000000 10658198752 11085807616 1207071440 12.842 100 99 196 1 1762370720 531251728 24047552
12:02:15 1057 8192000000 10672164064 11125174272 1215545792 12.920 100 96 188 0 1774442144 535453456 23442896
12:03:20 1101 8192000000 10695046544 11134222336 1207545264 12.846 100 92 182 0 1797152592 566455776 23151552
12:04:25 724 8192000000 10736902000 11158351872 1200715456 12.783 100 89 177 1 1838401168 616051824 21633888
12:05:31 4453 8192000000 10727814160 11165949952 1192829456 12.710 100 65 128 0 1827458736 617777888 16851392
12:06:50 1677 8192000000 10738286368 11218092032 1189075408 12.675 100 71 140 0 1842578224 635588400 17914416
12:08:00 6150 8192000000 10617644512 11137966080 1159108080 12.395 99 54 106 0 1716908560 543648096 14152384
12:09:31 24957 8192000000 10529985888 11004837888 1161646096 12.419 100 30 60 0 1680328000 510765968 7915936
12:11:59 32382 8192000000 10301592944 10833801216 1140110256 12.217 99 23 44 1 1462512128 315929776 6472096
12:13:58 30706 8192000000 10157728992 10637201408 1151594992 12.324 100 30 60 0 1292259680 133114064 7550624
12:16:21 44804 8192000000 10081470704 10530811904 1143959888 12.253 100 37 73 1 1195598960 44224432 7414640
12:18:21 71055 8192000000 10089138288 10553114624 1136490672 12.183 99 12 23 0 1164719824 26424496 1804656
12:20:40 13216 8192000000 10087814784 10525831168 1146470032 12.276 100 51 88 0 1174484880 18646128 9368720
12:21:55 140116 8192000000 10118566912 10597089280 1113553280 11.966 96 27 54 0 1164238640 44799696 5885664
12:25:26 146781 8192000000 10120858992 10549866496 1118510896 12.013 97 27 52 0 1164609568 40329280 5769392
12:28:55 25878 8192000000 10150852848 10600833024 1151211392 12.321 100 46 89 1 1242260144 81822656 9226096
12:30:31 21871 8192000000 10145518624 10588819456 1134605184 12.165 99 31 60 0 1234759040 93521248 6632608
12:31:54 16774 8192000000 10202189632 10660126720 1141350016 12.228 100 48 95 0 1293281920 142030512 9901392
12:33:12 61727 8192000000 10146587552 10613788672 1123171088 12.057 98 12 24 0 1239974656 113856320 2947248
12:35:15 37923 8192000000 10162921456 10610688000 1139746528 12.213 99 20 39 0 1266028608 122369744 3912336
12:37:11 70475 8192000000 10113984000 10593624064 1146855056 12.280 100 18 36 0 1284276288 133093280 4327952
12:39:22 56547 8192000000 10103446000 10592485376 1142316448 12.237 99 18 36 0 1193919792 47908432 3694912
12:41:24 48585 8192000000 10160546960 10611855360 1124950048 12.074 97 32 64 0 1213049776 80937568 7162160
12:43:18 37315 8192000000 10194335040 10625212416 1146017456 12.272 99 36 74 0 1224419104 70079408 8322240
12:44:56 44670 8192000000 10132239312 10612146176 1136235744 12.180 99 23 44 1 1256899920 115545808 5118368
12:46:44 61651 8192000000 10101324256 10549092352 1129455504 12.116 98 19 38 0 1172118800 38697344 3965952
12:48:50 27040 8192000000 10134272928 10569404416 1136995888 12.187 100 49 93 1 1228003200 81372160 9635152
12:50:20 54700 8192000000 10089544288 10539798528 1088878288 11.732 96 34 66 0 1135414944 40182416 6354240
12:52:16 21538 8192000000 10113120176 10541682688 1114907600 11.979 99 42 83 1 1162558704 39703632 7947472
12:53:38 58910 8192000000 10173856416 10660913152 1136156624 12.179 100 43 86 0 1281109744 136122160 8830960
12:55:55 81324 8192000000 10065365888 10535538688 1097576368 11.815 97 21 42 1 1156720176 54733104 4410704
12:58:18 46374 8192000000 10059860304 10495029248 1099767840 11.835 98 41 76 1 1147253872 39397216 8088816
13:00:04 58035 8192000000 10154488752 10607992832 1129604944 12.118 100 49 95 0 1240777568 101243120 9929504
13:02:19 44951 8192000000 10043099904 10544754688 1111001936 11.942 99 10 21 0 1188769200 75458496 2308768
13:04:06 59960 8192000000 10059424464 10521776128 1116591152 11.995 100 8 14 1 1148174720 30048624 1534944
13:06:06 55254 8192000000 10127298528 10596352000 1103367488 11.870 97 13 26 0 1181811328 75399152 3044688
13:08:48 44194 8192000000 10088004320 10571034624 1112511632 11.956 99 26 52 0 1179381552 62023664 4846256
13:11:13 26855 8192000000 10134594016 10588950528 1135042896 12.169 100 48 96 0 1221346240 76426800 9876544
13:12:45 81974 8192000000 10071471184 10585739264 1135274336 12.171 99 18 34 0 1205033792 66471952 3287504
13:15:08 32517 8192000000 10133333824 10570997760 1134357872 12.162 99 42 84 0 1210709824 67597344 8754608
13:16:43 51540 8192000000 10089272112 10551250944 1041023424 11.275 92 19 38 1 1108727600 63731136 3973040
13:18:34 24662 8192000000 10119448496 10590707712 1105808736 11.893 98 30 60 0 1216338464 104155744 6373984
13:20:00 101522 8192000000 10053150880 10510868480 1089685584 11.740 97 15 30 0 1134113760 41315712 3112464
13:22:42 66076 8192000000 10090801088 10536067072 1123793840 12.063 99 30 57 1 1180624064 50398816 6431408
13:24:54 57788 8192000000 10072410960 10521636864 1103398272 11.870 97 39 74 2 1144626320 33660176 7567872
13:26:53 16851 8192000000 10085036688 10522722304 1141951936 12.234 100 47 87 1 1187589408 36803088 8834384
13:28:12 79138 8192000000 10084170656 10551308288 1117784032 12.006 97 24 48 0 1167086800 44285376 5017392
13:30:31 61640 8192000000 10106130720 10606624768 1136671664 12.184 99 8 14 0 1219763200 81410288 1681248
13:32:38 75876 8192000000 10110160512 10559774720 1122787424 12.053 98 29 56 1 1193773184 64362720 6623040
13:34:55 73659 8192000000 10065093472 10547195904 1129181568 12.114 99 21 42 1 1178117632 45075296 3860768
13:37:09 18681 8192000000 9067159408 9331884032 218827536 2.601 20 2 4 0 234023440 14907520 288384

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 18-07-2022 13:38:50 to 18-07-2022 13:39:06.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 130 v_add_invoice_to_stock 335544517
except 15 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 18-07-2022 13:39:07 to 18-07-2022 13:39:09.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          219743
MON$OLDEST_ACTIVE               219744
MON$OLDEST_SNAPSHOT             219744
MON$NEXT_TRANSACTION            219744
MON$PAGE_BUFFERS                1000000
MON$SQL_DIALECT                 3
MON$SHUTDOWN_MODE               0
MON$SWEEP_INTERVAL              0
MON$READ_ONLY                   0
MON$FORCED_WRITES               1
MON$RESERVE_SPACE               1
MON$CREATION_DATE               2020-04-30 07:30:08.6030 GMT
MON$PAGES                       2083168
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             771
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2799 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2799 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2799 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2799 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
:108: 
:109: mscs-test-161.budzdorov.ru    Mon Jul 18 13:37:32 2022
:110:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:111: 
--- end of diff output ---

Final processing ISQL logs in /var/tmp/logs-oltp40 according to config parameter 'remove_isql_logs'

18.07.22 13:39:10. There are 3 logs in /var/tmp/logs-oltp40 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2022-07-18 13:39:12 No severe PSQL-related problems occured
18.07.22 13:39:14. End of report.