Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 13.02.2023 10: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 15103452 kB
MemAvailable 30386236 kB
Buffers 0 kB
Cached 16572112 kB
SwapCached 3376 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 2023-02-13 10:37:26
DTS_END                         2023-02-13 10:37:26.4670
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/35082
TRN_ID                          215210
ATT_ID                          605
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: 8952.78 20043 1074334
customer order: creation 164.02 460 19682
customer order: refuse 13.59 379 1631
order to supplier: creation 44.06 1377 5287
order to supplier: removal 3.44 161232 413
invoice (draft): creation 43.59 3624 5231
invoice (draft): removal 50.16 1062 6019
invoice accept: apply 40.04 24300 4805
invoice accept: cancel 45.59 1242 5471
customer reserve: creation 3787.95 228 454554
customer reserve: removal 4480.56 98 537667
realization accept: apply 78.83 267 9459
realization accept: cancel 78.73 151 9448
payment from customer: creation 55.48 171 6657
payment from customer: removal 5.01 130 601
payment to supplier: creation 46.97 187 5636
payment to supplier: removal 2.98 155 358
service: total inventory turnovers 3.90 4091 468
service: total monetary turnovers 6.85 345 822
service: aggregate perf. data 1.00 15822 120
service: refresh index statistics 0.04 185537 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 13-02-2023 10:38:27 to 13-02-2023 10:38:29.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6818
TEST_TIME 2 6093
TEST_TIME 3 6381
TEST_TIME 4 5854
TEST_TIME 5 6239
TEST_TIME 6 6088
TEST_TIME 7 5963
TEST_TIME 8 6093
TEST_TIME 9 6144
TEST_TIME 10 5971
TEST_TIME 11 6099
TEST_TIME 12 6186
TEST_TIME 13 6329
TEST_TIME 14 6899
TEST_TIME 15 6885
TEST_TIME 16 7042
TEST_TIME 17 7361
TEST_TIME 18 7297
TEST_TIME 19 7816
TEST_TIME 20 8068
TEST_TIME 21 8053
TEST_TIME 22 8455
TEST_TIME 23 8776
TEST_TIME 24 8971
TEST_TIME 25 8944
TEST_TIME 26 9407
TEST_TIME 27 9414
TEST_TIME 28 9922
TEST_TIME 29 10603
TEST_TIME 30 10827
TEST_TIME 31 10888
TEST_TIME 32 10610
TEST_TIME 33 10563
TEST_TIME 34 10562
TEST_TIME 35 10333
TEST_TIME 36 10185
TEST_TIME 37 10225
TEST_TIME 38 10056
TEST_TIME 39 9963
TEST_TIME 40 10009
TEST_TIME 41 10012
TEST_TIME 42 9909
TEST_TIME 43 9829
TEST_TIME 44 9779
TEST_TIME 45 9782
TEST_TIME 46 9851
TEST_TIME 47 9768
TEST_TIME 48 9852
TEST_TIME 49 9887
TEST_TIME 50 9733
TEST_TIME 51 9606
TEST_TIME 52 9462
TEST_TIME 53 9446
TEST_TIME 54 9421
TEST_TIME 55 9477
TEST_TIME 56 9410
TEST_TIME 57 9367
TEST_TIME 58 9481
TEST_TIME 59 9462
TEST_TIME 60 9390
TEST_TIME 61 9339
TEST_TIME 62 9265
TEST_TIME 63 9195
TEST_TIME 64 9113
TEST_TIME 65 9062
TEST_TIME 66 9178
TEST_TIME 67 9118
TEST_TIME 68 9186
TEST_TIME 69 9181
TEST_TIME 70 9131
TEST_TIME 71 9207
TEST_TIME 72 9264
TEST_TIME 73 9322
TEST_TIME 74 9315
TEST_TIME 75 9374
TEST_TIME 76 9387
TEST_TIME 77 9348
TEST_TIME 78 9357
TEST_TIME 79 9281
TEST_TIME 80 9277
TEST_TIME 81 9316
TEST_TIME 82 9329
TEST_TIME 83 9261
TEST_TIME 84 9234
TEST_TIME 85 9206
TEST_TIME 86 9139
TEST_TIME 87 9083
TEST_TIME 88 9039
TEST_TIME 89 9021
TEST_TIME 90 8983
TEST_TIME 91 8954
TEST_TIME 92 8942
TEST_TIME 93 9007
TEST_TIME 94 9014
TEST_TIME 95 8978
TEST_TIME 96 8954
TEST_TIME 97 8937
TEST_TIME 98 8962
TEST_TIME 99 8982
TEST_TIME 100 8999
TEST_TIME 101 9038
TEST_TIME 102 9115
TEST_TIME 103 9108
TEST_TIME 104 9231
TEST_TIME 105 9224
TEST_TIME 106 9171
TEST_TIME 107 9122
TEST_TIME 108 9073
TEST_TIME 109 9075
TEST_TIME 110 9037
TEST_TIME 111 8994
TEST_TIME 112 9013
TEST_TIME 113 8963
TEST_TIME 114 8971
TEST_TIME 115 9037
TEST_TIME 116 9014
TEST_TIME 117 8993
TEST_TIME 118 8988
TEST_TIME 119 8989
TEST_TIME 120 8973

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 13-02-2023 10:38:31 to 13-02-2023 10:38:34.

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 1492818 1492818 0 0.00 0 15739 12 0 0
doc_list_biud 1492815 1492815 0 0.00 0 110181 41 0 0
sp_add_invoice_to_stock 4876 4805 71 1.45 37 215799 24301 0 71
sp_cancel_adding_invoice 5471 5471 0 0.00 6 93903 1242 0 0
sp_cancel_client_order 1631 1631 0 0.00 6 8708 380 0 0
sp_cancel_customer_reserve 537667 537667 0 0.00 1 65309 99 0 0
sp_cancel_pay_from_customer 601 601 0 0.00 4 4298 130 0 0
sp_cancel_pay_to_supplier 358 358 0 0.00 4 10281 155 0 0
sp_cancel_supplier_invoice 6027 6019 8 0.13 3 110186 1063 0 8
sp_cancel_supplier_order 413 413 0 0.00 9 1239641 161232 0 0
sp_cancel_write_off 9448 9448 0 0.00 2 11602 152 0 0
sp_client_order 19682 19682 0 0.00 5 25020 460 0 0
sp_customer_reserve 454554 454554 0 0.00 0 106853 229 0 0
sp_fill_shopping_cart 30192 30192 0 0.00 1 13662 81 0 0
sp_fill_shopping_cart_clo_res 454530 454530 0 0.00 0 16293 19 0 0
sp_get_clo_for_invoice 4768 4768 0 0.00 0 19204 279 0 0
sp_kill_cost_storno 15878 15878 0 0.00 0 6586 67 0 0
sp_kill_qstorno_ret_qs2qd 1091214 1091214 0 0.00 0 110179 45 0 0
sp_kill_qty_storno 574874 574874 0 0.00 0 110180 100 0 0
sp_lock_selected_doc 19637 19637 0 0.00 0 8164 7 0 0
sp_make_cost_storno 26518 26518 0 0.00 0 10731 76 0 0
sp_multiply_rows_for_pdistr 26518 26518 0 0.00 0 10808 37 0 0
sp_multiply_rows_for_qdistr 19682 19682 0 0.00 0 16466 173 0 0
sp_pay_from_customer 6657 6657 0 0.00 5 14156 172 0 0
sp_pay_to_supplier 5636 5636 0 0.00 6 14523 188 0 0
sp_payment_common 12292 12292 0 0.00 2 10933 48 0 0
sp_qd_handle_on_cancel_clo 1631 1631 0 0.00 1 8697 306 0 0
sp_qd_handle_on_invoice_upd_sts 10237 10237 0 0.00 0 24870 482 0 0
sp_qd_handle_on_reserve_upd_sts 9459 9459 0 0.00 0 23938 60 0 0
sp_reserve_write_off 9459 9459 0 0.00 8 24009 268 0 0
sp_supplier_invoice 5231 5231 0 0.00 9 124670 3624 0 0
sp_supplier_order 5287 5287 0 0.00 8 55670 1378 0 0
srv_aggregate_perf_data 120 120 0 0.00 2597 183657 15822 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 685 124952 35424 0 0
srv_find_qd_qs_mism 1027229 1027229 0 0.00 0 9501 9 0 0
srv_make_invnt_saldo 468 468 0 0.00 51 91857 4092 0 0
srv_make_money_saldo 822 822 0 0.00 1 12501 346 0 0
srv_recalc_idx_stat 5 5 0 0.00 39217 299327 185538 0 0
t$perf-abend:sp_add_invoice_to_stock 71 71 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4805 4805 0 0.00 0 12559 776 0 0
t$perf-norm:sp_cancel_adding_invoice 437 437 0 0.00 1 7462 422 0 0
t$perf-norm:sp_cancel_client_order 1631 1631 0 0.00 0 4244 24 0 0
t$perf-norm:sp_cancel_customer_reserve 658 658 0 0.00 0 4524 36 0 0
t$perf-norm:sp_cancel_pay_from_customer 601 601 0 0.00 0 1546 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 358 358 0 0.00 0 413 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 359 359 0 0.00 0 2466 38 0 0
t$perf-norm:sp_cancel_supplier_order 413 413 0 0.00 0 89885 5857 0 0
t$perf-norm:sp_cancel_write_off 955 955 0 0.00 0 4005 26 0 0
t$perf-norm:sp_client_order 19682 19682 0 0.00 0 13925 33 0 0
t$perf-norm:sp_customer_reserve 940 940 0 0.00 0 1754 18 0 0
t$perf-norm:sp_pay_from_customer 6657 6657 0 0.00 0 5954 19 0 0
t$perf-norm:sp_pay_to_supplier 5636 5636 0 0.00 0 6747 20 0 0
t$perf-norm:sp_reserve_write_off 9459 9459 0 0.00 0 4587 21 0 0
t$perf-norm:sp_supplier_invoice 5231 5231 0 0.00 0 4605 28 0 0
t$perf-norm:sp_supplier_order 5287 5287 0 0.00 0 4719 29 0 0
t$perf-norm:srv_aggregate_perf_data 120 120 0 0.00 0 488 19 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 83 5 0 0
t$perf-norm:srv_make_invnt_saldo 468 468 0 0.00 0 3344 24 0 0
t$perf-norm:srv_make_money_saldo 822 822 0 0.00 0 962 5 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 10 174 83 0 0
v_add_invoice_to_stock 9674 9532 142 1.46 0 13632 26 0 142
v_all_customers 36124 36124 0 0.00 0 65 3 0 0
v_all_suppliers 29710 29710 0 0.00 0 97 4 0 0
v_all_wares 39364 39364 0 0.00 0 68 3 0 0
v_cancel_adding_invoice 874 874 0 0.00 0 3097 18 0 0
v_cancel_client_order 3262 3262 0 0.00 0 1754 9 0 0
v_cancel_customer_prepayment 1202 1202 0 0.00 0 1842 15 0 0
v_cancel_customer_reserve 1316 1316 0 0.00 1 5901 13 0 0
v_cancel_payment_to_supplier 716 716 0 0.00 0 10051 41 0 0
v_cancel_supplier_invoice 734 718 16 2.17 0 13755 55 0 16
v_cancel_supplier_order 826 826 0 0.00 0 1030 11 0 0
v_cancel_write_off 1910 1910 0 0.00 0 4862 22 0 0
v_max_id_clo_ord 5287 5287 0 0.00 0 1191 5 0 0
v_max_id_clo_res 940 940 0 0.00 0 1231 10 0 0
v_max_id_ord_sup 5223 5223 0 0.00 0 10356 9 0 0
v_max_non_paid_invoice 1289 1289 0 0.00 0 136 7 0 0
v_max_non_paid_realizn 3278 3278 0 0.00 0 5586 10 0 0
v_min_id_clo_ord 5287 5287 0 0.00 0 2646 8 0 0
v_min_id_clo_res 940 940 0 0.00 0 3962 14 0 0
v_min_id_ord_sup 5223 5223 0 0.00 0 5260 6 0 0
v_min_non_paid_invoice 5636 5636 0 0.00 0 10881 21 0 0
v_min_non_paid_realizn 6655 6655 0 0.00 0 9714 24 0 0
v_our_firm 9992 9992 0 0.00 0 95 4 0 0
v_reserve_write_off 18916 18916 0 0.00 0 9895 12 0 0
x_lock_depdocs_on_canc_invoice 11131 11131 0 0.00 0 19698 131 0 0
x_lock_depdocs_on_canc_sup_ord 413 413 0 0.00 0 25406 384 0 0
x_make_qstorno_1000_1200 5287 5287 0 0.00 2 55662 1227 0 0
x_make_qstorno_1200_2000 5231 5231 0 0.00 3 122338 3383 0 0
x_make_qstorno_2100_3300 454551 454551 0 0.00 0 106849 200 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 13-02-2023 10:38:35 to 13-02-2023 10:38:39.

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
08:07:30 934 8192000000 8936643568 8993333248 14719520 0.179 5 3 6 0 15264384 193200 351664
08:08:32 26395 8192000000 9733746448 10033766400 802925488 8.926 100 92 184 0 832942320 7271312 22745520
08:10:05 18357 8192000000 9764350960 10072100864 839846352 9.298 100 90 180 0 873743088 12595760 21300976
08:11:29 8668 8192000000 9801022400 10120314880 871687488 9.617 100 85 168 0 909195200 17981008 19526704
08:12:43 11028 8192000000 9862728608 10192130048 908263456 9.980 100 87 172 0 975058880 47713296 19082128
08:14:00 4451 8192000000 9907135760 10247598080 953478064 10.425 100 84 165 0 1020827328 49375648 17973616
08:15:08 2193 8192000000 9974934816 10327101440 995013696 10.830 100 87 169 0 1090360912 78423392 16923824
08:16:11 1005 8192000000 10000686992 10365624320 1026320704 11.133 100 93 183 3 1122108704 77534800 18253200
08:17:14 1006 8192000000 10011324816 10384236544 1046888112 11.331 100 98 190 2 1136654080 71480544 18285424
08:18:17 1065 8192000000 10013431728 10387271680 1058920784 11.446 100 93 176 1 1130886784 54624368 17341632
08:19:19 1018 8192000000 10062183456 10441023488 1067387152 11.527 100 94 182 1 1180921312 95159120 18375040
08:20:22 1009 8192000000 10050192544 10432229376 1069322992 11.546 100 95 183 3 1168233376 81264560 17645824
08:21:24 1040 8192000000 10034812112 10421923840 1077025776 11.619 100 91 176 2 1160020000 63597040 19397184
08:22:26 3410 8192000000 10029801520 10421121024 1079314608 11.641 100 67 131 0 1152045504 60049536 12681360
08:23:31 1694 8192000000 10045504384 10435600384 1085705088 11.702 100 84 160 2 1174622704 70842800 18074816
08:24:33 999 8192000000 10049801232 10437820416 1087047216 11.715 100 95 178 0 1172977664 67231648 18698800
08:25:35 1220 8192000000 10064872240 10455285760 1090672272 11.749 100 88 167 0 1188020752 80804064 16544416
08:26:37 1400 8192000000 10055586976 10451046400 1093224160 11.773 100 94 176 1 1185391344 71833632 20333552
08:27:39 1034 8192000000 10062452656 10452074496 1095708064 11.797 100 92 167 2 1181729456 68946800 17074592
08:28:41 997 8192000000 10033129312 10422906880 1080535120 11.653 100 86 164 0 1155265104 57459760 17270224
08:29:44 1039 8192000000 10036713648 10418761728 1072981632 11.581 100 92 169 3 1159553152 67919712 18651808
08:30:46 1110 8192000000 10015401968 10392936448 1060650560 11.463 100 96 188 4 1138811520 56693088 21467872
08:31:48 1037 8192000000 10026972096 10408026112 1066997120 11.523 100 92 181 1 1151589200 64867408 19724672
08:32:50 970 8192000000 10023252512 10406256640 1070394160 11.556 100 81 160 2 1142342016 55826448 16121408
08:33:52 930 8192000000 10007636192 10384236544 1054887552 11.408 100 93 177 0 1128796928 55203104 18706272
08:34:54 2231 8192000000 9965407376 10339631104 1035903792 11.225 100 74 144 0 1087024672 36254000 14866880
08:35:57 1225 8192000000 10007000304 10383990784 1045288832 11.315 100 87 170 1 1129469392 66957616 17222944
08:37:00 2387 8192000000 10008303104 10389336064 1052249968 11.382 99 85 160 2 1137465984 68892736 16323280
08:38:08 37506 8192000000 10021858272 10401517568 1064808464 11.502 99 74 142 0 1129571696 51026272 13736960
08:39:52 9023 8192000000 10073603520 10462633984 1095995280 11.800 100 79 150 0 1190195936 78930960 15269696
08:41:07 11391 8192000000 10092518768 10478321664 1110352112 11.936 100 74 136 0 1204263264 80279680 13631472
08:42:27 3277 8192000000 10161248240 10554036224 1128936624 12.111 100 75 142 0 1277270688 132892592 15441472
08:43:36 5109 8192000000 10174529168 10581315584 1145565024 12.268 100 59 115 0 1291438944 133185200 12688720
08:44:44 1620 8192000000 10207158288 10609885184 1153703504 12.344 100 77 139 2 1324629328 154625088 16300736
08:45:48 1943 8192000000 10271251152 10671730688 1160629200 12.409 100 96 184 1 1382472544 201192160 20651184
08:46:54 1607 8192000000 10287592352 10685992960 1172113872 12.517 100 96 181 0 1401422896 209653600 19655424
08:47:58 4695 8192000000 10327072576 10741366784 1177973600 12.571 100 66 131 1 1442053488 249420848 14659040
08:49:07 866 8192000000 10364092080 10769252352 1189083360 12.675 100 99 185 1 1468676624 258648752 20944512
08:50:12 675 8192000000 10420660256 10823667712 1201279648 12.788 100 100 196 1 1532952848 308968224 22704976
08:51:17 941 8192000000 10377767728 10821959680 1187947776 12.664 100 96 181 0 1492444192 284069648 20426768
08:52:20 768 8192000000 10427286496 10837831680 1204213008 12.815 100 98 188 1 1536789824 310424816 22152000
08:53:24 2267 8192000000 10456651616 10903633920 1208261584 12.853 100 95 185 1 1564634128 334575728 21796816
08:54:30 1982 8192000000 10461273168 10879447040 1207035728 12.842 100 84 168 0 1566783168 340052544 19694896
08:55:38 745 8192000000 10472126256 10928533504 1210579376 12.874 100 92 177 3 1578639008 347216688 20842944
08:56:43 2443 8192000000 10483431040 10931941376 1194396400 12.724 99 86 171 0 1583628832 368374016 20858416
08:57:48 4451 8192000000 10501732864 10934566912 1187212208 12.657 99 66 130 1 1595899040 392171456 16515376
08:58:58 6183 8192000000 10477010832 10969440256 1203373264 12.808 100 53 104 2 1603126352 385513232 14239856
09:00:10 15223 8192000000 10482422480 10961059840 1204070800 12.814 100 53 103 1 1600195936 382949216 13175920
09:01:48 10852 8192000000 10468672192 10928521216 1208919408 12.859 100 48 95 1 1579422880 359033184 11470288
09:03:00 13168 8192000000 10419223360 10889355264 1164676864 12.447 98 32 62 1 1534926720 361735664 8514192
09:04:15 28471 8192000000 10396924096 10854297600 1175228272 12.546 100 26 52 0 1502837552 320725520 6883760
09:05:48 15968 8192000000 10281517936 10744696832 1149099008 12.301 100 25 48 1 1393494720 238442336 5953376
09:07:07 22046 8192000000 10137219280 10636886016 1137810176 12.195 98 19 38 0 1228098624 85945152 4343296
09:08:31 65289 8192000000 10107313152 10544357376 1140603840 12.221 99 41 80 0 1222782320 72589648 9588832
09:10:39 29491 8192000000 10084554144 10536693760 1104247696 11.878 97 11 22 0 1169073056 62511584 2313776
09:12:09 31607 8192000000 10096546336 10576629760 1139686672 12.213 100 11 22 0 1197774304 55777760 2309872
09:13:40 70613 8192000000 10098193376 10560819200 1148867856 12.299 100 29 56 1 1251301152 96520944 5912352
09:15:53 29455 8192000000 10121969872 10547920896 1141885776 12.233 99 42 83 0 1202421072 50377936 10157360
09:17:27 107445 8192000000 10057964080 10508832768 1055431984 11.413 92 6 12 0 1092083584 35680064 971536
09:20:17 84219 8192000000 10098776048 10557579264 1084477872 11.690 95 25 49 0 1156362944 66347776 5537296
09:22:42 89666 8192000000 10063484416 10542596096 1081988048 11.666 96 14 26 0 1140310064 55882384 2439632
09:25:15 106658 8192000000 10063108064 10562183168 1094519488 11.786 97 11 18 1 1146788480 49893536 2375456
09:28:03 99281 8192000000 10162560720 10602553344 1135139552 12.170 100 33 62 0 1253973648 111477696 7356400
09:31:30 89239 8192000000 10106103712 10547617792 1105158208 11.887 97 32 64 0 1195930960 84057136 6715616
09:34:00 32947 8192000000 10120192368 10559127552 1111641168 11.948 98 43 83 1 1208981616 87105760 10234688
09:35:56 30539 8192000000 10054481520 10503979008 1119604512 12.023 100 15 31 1 1167367104 44464720 3297872
09:37:41 124952 8192000000 10030133936 10491789312 1065479296 11.509 94 19 36 0 1109247808 40187168 3581344
09:40:51 103111 8192000000 10127394560 10571128832 1138552656 12.202 99 18 34 1 1208486048 66042256 3891136
09:43:41 27227 8192000000 10127331456 10582659072 1142246368 12.237 99 24 48 0 1244734896 96973488 5515040
09:45:08 21589 8192000000 10168361520 10611314688 1118741056 12.015 97 28 56 0 1252299920 127196096 6362768
09:46:36 55708 8192000000 10161681504 10598727680 1124704208 12.071 98 32 63 0 1236146848 104154160 7288480
09:48:33 26552 8192000000 10137913424 10574860288 1096257504 11.802 96 36 70 0 1211462912 107328576 7876832
09:50:01 31224 8192000000 10112562816 10567507968 1127414768 12.097 99 34 68 0 1242923712 108102736 7406208
09:51:32 29187 8192000000 10127864384 10561429504 1131913456 12.139 99 44 84 1 1207321312 65847040 9560816
09:53:06 45769 8192000000 10125388304 10573062144 1118433168 12.012 98 16 32 0 1215935824 94169040 3333616
09:54:52 80329 8192000000 10189428304 10627096576 1136563760 12.183 99 26 52 0 1249954464 107320096 6070608
09:58:33 26150 8192000000 10102889472 10588921856 1120758784 12.034 98 55 110 0 1219809712 87690864 11360064
10:00:08 56492 8192000000 10071966256 10525126656 1142163552 12.236 100 13 24 0 1180082144 35454816 2463776
10:02:09 53288 8192000000 10061169744 10531115008 1136080752 12.179 100 18 34 0 1166238432 26278672 3879008
10:04:03 54405 8192000000 10073628112 10523127808 1122965888 12.055 98 23 44 0 1164236560 36884736 4385936
10:06:57 38355 8192000000 10075037136 10522431488 1091950672 11.761 97 21 42 0 1139195008 43237440 4006896
10:08:41 84068 8192000000 10097285552 10580226048 1130942240 12.130 100 23 46 0 1228941792 93192704 4806848
10:11:06 30326 8192000000 10051718192 10491412480 1094492064 11.785 97 12 24 0 1134700080 38064352 2143664
10:12:40 13668 8192000000 10118185888 10544275456 1128440400 12.107 100 46 92 0 1210250064 72374640 9435024
10:13:58 8403 8192000000 10154673936 10580217856 1126749920 12.091 99 50 98 0 1243446832 106245216 10451696
10:15:06 12946 8192000000 10224809712 10653646848 1139841680 12.214 99 56 108 0 1306249536 154407424 12000432
10:16:27 11782 8192000000 10201955360 10646122496 1137163840 12.189 99 39 78 0 1290802416 144581456 9057120
10:17:46 8579 8192000000 10187642016 10615984128 1130408160 12.125 99 49 93 2 1273989744 132687536 10894048
10:18:58 30937 8192000000 10168097824 10618408960 1150928016 12.318 100 25 46 1 1282294272 126197568 5168688
10:20:32 46314 8192000000 10138679648 10651140096 1116611696 11.995 97 25 50 0 1215239008 93424608 5202704
10:22:27 37350 8192000000 10073794704 10497355776 1113561968 11.966 98 43 84 1 1155076160 33618320 7895872
10:24:05 71178 8192000000 10083925696 10519351296 1126347840 12.087 99 40 80 0 1164092736 29760800 7984096
10:26:17 31822 8192000000 10136924016 10582347776 1141898560 12.233 100 58 116 0 1228143776 74606752 11638464
10:27:57 100293 8192000000 10088889792 10581864448 1101828640 11.855 96 13 26 0 1190806512 85908784 3069088
10:30:38 52916 8192000000 10144599840 10619293696 1144523456 12.258 100 25 50 0 1227803360 77968096 5311808
10:32:55 35218 8192000000 10081889744 10525130752 1135935936 12.177 99 20 40 1 1166073136 25940688 4196512
10:34:38 55825 8192000000 10094248352 10551435264 1123804352 12.063 99 20 38 1 1170786848 42540528 4441968
10:36:34 3825 8192000000 10077115552 10495803392 1133641024 12.156 100 81 141 3 1173457024 24294624 15521376

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 13-02-2023 10:38:41 to 13-02-2023 10:38:58.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 71 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 13-02-2023 10:38:59 to 13-02-2023 10:39:01.

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          213447
MON$OLDEST_ACTIVE               215471
MON$OLDEST_SNAPSHOT             215471
MON$NEXT_TRANSACTION            215471
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                       2054688
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             767
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2899 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2899 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2899 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2899 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 ---
:267: 
:268: mscs-test-161.budzdorov.ru    Mon Feb 13 10:37:30 2023
:269:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:270: 
:271: 
:272: mscs-test-161.budzdorov.ru    Mon Feb 13 10:37:31 2023
:273:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:274: 
:275: 
:276: mscs-test-161.budzdorov.ru    Mon Feb 13 10:37:31 2023
:277:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:278: 
--- end of diff output ---

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

13.02.23 10:39:02. 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
2023-02-13 10:39:04 No severe PSQL-related problems occured
13.02.23 10:39:06. End of report.