Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 10.05.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 16096936 kB
MemAvailable 31449996 kB
Buffers 0 kB
Cached 15572200 kB
SwapCached 2608 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-05-10 13:37:14
DTS_END                         2022-05-10 13:37:14.5570
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/36404
TRN_ID                          219357
ATT_ID                          630
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: 8995.55 25464 1079466
customer order: creation 167.10 450 20052
customer order: refuse 14.84 382 1781
order to supplier: creation 44.49 1303 5339
order to supplier: removal 3.23 157638 388
invoice (draft): creation 46.14 3098 5537
invoice (draft): removal 49.95 948 5994
invoice accept: apply 41.31 25659 4957
invoice accept: cancel 45.46 1216 5455
customer reserve: creation 3849.68 241 461962
customer reserve: removal 4440.35 89 532842
realization accept: apply 82.86 279 9943
realization accept: cancel 82.65 160 9918
payment from customer: creation 58.41 182 7009
payment from customer: removal 5.18 116 621
payment to supplier: creation 47.86 192 5743
payment to supplier: removal 3.31 117 397
service: total inventory turnovers 4.14 3952 497
service: total monetary turnovers 7.69 342 923
service: aggregate perf. data 0.87 18006 104
service: refresh index statistics 0.03 294909 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 10-05-2022 13:38:23 to 10-05-2022 13:38:26.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9993
TEST_TIME 2 8124
TEST_TIME 3 6887
TEST_TIME 4 6796
TEST_TIME 5 6664
TEST_TIME 6 7005
TEST_TIME 7 6845
TEST_TIME 8 7055
TEST_TIME 9 6849
TEST_TIME 10 6829
TEST_TIME 11 7091
TEST_TIME 12 6996
TEST_TIME 13 7145
TEST_TIME 14 7267
TEST_TIME 15 7428
TEST_TIME 16 7839
TEST_TIME 17 8190
TEST_TIME 18 8261
TEST_TIME 19 8311
TEST_TIME 20 8395
TEST_TIME 21 8436
TEST_TIME 22 8587
TEST_TIME 23 8583
TEST_TIME 24 8551
TEST_TIME 25 8862
TEST_TIME 26 8961
TEST_TIME 27 9021
TEST_TIME 28 9147
TEST_TIME 29 9569
TEST_TIME 30 10117
TEST_TIME 31 10287
TEST_TIME 32 10520
TEST_TIME 33 11175
TEST_TIME 34 11404
TEST_TIME 35 11409
TEST_TIME 36 11382
TEST_TIME 37 11176
TEST_TIME 38 11031
TEST_TIME 39 10919
TEST_TIME 40 11110
TEST_TIME 41 10926
TEST_TIME 42 10771
TEST_TIME 43 10588
TEST_TIME 44 10534
TEST_TIME 45 10561
TEST_TIME 46 10459
TEST_TIME 47 10303
TEST_TIME 48 10309
TEST_TIME 49 10225
TEST_TIME 50 10140
TEST_TIME 51 10006
TEST_TIME 52 9942
TEST_TIME 53 9854
TEST_TIME 54 9855
TEST_TIME 55 9856
TEST_TIME 56 9788
TEST_TIME 57 9728
TEST_TIME 58 9701
TEST_TIME 59 9661
TEST_TIME 60 9540
TEST_TIME 61 9570
TEST_TIME 62 9492
TEST_TIME 63 9478
TEST_TIME 64 9443
TEST_TIME 65 9445
TEST_TIME 66 9425
TEST_TIME 67 9412
TEST_TIME 68 9446
TEST_TIME 69 9438
TEST_TIME 70 9490
TEST_TIME 71 9495
TEST_TIME 72 9545
TEST_TIME 73 9619
TEST_TIME 74 9550
TEST_TIME 75 9558
TEST_TIME 76 9554
TEST_TIME 77 9582
TEST_TIME 78 9487
TEST_TIME 79 9413
TEST_TIME 80 9443
TEST_TIME 81 9404
TEST_TIME 82 9374
TEST_TIME 83 9357
TEST_TIME 84 9332
TEST_TIME 85 9287
TEST_TIME 86 9247
TEST_TIME 87 9228
TEST_TIME 88 9233
TEST_TIME 89 9188
TEST_TIME 90 9241
TEST_TIME 91 9205
TEST_TIME 92 9235
TEST_TIME 93 9216
TEST_TIME 94 9191
TEST_TIME 95 9214
TEST_TIME 96 9313
TEST_TIME 97 9304
TEST_TIME 98 9298
TEST_TIME 99 9270
TEST_TIME 100 9344
TEST_TIME 101 9262
TEST_TIME 102 9224
TEST_TIME 103 9165
TEST_TIME 104 9144
TEST_TIME 105 9130
TEST_TIME 106 9103
TEST_TIME 107 9098
TEST_TIME 108 9065
TEST_TIME 109 9032
TEST_TIME 110 9022
TEST_TIME 111 9010
TEST_TIME 112 9041
TEST_TIME 113 9023
TEST_TIME 114 9035
TEST_TIME 115 9065
TEST_TIME 116 9071
TEST_TIME 117 9074
TEST_TIME 118 9049
TEST_TIME 119 9059
TEST_TIME 120 9022

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 10-05-2022 13:38:27 to 10-05-2022 13:38:30.

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 1503815 1503815 0 0.00 0 18808 13 0 0
doc_list_biud 1503797 1503797 0 0.00 0 45437 38 0 0
sp_add_invoice_to_stock 5071 4957 114 2.24 31 217172 25659 0 114
sp_cancel_adding_invoice 5455 5455 0 0.00 5 66187 1216 0 0
sp_cancel_client_order 1781 1781 0 0.00 6 20347 382 0 0
sp_cancel_customer_reserve 532842 532842 0 0.00 1 45440 90 0 0
sp_cancel_pay_from_customer 621 621 0 0.00 4 2237 117 0 0
sp_cancel_pay_to_supplier 397 397 0 0.00 4 1522 117 0 0
sp_cancel_supplier_invoice 6003 5994 9 0.14 2 18976 949 0 9
sp_cancel_supplier_order 388 388 0 0.00 11 1556864 157638 0 0
sp_cancel_write_off 9918 9918 0 0.00 2 9688 161 0 0
sp_client_order 20052 20052 0 0.00 6 21039 451 0 0
sp_customer_reserve 461962 461962 0 0.00 1 132015 242 0 0
sp_fill_shopping_cart 30916 30916 0 0.00 1 24359 88 0 0
sp_fill_shopping_cart_clo_res 461926 461926 0 0.00 0 14875 19 0 0
sp_get_clo_for_invoice 4911 4911 0 0.00 0 28285 311 0 0
sp_kill_cost_storno 16391 16391 0 0.00 0 12230 66 0 0
sp_kill_qstorno_ret_qs2qd 1081984 1081984 0 0.00 0 45433 38 0 0
sp_kill_qty_storno 571228 571228 0 0.00 0 45437 90 0 0
sp_lock_selected_doc 20513 20513 0 0.00 0 6946 7 0 0
sp_make_cost_storno 27604 27604 0 0.00 0 18787 79 0 0
sp_multiply_rows_for_pdistr 27604 27604 0 0.00 0 10019 35 0 0
sp_multiply_rows_for_qdistr 20052 20052 0 0.00 0 12797 172 0 0
sp_pay_from_customer 7009 7009 0 0.00 5 9334 183 0 0
sp_pay_to_supplier 5743 5743 0 0.00 6 17234 193 0 0
sp_payment_common 12751 12751 0 0.00 2 6041 48 0 0
sp_qd_handle_on_cancel_clo 1781 1781 0 0.00 1 20339 315 0 0
sp_qd_handle_on_invoice_upd_sts 10363 10363 0 0.00 0 24905 517 0 0
sp_qd_handle_on_reserve_upd_sts 9942 9942 0 0.00 0 18923 63 0 0
sp_reserve_write_off 9943 9943 0 0.00 9 18946 279 0 0
sp_supplier_invoice 5537 5537 0 0.00 9 106879 3098 0 0
sp_supplier_order 5339 5339 0 0.00 7 84375 1303 0 0
srv_aggregate_perf_data 104 104 0 0.00 2417 148402 18006 0 0
srv_fill_mon_cache_memory 68 68 0 0.00 760 114128 38916 0 0
srv_find_qd_qs_mism 1030649 1030649 0 0.00 0 14357 10 0 0
srv_make_invnt_saldo 497 497 0 0.00 89 87019 3952 0 0
srv_make_money_saldo 923 923 0 0.00 1 8938 342 0 0
srv_recalc_idx_stat 4 4 0 0.00 57149 435445 294910 0 0
t$perf-abend:sp_add_invoice_to_stock 114 114 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4961 4961 0 0.00 0 20432 1015 0 0
t$perf-norm:sp_cancel_adding_invoice 403 403 0 0.00 1 6417 530 0 0
t$perf-norm:sp_cancel_client_order 1781 1781 0 0.00 0 2662 28 0 0
t$perf-norm:sp_cancel_customer_reserve 674 674 0 0.00 0 527 22 0 0
t$perf-norm:sp_cancel_pay_from_customer 621 621 0 0.00 0 1280 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 397 397 0 0.00 0 308 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 402 402 0 0.00 0 4934 40 0 0
t$perf-norm:sp_cancel_supplier_order 388 388 0 0.00 0 97669 6569 0 0
t$perf-norm:sp_cancel_write_off 1006 1006 0 0.00 0 674 16 0 0
t$perf-norm:sp_client_order 20052 20052 0 0.00 0 12137 35 0 0
t$perf-norm:sp_customer_reserve 1001 1001 0 0.00 0 1609 13 0 0
t$perf-norm:sp_pay_from_customer 7009 7009 0 0.00 0 3711 19 0 0
t$perf-norm:sp_pay_to_supplier 5743 5743 0 0.00 0 7607 22 0 0
t$perf-norm:sp_reserve_write_off 9943 9943 0 0.00 0 14351 26 0 0
t$perf-norm:sp_supplier_invoice 5537 5537 0 0.00 0 5085 30 0 0
t$perf-norm:sp_supplier_order 5342 5342 0 0.00 0 1681 30 0 0
t$perf-norm:srv_aggregate_perf_data 104 104 0 0.00 0 169 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 68 68 0 0.00 0 689 17 0 0
t$perf-norm:srv_make_invnt_saldo 497 497 0 0.00 0 2451 18 0 0
t$perf-norm:srv_make_money_saldo 923 923 0 0.00 0 388 4 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 179 65 0 0
v_add_invoice_to_stock 10026 9798 228 2.27 0 6269 24 0 228
v_all_customers 36770 36770 0 0.00 0 59 4 0 0
v_all_suppliers 30050 30050 0 0.00 0 74 4 0 0
v_all_wares 40098 40098 0 0.00 0 98 4 0 0
v_cancel_adding_invoice 806 806 0 0.00 0 2115 18 0 0
v_cancel_client_order 3562 3562 0 0.00 0 1958 9 0 0
v_cancel_customer_prepayment 1242 1242 0 0.00 0 1488 12 0 0
v_cancel_customer_reserve 1348 1348 0 0.00 0 2046 10 0 0
v_cancel_payment_to_supplier 794 794 0 0.00 0 651 10 0 0
v_cancel_supplier_invoice 822 804 18 2.18 0 6722 31 0 18
v_cancel_supplier_order 776 776 0 0.00 0 6438 16 0 0
v_cancel_write_off 2012 2012 0 0.00 0 6560 27 0 0
v_max_id_clo_ord 5336 5336 0 0.00 0 4061 6 0 0
v_max_id_clo_res 1001 1001 0 0.00 0 2749 12 0 0
v_max_id_ord_sup 5530 5530 0 0.00 0 4703 7 0 0
v_max_non_paid_invoice 1584 1584 0 0.00 0 913 8 0 0
v_max_non_paid_realizn 3682 3682 0 0.00 0 5558 13 0 0
v_min_id_clo_ord 5336 5336 0 0.00 0 7722 11 0 0
v_min_id_clo_res 1001 1001 0 0.00 0 2651 14 0 0
v_min_id_ord_sup 5530 5530 0 0.00 0 7568 15 0 0
v_min_non_paid_invoice 5742 5742 0 0.00 0 4813 16 0 0
v_min_non_paid_realizn 7008 7008 0 0.00 0 5334 18 0 0
v_our_firm 9980 9980 0 0.00 0 59 5 0 0
v_reserve_write_off 19884 19884 0 0.00 0 6928 12 0 0
x_lock_depdocs_on_canc_invoice 11047 11047 0 0.00 0 11718 115 0 0
x_lock_depdocs_on_canc_sup_ord 388 388 0 0.00 0 9442 357 0 0
x_make_qstorno_1000_1200 5339 5339 0 0.00 3 84320 1129 0 0
x_make_qstorno_1200_2000 5537 5537 0 0.00 3 106706 2861 0 0
x_make_qstorno_2100_3300 461960 461960 0 0.00 0 132013 210 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 10-05-2022 13:38:31 to 10-05-2022 13:38:36.

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:18 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:18 32119 8192000000 9729713264 10031689728 804697984 8.944 100 87 174 0 833333088 7963872 20671232
11:10:03 20350 8192000000 9768784080 10081673216 847127680 9.371 100 87 174 0 881594192 14583520 19882992
11:11:32 17491 8192000000 9814965888 10137563136 877169424 9.671 100 88 176 0 922747904 25492848 20085632
11:12:54 12713 8192000000 9864060880 10200670208 911197792 10.009 100 75 150 2 977693328 50069344 16426192
11:14:09 4046 8192000000 9908333440 10251079680 954176208 10.432 100 85 167 1 1036957104 65662304 17118592
11:15:14 2254 8192000000 9953920352 10305503232 989379200 10.775 100 92 181 0 1070514928 62248528 18887200
11:16:19 1273 8192000000 10004830816 10365870080 1017616528 11.049 100 85 167 1 1118497216 84719008 16161680
11:17:22 1033 8192000000 10029952208 10401452032 1048055360 11.342 100 95 180 1 1156560864 89756912 18748592
11:18:24 1097 8192000000 10037327168 10412425216 1064151712 11.496 100 91 176 2 1155351840 74519120 16681008
11:19:26 1135 8192000000 10061462464 10444021760 1069714592 11.549 100 96 185 1 1180869440 92423664 18731184
11:20:28 1084 8192000000 10031615616 10421194752 1077644096 11.625 100 77 152 0 1155082592 61896272 15542224
11:21:31 1662 8192000000 10042053248 10431016960 1082194096 11.668 100 77 147 2 1166683488 69914384 14575008
11:22:33 1238 8192000000 10050198752 10436579328 1085038640 11.695 100 97 193 3 1176102032 70848960 20214432
11:23:35 988 8192000000 10064242336 10450792448 1090195104 11.745 100 84 168 0 1183594240 76306832 17092304
11:24:37 893 8192000000 10063574624 10454593536 1091402784 11.756 100 87 168 3 1181988272 73898112 16687376
11:25:38 2832 8192000000 10030392112 10432516096 1094869424 11.789 100 60 110 1 1154537072 47847824 11819824
11:26:42 1195 8192000000 10064986480 10457313280 1095351472 11.794 100 93 176 1 1187059872 73492464 18215936
11:27:45 1267 8192000000 10069545632 10458931200 1097569904 11.815 100 97 184 2 1194295456 77867200 18858352
11:28:47 1148 8192000000 10037331632 10429812736 1078761696 11.636 99 80 153 4 1163101296 68041376 16298224
11:29:49 963 8192000000 10026898240 10415771648 1074353824 11.594 100 87 168 1 1148268480 55573984 18340672
11:30:51 906 8192000000 10031538096 10415022080 1065045776 11.505 99 86 164 2 1148457168 67048464 16362928
11:31:52 1403 8192000000 10032392704 10417156096 1058910592 11.446 100 88 166 0 1158598224 81612240 18075392
11:32:54 1608 8192000000 9993768224 10369282048 1037858608 11.244 99 80 158 1 1116885792 63247280 15779904
11:33:56 1410 8192000000 9960177616 10334552064 1023733088 11.108 100 73 139 1 1078774496 40008256 15033152
11:34:58 878 8192000000 9973303712 10348621824 1029299728 11.162 100 84 159 2 1095416032 49339200 16777104
11:36:00 4100 8192000000 10004501392 10385010688 1045860896 11.321 100 69 133 2 1126688432 67961264 12866272
11:37:05 2812 8192000000 10009908240 10392473600 1050017760 11.361 100 73 136 1 1131666016 67103648 14544608
11:38:15 1255 8192000000 10045926672 10424926208 1063341904 11.488 100 92 182 0 1165991216 84964720 17684592
11:39:22 1466 8192000000 10031817760 10418249728 1072389072 11.575 100 74 135 0 1151270624 64520736 14360816
11:40:30 3675 8192000000 10042432240 10438021120 1083406384 11.680 100 66 130 0 1164692624 67668080 13618160
11:41:41 1555 8192000000 10098404240 10487570432 1096334384 11.803 100 88 167 0 1213806416 100715824 16756208
11:42:50 5744 8192000000 10126818528 10534367232 1113614336 11.967 100 66 128 0 1237057920 110245232 13198352
11:43:59 6056 8192000000 10134588160 10529009664 1121014880 12.037 100 81 156 1 1250317088 113097520 16204688
11:45:08 11404 8192000000 10162917760 10561413120 1128464256 12.107 100 69 136 1 1283003024 139365056 15173712
11:46:27 4433 8192000000 10154419504 10557214720 1133624512 12.156 100 68 134 2 1271830112 124333056 13872544
11:47:37 1556 8192000000 10222220448 10624438272 1148303312 12.294 100 85 163 2 1339761520 172798816 18659392
11:48:42 1523 8192000000 10248658144 10657587200 1165559088 12.455 100 82 160 0 1361585856 177887136 18139632
11:49:48 1520 8192000000 10322761088 10724462592 1175077360 12.544 100 94 187 0 1432757792 237174752 20505680
11:50:51 1306 8192000000 10359084240 10772783104 1180049136 12.591 100 91 177 0 1471724768 270391168 21284464
11:51:57 1697 8192000000 10372477792 10783920128 1176316352 12.556 100 88 172 1 1480842320 284917024 19608944
11:53:02 1141 8192000000 10400709296 10817433600 1183933776 12.627 100 93 183 0 1508397472 303053440 21410256
11:54:06 1143 8192000000 10381487472 10803122176 1182519824 12.614 100 94 178 1 1490714080 286767040 21427216
11:55:09 904 8192000000 10376919376 10784956416 1167928672 12.477 100 90 175 2 1486601088 297347584 21324832
11:56:13 1295 8192000000 10416839824 10823266304 1175877520 12.552 100 94 186 0 1522230640 325127056 21226064
11:57:18 828 8192000000 10442206672 10861096960 1167396960 12.472 100 97 189 0 1545311344 356172656 21741728
11:58:21 746 8192000000 10447267712 10864533504 1166423712 12.463 100 97 187 0 1553487248 364491296 22572240
11:59:24 2509 8192000000 10477209568 10882936832 1166113024 12.460 100 89 163 0 1583173120 398233200 18826896
12:00:30 1149 8192000000 10521505168 10935173120 1175952352 12.552 100 97 188 3 1624104112 426415840 21735920
12:01:33 840 8192000000 10545798496 10971324416 1164129520 12.442 100 97 189 0 1648133872 461373984 22630368
12:02:36 3982 8192000000 10525710096 10994974720 1166607680 12.465 100 52 104 0 1642731744 462532336 13591728
12:03:42 13454 8192000000 10543079152 10972397568 1164111184 12.442 100 53 106 0 1637135968 459518464 13506320
12:05:00 9473 8192000000 10542270432 10987786240 1165298960 12.453 100 46 92 0 1656851920 479739504 11813456
12:06:33 4464 8192000000 10472223536 10962993152 1155288416 12.359 100 64 119 4 1571757120 402404192 14064512
12:08:32 24622 8192000000 10359922528 10844889088 1147190384 12.283 100 29 57 1 1464160592 309574624 7395584
12:10:26 52313 8192000000 10074108448 10595405824 1114589664 11.976 98 14 28 0 1287438832 169638144 3211024
12:12:18 93239 8192000000 10125381008 10605244416 1100225184 11.840 96 20 41 1 1179486832 75349520 3912128
12:14:52 93624 8192000000 10138327424 10580443136 1102139856 11.858 97 49 92 3 1245963584 133455504 10368224
12:17:37 41738 8192000000 10047245440 10541486080 1102157136 11.858 99 23 44 1 1149552384 42369440 5025808
12:19:25 58480 8192000000 10079380848 10515271680 1109898048 11.931 99 32 61 2 1165060912 48693056 6469808
12:21:28 81666 8192000000 10061450832 10539380736 1106294672 11.897 99 15 30 0 1168897856 59220880 3382304
12:23:50 108383 8192000000 10093534176 10557075456 1119740736 12.025 99 23 46 3 1209013312 83166416 6106160
12:26:45 78718 8192000000 10102510192 10578825216 1145234624 12.265 100 18 36 0 1196784016 47309232 4240160
12:29:19 114128 8192000000 10110336768 10631360512 1133416992 12.154 99 24 48 0 1268219632 128883872 5918768
12:32:19 34028 8192000000 10105243168 10591444992 1135046784 12.169 99 29 58 1 1205150864 63904480 6199600
12:34:04 89986 8192000000 10057039280 10543226880 1100818272 11.845 97 14 29 0 1155282912 51420304 3044336
12:36:39 51078 8192000000 10080114288 10529079296 1133206768 12.152 100 34 59 0 1163791728 24371024 6213936
12:38:37 85155 8192000000 10067198880 10556739584 1071045136 11.562 94 13 26 1 1120547328 46225616 3276576
12:41:03 25565 8192000000 10069433088 10518822912 1139224112 12.208 100 31 62 0 1173070800 27843696 6002992
12:42:30 9547 8192000000 10121834848 10551050240 1136399408 12.182 100 71 136 0 1208295664 57418032 14478224
12:43:40 55900 8192000000 10120600992 10559287296 1112749072 11.958 98 42 81 0 1181712832 61002320 7961440
12:45:41 7649 8192000000 10102116032 10535845888 1131272064 12.133 100 48 93 1 1192876736 50757440 10847232
12:46:53 70835 8192000000 10128228752 10616238080 1127221568 12.095 99 22 42 3 1218286448 86496096 4568784
12:49:07 50132 8192000000 10121220368 10593570816 1127286864 12.096 99 19 38 1 1233393888 101560528 4546496
12:50:58 38789 8192000000 10093560160 10521317376 1121045856 12.037 99 41 82 2 1167366704 38085024 8235824
12:52:45 69062 8192000000 10154031856 10590822400 1103428576 11.870 97 28 52 3 1209264032 100224880 5610576
12:55:45 61751 8192000000 10108327296 10549850112 1123557040 12.061 99 25 48 0 1206209136 77309552 5342544
12:57:47 37834 8192000000 10084022320 10549911552 1127551664 12.098 99 14 28 1 1202010736 71408256 3050816
12:59:35 62824 8192000000 10083414144 10532429824 1114819280 11.978 98 14 29 0 1189192352 71359184 3013888
13:01:39 79767 8192000000 10076897328 10581405696 1122400976 12.050 99 11 22 0 1171221104 46667680 2152448
13:04:10 90372 8192000000 10078911536 10537570304 1131986896 12.140 99 16 32 0 1207525616 72140000 3398720
13:06:47 41015 8192000000 10095349920 10562961408 1118421072 12.012 99 38 76 0 1185876944 59452448 8003424
13:08:30 74169 8192000000 10096758752 10561638400 1095900352 11.799 96 25 50 0 1138259792 37596560 4762880
13:10:45 67503 8192000000 10123713440 10578509824 1094623344 11.787 96 26 52 0 1191178832 91375712 5179776
13:12:53 107499 8192000000 10082859840 10573963264 1086993072 11.714 96 15 29 1 1147857680 57946800 2917808
13:15:47 29222 8192000000 10119561456 10548269056 1142591504 12.240 100 39 78 0 1202244592 51886320 7766768
13:17:18 93009 8192000000 10072741728 10542833664 1139707872 12.213 100 19 38 0 1186981648 43145056 4128720
13:20:12 40404 8192000000 10072664304 10511196160 1122177440 12.048 99 29 58 1 1173570896 46074816 5318640
13:21:53 100429 8192000000 10087554272 10558996480 1080751328 11.655 95 18 36 0 1147803664 63335664 3716672
13:24:35 65889 8192000000 10103018048 10562502656 1143585120 12.249 100 21 42 2 1209668992 61229136 4854736
13:26:49 76289 8192000000 10102719456 10574700544 1128587568 12.108 99 6 12 1 1199369184 69531888 1249728
13:29:18 32638 8192000000 10066505280 10512351232 1099042240 11.829 97 17 32 0 1139610768 37476720 3091808
13:30:54 48640 8192000000 10118889056 10572296192 1092126368 11.763 96 19 34 3 1175734464 79961856 3646240
13:32:42 57343 8192000000 10095743056 10564435968 1117455072 12.003 99 21 42 0 1174266128 52499328 4311728
13:34:40 15561 8192000000 10082678240 10495418368 1117659856 12.005 100 69 138 0 1176296208 44227744 14408608
13:35:56 44149 8192000000 10179781232 10605359104 1133217792 12.152 100 53 106 1 1258900672 115040992 10641888

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 10-05-2022 13:38:37 to 10-05-2022 13:38:53.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 114 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 10-05-2022 13:38:54 to 10-05-2022 13:38:56.

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          216491
MON$OLDEST_ACTIVE               220820
MON$OLDEST_SNAPSHOT             220820
MON$NEXT_TRANSACTION            220820
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                       2084384
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             776
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2763 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2763 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2763 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2763 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 ---
:260: 
:261: mscs-test-161.budzdorov.ru    Tue May 10 13:37:16 2022
:262:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:263: 
:264: 
:265: mscs-test-161.budzdorov.ru    Tue May 10 13:37:16 2022
:266:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:267: 
:268: 
:269: mscs-test-161.budzdorov.ru    Tue May 10 13:37:17 2022
:270:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:271: 
:272: 
:273: mscs-test-161.budzdorov.ru    Tue May 10 13:37:29 2022
:274:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:275: 
:276: 
:277: mscs-test-161.budzdorov.ru    Tue May 10 13:37:32 2022
:278:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:279: 
:280: 
:281: mscs-test-161.budzdorov.ru    Tue May 10 13:37:34 2022
:282:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:283: 
--- end of diff output ---

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

10.05.22 13:38:57. 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-05-10 13:39:00 No severe PSQL-related problems occured
10.05.22 13:39:01. End of report.