Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 27.09.2022 07:47
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 15307920 kB
MemAvailable 30578404 kB
Buffers 0 kB
Cached 16385964 kB
SwapCached 2068 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.3
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-09-27 07:46:58
DTS_END                         2022-09-27 07:46:58.7480
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/42510
TRN_ID                          216113
ATT_ID                          631
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: 8801.48 24154 1056177
customer order: creation 170.53 512 20463
customer order: refuse 14.76 451 1771
order to supplier: creation 44.63 1445 5355
order to supplier: removal 3.18 191423 381
invoice (draft): creation 43.77 3413 5252
invoice (draft): removal 48.85 1028 5862
invoice accept: apply 40.56 24873 4867
invoice accept: cancel 44.48 1292 5337
customer reserve: creation 3738.82 234 448658
customer reserve: removal 4364.23 111 523708
realization accept: apply 80.29 282 9635
realization accept: cancel 83.13 166 9976
payment from customer: creation 57.28 186 6874
payment from customer: removal 5.32 116 638
payment to supplier: creation 46.52 211 5582
payment to supplier: removal 3.28 136 394
service: total inventory turnovers 3.76 4575 451
service: total monetary turnovers 7.24 395 869
service: aggregate perf. data 0.83 16626 100
service: refresh index statistics 0.03 235595 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 27-09-2022 07:48:13 to 27-09-2022 07:48:15.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5179
TEST_TIME 2 4520
TEST_TIME 3 4404
TEST_TIME 4 5134
TEST_TIME 5 5444
TEST_TIME 6 5563
TEST_TIME 7 5995
TEST_TIME 8 6427
TEST_TIME 9 6492
TEST_TIME 10 6703
TEST_TIME 11 6817
TEST_TIME 12 6899
TEST_TIME 13 6940
TEST_TIME 14 6979
TEST_TIME 15 7022
TEST_TIME 16 6930
TEST_TIME 17 7300
TEST_TIME 18 7297
TEST_TIME 19 7422
TEST_TIME 20 7726
TEST_TIME 21 7940
TEST_TIME 22 7896
TEST_TIME 23 7828
TEST_TIME 24 7872
TEST_TIME 25 7934
TEST_TIME 26 7926
TEST_TIME 27 8103
TEST_TIME 28 8150
TEST_TIME 29 8795
TEST_TIME 30 8964
TEST_TIME 31 9426
TEST_TIME 32 10196
TEST_TIME 33 10485
TEST_TIME 34 11082
TEST_TIME 35 10890
TEST_TIME 36 10794
TEST_TIME 37 10593
TEST_TIME 38 10659
TEST_TIME 39 10577
TEST_TIME 40 10399
TEST_TIME 41 10354
TEST_TIME 42 10192
TEST_TIME 43 10074
TEST_TIME 44 10093
TEST_TIME 45 10019
TEST_TIME 46 9913
TEST_TIME 47 9756
TEST_TIME 48 9720
TEST_TIME 49 9549
TEST_TIME 50 9448
TEST_TIME 51 9567
TEST_TIME 52 9614
TEST_TIME 53 9518
TEST_TIME 54 9534
TEST_TIME 55 9541
TEST_TIME 56 9412
TEST_TIME 57 9377
TEST_TIME 58 9300
TEST_TIME 59 9214
TEST_TIME 60 9230
TEST_TIME 61 9133
TEST_TIME 62 9100
TEST_TIME 63 9023
TEST_TIME 64 9049
TEST_TIME 65 9054
TEST_TIME 66 9018
TEST_TIME 67 9044
TEST_TIME 68 8975
TEST_TIME 69 8922
TEST_TIME 70 8907
TEST_TIME 71 8973
TEST_TIME 72 9096
TEST_TIME 73 9169
TEST_TIME 74 9191
TEST_TIME 75 9141
TEST_TIME 76 9225
TEST_TIME 77 9209
TEST_TIME 78 9238
TEST_TIME 79 9197
TEST_TIME 80 9095
TEST_TIME 81 9108
TEST_TIME 82 9040
TEST_TIME 83 9045
TEST_TIME 84 9007
TEST_TIME 85 8999
TEST_TIME 86 8941
TEST_TIME 87 8909
TEST_TIME 88 8902
TEST_TIME 89 8831
TEST_TIME 90 8869
TEST_TIME 91 8894
TEST_TIME 92 8855
TEST_TIME 93 8859
TEST_TIME 94 8901
TEST_TIME 95 8856
TEST_TIME 96 8855
TEST_TIME 97 8854
TEST_TIME 98 8922
TEST_TIME 99 8924
TEST_TIME 100 8985
TEST_TIME 101 9019
TEST_TIME 102 8996
TEST_TIME 103 9072
TEST_TIME 104 9065
TEST_TIME 105 8995
TEST_TIME 106 8994
TEST_TIME 107 8947
TEST_TIME 108 8932
TEST_TIME 109 8949
TEST_TIME 110 8907
TEST_TIME 111 8862
TEST_TIME 112 8847
TEST_TIME 113 8812
TEST_TIME 114 8816
TEST_TIME 115 8792
TEST_TIME 116 8769
TEST_TIME 117 8798
TEST_TIME 118 8800
TEST_TIME 119 8803
TEST_TIME 120 8826

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 27-09-2022 07:48:17 to 27-09-2022 07:48:20.

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 1470049 1470049 0 0.00 0 23307 13 0 0
doc_list_biud 1470035 1470035 0 0.00 0 28210 45 0 0
sp_add_invoice_to_stock 5050 4867 183 3.62 56 220929 24873 0 183
sp_cancel_adding_invoice 5337 5337 0 0.00 7 79801 1292 0 0
sp_cancel_client_order 1771 1771 0 0.00 6 12003 451 0 0
sp_cancel_customer_reserve 523708 523708 0 0.00 1 28234 111 0 0
sp_cancel_pay_from_customer 638 638 0 0.00 4 2834 117 0 0
sp_cancel_pay_to_supplier 394 394 0 0.00 4 6671 137 0 0
sp_cancel_supplier_invoice 5877 5862 15 0.25 2 15532 1028 0 15
sp_cancel_supplier_order 381 381 0 0.00 9 1580131 191424 0 0
sp_cancel_write_off 9976 9976 0 0.00 2 6670 166 0 0
sp_client_order 20463 20463 0 0.00 5 24118 513 0 0
sp_customer_reserve 448658 448658 0 0.00 0 87760 234 0 0
sp_fill_shopping_cart 31063 31063 0 0.00 1 21802 92 0 0
sp_fill_shopping_cart_clo_res 448620 448620 0 0.00 0 16661 20 0 0
sp_get_clo_for_invoice 4820 4820 0 0.00 0 40653 393 0 0
sp_kill_cost_storno 16345 16345 0 0.00 0 6665 73 0 0
sp_kill_qstorno_ret_qs2qd 1063635 1063635 0 0.00 0 28192 48 0 0
sp_kill_qty_storno 561489 561489 0 0.00 0 28207 109 0 0
sp_lock_selected_doc 20121 20121 0 0.00 0 7555 9 0 0
sp_make_cost_storno 26910 26910 0 0.00 0 16949 83 0 0
sp_multiply_rows_for_pdistr 26910 26910 0 0.00 0 8132 38 0 0
sp_multiply_rows_for_qdistr 20463 20463 0 0.00 0 15721 202 0 0
sp_pay_from_customer 6874 6874 0 0.00 6 11653 187 0 0
sp_pay_to_supplier 5582 5582 0 0.00 6 17975 211 0 0
sp_payment_common 12455 12455 0 0.00 2 17947 51 0 0
sp_qd_handle_on_cancel_clo 1771 1771 0 0.00 0 11865 367 0 0
sp_qd_handle_on_invoice_upd_sts 10157 10157 0 0.00 0 20098 531 0 0
sp_qd_handle_on_reserve_upd_sts 9634 9634 0 0.00 0 17641 62 0 0
sp_reserve_write_off 9635 9635 0 0.00 8 17658 283 0 0
sp_supplier_invoice 5252 5252 0 0.00 9 86021 3413 0 0
sp_supplier_order 5355 5355 0 0.00 7 57285 1446 0 0
srv_aggregate_perf_data 100 100 0 0.00 2517 291814 16626 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 751 134155 39874 0 0
srv_find_qd_qs_mism 1009036 1009036 0 0.00 0 19701 9 0 0
srv_make_invnt_saldo 451 451 0 0.00 45 215468 4575 0 0
srv_make_money_saldo 869 869 0 0.00 1 17647 395 0 0
srv_recalc_idx_stat 4 4 0 0.00 105188 356390 235596 0 0
t$perf-abend:sp_add_invoice_to_stock 183 183 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-norm:sp_add_invoice_to_stock 4869 4869 0 0.00 1 29092 1014 0 0
t$perf-norm:sp_cancel_adding_invoice 414 414 0 0.00 2 6002 498 0 0
t$perf-norm:sp_cancel_client_order 1771 1771 0 0.00 0 8122 38 0 0
t$perf-norm:sp_cancel_customer_reserve 713 713 0 0.00 0 1429 27 0 0
t$perf-norm:sp_cancel_pay_from_customer 638 638 0 0.00 0 1573 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 394 394 0 0.00 0 7242 36 0 0
t$perf-norm:sp_cancel_supplier_invoice 417 417 0 0.00 0 1252 40 0 0
t$perf-norm:sp_cancel_supplier_order 381 381 0 0.00 0 101217 6818 0 0
t$perf-norm:sp_cancel_write_off 939 939 0 0.00 0 937 19 0 0
t$perf-norm:sp_client_order 20463 20463 0 0.00 0 10934 40 0 0
t$perf-norm:sp_customer_reserve 989 989 0 0.00 0 5615 26 0 0
t$perf-norm:sp_pay_from_customer 6874 6874 0 0.00 0 26139 22 0 0
t$perf-norm:sp_pay_to_supplier 5582 5582 0 0.00 0 18052 26 0 0
t$perf-norm:sp_reserve_write_off 9636 9636 0 0.00 0 25176 30 0 0
t$perf-norm:sp_supplier_invoice 5252 5252 0 0.00 0 9982 35 0 0
t$perf-norm:sp_supplier_order 5355 5355 0 0.00 0 5247 39 0 0
t$perf-norm:srv_aggregate_perf_data 100 100 0 0.00 0 487 17 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 164 9 0 0
t$perf-norm:srv_make_invnt_saldo 451 451 0 0.00 0 1275 18 0 0
t$perf-norm:srv_make_money_saldo 869 869 0 0.00 0 242 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 4 582 163 0 0
v_add_invoice_to_stock 10006 9640 366 3.65 0 7645 32 0 366
v_all_customers 37174 37174 0 0.00 0 88 4 0 0
v_all_suppliers 29384 29384 0 0.00 0 94 4 0 0
v_all_wares 40920 40920 0 0.00 0 96 4 0 0
v_cancel_adding_invoice 828 828 0 0.00 0 4115 27 0 0
v_cancel_client_order 3542 3542 0 0.00 0 7037 15 0 0
v_cancel_customer_prepayment 1276 1276 0 0.00 0 2473 16 0 0
v_cancel_customer_reserve 1426 1426 0 0.00 1 1483 14 0 0
v_cancel_payment_to_supplier 788 788 0 0.00 0 349 10 0 0
v_cancel_supplier_invoice 864 834 30 3.47 0 469 25 0 30
v_cancel_supplier_order 762 762 0 0.00 0 3028 17 0 0
v_cancel_write_off 1878 1878 0 0.00 0 4111 27 0 0
v_max_id_clo_ord 5355 5355 0 0.00 0 510 5 0 0
v_max_id_clo_res 989 989 0 0.00 0 4260 21 0 0
v_max_id_ord_sup 5247 5247 0 0.00 0 2747 7 0 0
v_max_non_paid_invoice 1491 1491 0 0.00 0 168 8 0 0
v_max_non_paid_realizn 3593 3593 0 0.00 0 6596 9 0 0
v_min_id_clo_ord 5355 5355 0 0.00 0 2055 8 0 0
v_min_id_clo_res 989 989 0 0.00 0 3616 17 0 0
v_min_id_ord_sup 5247 5247 0 0.00 0 5969 13 0 0
v_min_non_paid_invoice 5581 5581 0 0.00 0 17934 21 0 0
v_min_non_paid_realizn 6874 6874 0 0.00 0 6262 17 0 0
v_our_firm 10304 10304 0 0.00 0 66 5 0 0
v_reserve_write_off 19268 19268 0 0.00 0 7157 15 0 0
x_lock_depdocs_on_canc_invoice 10782 10782 0 0.00 0 21051 135 0 0
x_lock_depdocs_on_canc_sup_ord 381 381 0 0.00 0 8840 423 0 0
x_make_qstorno_1000_1200 5355 5355 0 0.00 3 56919 1276 0 0
x_make_qstorno_1200_2000 5252 5252 0 0.00 4 86007 3139 0 0
x_make_qstorno_2100_3300 448658 448658 0 0.00 0 87755 201 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 27-09-2022 07:48:21 to 27-09-2022 07:48:25.

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
05:17:02 1 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
05:18:03 17706 8192000000 9719306528 10015473664 797406944 8.870 100 87 172 0 826843840 7917712 21519184
05:19:25 24424 8192000000 9763965856 10070568960 835607760 9.256 100 92 184 0 870237824 12361968 22268096
05:20:54 13312 8192000000 9803801072 10120224768 869746928 9.598 100 83 166 1 910562992 21396432 19419632
05:22:13 8629 8192000000 9850603088 10182758400 903097728 9.929 100 79 153 0 959874576 39920464 16856384
05:23:27 3479 8192000000 9911744304 10254131200 954994688 10.440 100 87 171 1 1032689504 60062976 17631840
05:24:39 4134 8192000000 9971442064 10324525056 996398512 10.844 100 93 186 0 1083439760 68088528 18952720
05:25:46 1510 8192000000 10015005200 10373214208 1020484048 11.077 100 98 195 2 1125176608 85574112 19118448
05:26:51 1072 8192000000 10010561184 10378076160 1042185936 11.286 100 96 183 0 1133609808 71375664 20048208
05:27:53 1046 8192000000 10022838512 10400227328 1057903888 11.436 100 94 180 2 1142788576 66969856 17914832
05:28:56 913 8192000000 10043612944 10420731904 1070761488 11.559 100 97 190 0 1168398176 77638880 19997808
05:29:59 1234 8192000000 10031156448 10412072960 1073451232 11.585 100 75 143 2 1156978528 69054448 14472848
05:31:02 1080 8192000000 10076187920 10460352512 1083395376 11.680 100 96 186 1 1198605280 97626688 17583216
05:32:05 1135 8192000000 10063421024 10450698240 1082653664 11.673 100 94 179 1 1186752400 86609248 17489488
05:33:07 1196 8192000000 10055731328 10441867264 1085046704 11.696 100 92 174 2 1173203712 71338768 16818240
05:34:09 1188 8192000000 10043888944 10429497344 1095221920 11.792 100 93 182 1 1167942880 54309408 18411552
05:35:11 2884 8192000000 10064667680 10453143552 1092420208 11.766 100 80 149 2 1182729072 74758096 15550768
05:36:15 3925 8192000000 10060778352 10462203904 1097133056 11.810 100 68 135 2 1186151472 75352912 13665504
05:37:20 1420 8192000000 10069234304 10466865152 1102319200 11.860 100 84 166 0 1193044512 73280608 17444704
05:38:24 3424 8192000000 10047426320 10443620352 1097133408 11.810 100 66 127 0 1168684320 58781664 12769248
05:39:28 1080 8192000000 10063511744 10451410944 1093609584 11.777 100 98 187 1 1188788032 76231088 18947360
05:40:30 1212 8192000000 10008258592 10385113088 1060249744 11.459 99 95 175 3 1132175504 53274992 18650768
05:41:33 1595 8192000000 9992444736 10368614400 1050024400 11.361 100 84 145 0 1117025072 53568560 13432112
05:42:35 2440 8192000000 10023007760 10405564416 1058956976 11.446 99 70 136 0 1138128384 64758592 14412816
05:43:40 1362 8192000000 9993119744 10368806912 1035651088 11.223 100 96 177 3 1110541312 57466448 17423776
05:44:42 1288 8192000000 9979525520 10351341568 1020291280 11.075 99 75 141 0 1094430512 59689648 14449584
05:45:45 1384 8192000000 9951487232 10321309696 1029740832 11.166 99 78 147 1 1077607600 31950048 15916720
05:46:48 2808 8192000000 10003486720 10378719232 1033781232 11.205 100 80 154 1 1126765104 78044272 14939600
05:47:58 5507 8192000000 9980195888 10354290688 1048962496 11.351 100 75 126 2 1112570768 52388224 11220048
05:49:14 22705 8192000000 10033389808 10420174848 1064810864 11.502 100 61 107 0 1135898528 60587536 10500128
05:50:44 1320 8192000000 10090162384 10472968192 1087710032 11.721 100 91 168 2 1206925984 99345744 19870208
05:51:51 2889 8192000000 10102083456 10496528384 1103232400 11.868 100 88 169 2 1225786192 103523584 19030208
05:53:00 3378 8192000000 10158612240 10554503168 1115963840 11.989 100 72 141 0 1280705696 148933920 15807936
05:54:10 1504 8192000000 10189376832 10592976896 1134805936 12.167 100 91 178 0 1303300320 148840416 19653968
05:55:17 1991 8192000000 10218562432 10617708544 1144627248 12.259 100 84 166 1 1324038928 160405936 19005744
05:56:23 2021 8192000000 10228279296 10628448256 1153668800 12.344 100 87 165 0 1341161616 169238624 18254192
05:57:28 2082 8192000000 10273612880 10669850624 1163472224 12.436 100 87 163 1 1380117744 199314288 17331232
05:58:33 3503 8192000000 10288441152 10699034624 1162405344 12.426 100 75 147 0 1402897616 223497312 16994960
05:59:42 1270 8192000000 10323741248 10725588992 1175694768 12.550 100 84 165 0 1435750032 240648608 19406656
06:00:48 1134 8192000000 10390152688 10793209856 1185359328 12.640 100 86 168 1 1492682944 288576272 18747344
06:01:54 16597 8192000000 10392036720 10815401984 1178551008 12.577 100 78 155 1 1500347696 302484048 19312640
06:03:18 952 8192000000 10430441968 10867724288 1195477264 12.734 100 99 194 0 1540897040 322502064 22917712
06:04:24 1128 8192000000 10422092352 10837291008 1183732416 12.625 99 97 188 2 1530586224 324388576 22465232
06:05:27 1002 8192000000 10464753312 10887528448 1187140000 12.657 99 94 183 0 1570013808 361058048 21815760
06:06:32 843 8192000000 10509992032 10951262208 1200252560 12.779 100 97 191 0 1615767776 392325712 23189504
06:07:36 2723 8192000000 10532593392 10956574720 1189085968 12.675 100 86 168 0 1633982944 424980896 19916080
06:08:46 1052 8192000000 10560925264 10974199808 1192495888 12.707 100 90 171 0 1658211760 445175120 20540752
06:09:50 743 8192000000 10608105664 11028357120 1206586160 12.837 100 97 192 0 1711865600 481823200 23456240
06:10:55 752 8192000000 10620675520 11047858176 1195401840 12.734 100 99 194 1 1720409504 502634464 22373200
06:11:59 1652 8192000000 10639002048 11095109632 1201278224 12.788 100 85 169 1 1741081600 518612224 21191152
06:13:04 1392 8192000000 10673730000 11143966720 1192699184 12.708 100 65 129 0 1775328272 565621568 17007520
06:14:11 5666 8192000000 10688591584 11139354624 1185404800 12.641 100 55 106 0 1792332368 591648320 15279248
06:15:46 13307 8192000000 10552998768 11063160832 1175083568 12.544 100 38 76 0 1669923200 483817120 11022512
06:17:27 59179 8192000000 10382001024 10885009408 1164641984 12.447 100 31 62 0 1585319344 412664032 8013328
06:19:28 57963 8192000000 10145618224 10660671488 1103448560 11.870 96 25 50 0 1254997424 145976128 5572736
06:21:30 28557 8192000000 10102010928 10544066560 1139001824 12.206 100 27 51 6 1207249472 61421952 6825696
06:23:00 87675 8192000000 10122244848 10572148736 1087812352 11.722 95 24 43 1 1192991344 100310880 4868112
06:25:28 134155 8192000000 10050600256 10514219008 1080275440 11.650 95 25 47 1 1117556144 32115936 5164768
06:28:43 80610 8192000000 10095743152 10539810816 1121394096 12.040 98 25 50 0 1183221408 55863696 5963616
06:31:07 52301 8192000000 10069378320 10499616768 1116625744 11.995 97 33 57 1 1162078608 38709376 6743488
06:33:00 39145 8192000000 10109623632 10547372032 1145405904 12.266 100 46 84 2 1222138944 66946544 9786496
06:34:43 91518 8192000000 10171638880 10591236096 1120934320 12.036 97 39 69 2 1250357600 120644448 8778832
06:37:16 36539 8192000000 10161910416 10656788480 1143231104 12.246 100 35 69 1 1303660576 152917056 7512416
06:38:58 42921 8192000000 10128401232 10612711424 1130907712 12.130 100 8 16 0 1236277120 103721584 1647824
06:41:10 84779 8192000000 10053533008 10498367488 1105536000 11.890 98 13 26 0 1165261520 56880192 2845328
06:43:35 85501 8192000000 10064072352 10508603392 1096560720 11.805 97 15 28 1 1145612160 46137792 2913648
06:46:04 56464 8192000000 10069888320 10564923392 1097417648 11.813 97 30 58 0 1191239184 87427184 6394352
06:48:10 68718 8192000000 10079092240 10509885440 1115085952 11.981 99 28 52 0 1156112192 35252656 5773584
06:50:27 79458 8192000000 10050971104 10501423104 1130217312 12.123 100 11 22 1 1212738192 80367296 2153584
06:52:47 44788 8192000000 10129398240 10573819904 1133306208 12.153 98 22 44 0 1198330256 59965840 5058208
06:54:32 43325 8192000000 10141399584 10580414464 1143919264 12.252 99 24 44 0 1231527440 82520176 5088000
06:56:25 45457 8192000000 10198490016 10674446336 1154066976 12.348 99 40 80 0 1320100400 157236080 8797344
06:58:16 107049 8192000000 10113697264 10619924480 1104695440 11.882 96 10 20 0 1185783120 79035936 2051744
07:01:08 27242 8192000000 10108889152 10554159104 1138603600 12.202 100 38 76 1 1210325872 63816720 7905552
07:02:36 29020 8192000000 10051831216 10477723648 1092722880 11.769 97 31 62 0 1148152784 49134288 6295616
07:04:05 43490 8192000000 10124559808 10548785152 1093037760 11.772 98 41 83 0 1188568752 86705360 8825632
07:05:49 118035 8192000000 10042725680 10490687488 1106794800 11.902 99 31 62 0 1151106944 38301872 6010272
07:08:49 19566 8192000000 10059720624 10495492096 1112482032 11.956 100 41 80 0 1158786848 38115184 8189632
07:10:20 54311 8192000000 10068132144 10515603456 1122334704 12.049 100 17 34 0 1169726320 43763408 3628208
07:12:20 58333 8192000000 10093087344 10539679744 1129716144 12.119 100 14 28 0 1187731680 54733216 3282320
07:14:26 82150 8192000000 10109865600 10557677568 1127371680 12.097 99 19 38 0 1202067488 70550464 4145344
07:17:01 60888 8192000000 10064801632 10494296064 1090309648 11.746 96 14 28 0 1125510432 32371504 2829280
07:19:09 40211 8192000000 10126570112 10564603904 1144552832 12.258 99 30 60 0 1223841184 72817888 6470464
07:20:50 22892 8192000000 10117626704 10549129216 1142097136 12.235 99 40 75 1 1207350432 56360128 8893168
07:22:17 70386 8192000000 10193625616 10632323072 1155402736 12.360 100 28 54 0 1278314736 117133760 5778240
07:24:30 39485 8192000000 10159168496 10619547648 1140734544 12.222 99 16 32 0 1246887792 102022208 4131040
07:26:11 38847 8192000000 10100099872 10528665600 1119931296 12.026 98 20 40 1 1177412784 53401536 4079952
07:28:00 80241 8192000000 10078756960 10607607808 1120004064 12.027 99 20 38 1 1242548448 118203728 4340656
07:30:46 110505 8192000000 10070828048 10528137216 1117616352 12.004 99 37 74 1 1195235680 70295040 7324288
07:33:38 783 8192000000 10069759552 10519953408 1113728352 11.968 99 51 91 0 1175258112 52524560 9005200
07:35:01 64145 8192000000 10048854752 10535309312 1123969120 12.064 100 18 36 0 1193592336 65924832 3698384
07:37:05 109740 8192000000 10082795792 10547322880 1110763776 11.940 98 16 30 0 1152566160 38595664 3206720
07:40:31 85874 8192000000 10071495840 10527387648 1098875344 11.827 97 13 26 0 1147759136 46166736 2717056
07:43:26 57067 8192000000 10132811680 10599215104 1131310592 12.134 99 28 57 0 1221221840 83395808 6515440
07:45:26 34881 8192000000 10126205600 10595737600 1120430368 12.031 98 42 84 0 1228092480 98745360 8916752

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 27-09-2022 07:48:27 to 27-09-2022 07:48:43.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 183 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 27-09-2022 07:48:44 to 27-09-2022 07:48:46.

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          211769
MON$OLDEST_ACTIVE               216257
MON$OLDEST_SNAPSHOT             216257
MON$NEXT_TRANSACTION            216257
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                       2062944
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             768
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2850 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2850 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2850 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2850 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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Tue Sep 27 07:47:01 2022
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Tue Sep 27 07:47:04 2022
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:13: 
:14: 
:15: mscs-test-161.budzdorov.ru    Tue Sep 27 07:47:04 2022
:16:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:17: 
--- end of diff output ---

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

27.09.22 07:48:47. 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-09-27 07:48:50 No severe PSQL-related problems occured
27.09.22 07:48:51. End of report.