Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 16.06.2022 19:39
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 16316140 kB
MemAvailable 31230024 kB
Buffers 0 kB
Cached 15366272 kB
SwapCached 2448 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 3.0.10
DB_NAME                         /home/bases/oltp30-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/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/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=3333
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/oltp30-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-oltp30
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-06-16 19:38:22
DTS_END                         2022-06-16 19:38:22.0350
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(57:17)
IP                              ::1/56764
TRN_ID                          251240
ATT_ID                          682
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: 9548.82 23631 1145858
customer order: creation 180.03 373 21604
customer order: refuse 15.13 346 1816
order to supplier: creation 47.93 1251 5752
order to supplier: removal 3.54 115151 425
invoice (draft): creation 47.77 3198 5732
invoice (draft): removal 54.16 915 6499
invoice accept: apply 43.45 17468 5214
invoice accept: cancel 48.96 832 5875
customer reserve: creation 4010.50 172 481260
customer reserve: removal 4788.73 66 574648
realization accept: apply 87.60 213 10512
realization accept: cancel 85.64 123 10277
payment from customer: creation 61.13 116 7336
payment from customer: removal 6.51 96 781
payment to supplier: creation 50.98 144 6117
payment to supplier: removal 3.60 99 432
service: total inventory turnovers 5.06 873 607
service: total monetary turnovers 7.14 33 857
service: aggregate perf. data 0.92 19087 110
service: refresh index statistics 0.03 312060 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 16-06-2022 19:39:35 to 16-06-2022 19:39:37.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3336
TEST_TIME 2 2640
TEST_TIME 3 3068
TEST_TIME 4 3615
TEST_TIME 5 3794
TEST_TIME 6 4123
TEST_TIME 7 4313
TEST_TIME 8 4897
TEST_TIME 9 4825
TEST_TIME 10 4964
TEST_TIME 11 5054
TEST_TIME 12 5225
TEST_TIME 13 5316
TEST_TIME 14 5968
TEST_TIME 15 6142
TEST_TIME 16 6087
TEST_TIME 17 6221
TEST_TIME 18 6416
TEST_TIME 19 6611
TEST_TIME 20 7246
TEST_TIME 21 7387
TEST_TIME 22 8240
TEST_TIME 23 8649
TEST_TIME 24 8763
TEST_TIME 25 9363
TEST_TIME 26 10502
TEST_TIME 27 10695
TEST_TIME 28 10703
TEST_TIME 29 10583
TEST_TIME 30 10661
TEST_TIME 31 10613
TEST_TIME 32 10641
TEST_TIME 33 10446
TEST_TIME 34 10450
TEST_TIME 35 10340
TEST_TIME 36 10315
TEST_TIME 37 10285
TEST_TIME 38 10384
TEST_TIME 39 10426
TEST_TIME 40 10271
TEST_TIME 41 10242
TEST_TIME 42 10156
TEST_TIME 43 10013
TEST_TIME 44 9939
TEST_TIME 45 9827
TEST_TIME 46 9841
TEST_TIME 47 9794
TEST_TIME 48 9676
TEST_TIME 49 9741
TEST_TIME 50 9840
TEST_TIME 51 9812
TEST_TIME 52 9811
TEST_TIME 53 9853
TEST_TIME 54 9781
TEST_TIME 55 9903
TEST_TIME 56 9986
TEST_TIME 57 10117
TEST_TIME 58 10104
TEST_TIME 59 10124
TEST_TIME 60 10212
TEST_TIME 61 10165
TEST_TIME 62 10014
TEST_TIME 63 9948
TEST_TIME 64 9932
TEST_TIME 65 9947
TEST_TIME 66 9852
TEST_TIME 67 9784
TEST_TIME 68 9832
TEST_TIME 69 9786
TEST_TIME 70 9747
TEST_TIME 71 9765
TEST_TIME 72 9779
TEST_TIME 73 9853
TEST_TIME 74 9781
TEST_TIME 75 9830
TEST_TIME 76 9851
TEST_TIME 77 9866
TEST_TIME 78 9865
TEST_TIME 79 9851
TEST_TIME 80 9862
TEST_TIME 81 9987
TEST_TIME 82 9927
TEST_TIME 83 9826
TEST_TIME 84 9766
TEST_TIME 85 9769
TEST_TIME 86 9709
TEST_TIME 87 9669
TEST_TIME 88 9609
TEST_TIME 89 9584
TEST_TIME 90 9551
TEST_TIME 91 9522
TEST_TIME 92 9522
TEST_TIME 93 9553
TEST_TIME 94 9680
TEST_TIME 95 9643
TEST_TIME 96 9729
TEST_TIME 97 9704
TEST_TIME 98 9709
TEST_TIME 99 9689
TEST_TIME 100 9724
TEST_TIME 101 9743
TEST_TIME 102 9723
TEST_TIME 103 9744
TEST_TIME 104 9728
TEST_TIME 105 9669
TEST_TIME 106 9670
TEST_TIME 107 9642
TEST_TIME 108 9617
TEST_TIME 109 9600
TEST_TIME 110 9567
TEST_TIME 111 9529
TEST_TIME 112 9551
TEST_TIME 113 9523
TEST_TIME 114 9500
TEST_TIME 115 9468
TEST_TIME 116 9544
TEST_TIME 117 9567
TEST_TIME 118 9598
TEST_TIME 119 9591
TEST_TIME 120 9550

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 16-06-2022 19:39:38 to 16-06-2022 19:39:42.

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 1591870 1591870 0 0.00 0 20090 7 0 0
doc_list_biud 1591867 1591867 0 0.00 0 29130 30 0 0
sp_add_invoice_to_stock 5337 5214 123 2.30 28 160167 17469 0 123
sp_cancel_adding_invoice 5875 5875 0 0.00 3 85464 833 0 0
sp_cancel_client_order 1816 1816 0 0.00 4 20029 347 0 0
sp_cancel_customer_reserve 574648 574648 0 0.00 1 28417 67 0 0
sp_cancel_pay_from_customer 781 781 0 0.00 3 5595 97 0 0
sp_cancel_pay_to_supplier 432 432 0 0.00 2 7890 100 0 0
sp_cancel_supplier_invoice 6509 6499 10 0.15 1 19916 916 0 10
sp_cancel_supplier_order 425 425 0 0.00 7 1272459 115151 0 0
sp_cancel_write_off 10277 10277 0 0.00 1 13177 123 0 0
sp_client_order 21604 21604 0 0.00 3 43821 374 0 0
sp_customer_reserve 481260 481260 0 0.00 0 77745 172 0 0
sp_fill_shopping_cart 33087 33087 0 0.00 1 11504 38 0 0
sp_fill_shopping_cart_clo_res 481255 481255 0 0.00 0 29418 15 0 0
sp_get_clo_for_invoice 5209 5209 0 0.00 0 43921 345 0 0
sp_kill_cost_storno 17365 17365 0 0.00 0 12929 65 0 0
sp_kill_qstorno_ret_qs2qd 1166497 1166497 0 0.00 0 29128 36 0 0
sp_kill_qty_storno 615261 615261 0 0.00 0 29129 77 0 0
sp_lock_selected_doc 21775 21775 0 0.00 0 9194 5 0 0
sp_make_cost_storno 29174 29174 0 0.00 0 20088 68 0 0
sp_multiply_rows_for_pdistr 29174 29174 0 0.00 0 13486 35 0 0
sp_multiply_rows_for_qdistr 21604 21604 0 0.00 0 43796 175 0 0
sp_pay_from_customer 7336 7336 0 0.00 4 18992 117 0 0
sp_pay_to_supplier 6117 6117 0 0.00 5 20093 144 0 0
sp_payment_common 13453 13453 0 0.00 1 18975 36 0 0
sp_qd_handle_on_cancel_clo 1816 1816 0 0.00 0 20023 319 0 0
sp_qd_handle_on_invoice_upd_sts 11084 11084 0 0.00 0 14252 321 0 0
sp_qd_handle_on_reserve_upd_sts 10512 10512 0 0.00 0 14569 65 0 0
sp_reserve_write_off 10512 10512 0 0.00 6 19978 213 0 0
sp_supplier_invoice 5732 5732 0 0.00 6 75906 3199 0 0
sp_supplier_order 5752 5752 0 0.00 5 64847 1252 0 0
srv_aggregate_perf_data 110 110 0 0.00 2508 351772 19088 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1172 115458 43138 0 0
srv_find_qd_qs_mism 1096234 1096234 0 0.00 0 15497 5 0 0
srv_make_invnt_saldo 607 607 0 0.00 44 23090 874 0 0
srv_make_money_saldo 857 857 0 0.00 0 6576 34 0 0
srv_recalc_idx_stat 4 4 0 0.00 164789 485548 312060 0 0
t$perf-abend:sp_add_invoice_to_stock 123 123 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5214 5214 0 0.00 0 14977 171 0 0
t$perf-norm:sp_cancel_adding_invoice 435 435 0 0.00 0 12397 118 0 0
t$perf-norm:sp_cancel_client_order 1816 1816 0 0.00 0 4069 14 0 0
t$perf-norm:sp_cancel_customer_reserve 718 718 0 0.00 0 900 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 781 781 0 0.00 0 4122 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 432 432 0 0.00 0 270 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 411 411 0 0.00 0 1522 17 0 0
t$perf-norm:sp_cancel_supplier_order 425 425 0 0.00 0 26799 1485 0 0
t$perf-norm:sp_cancel_write_off 1036 1036 0 0.00 0 14391 27 0 0
t$perf-norm:sp_client_order 21604 21604 0 0.00 0 12955 18 0 0
t$perf-norm:sp_customer_reserve 957 957 0 0.00 0 7317 16 0 0
t$perf-norm:sp_pay_from_customer 7336 7336 0 0.00 0 5876 10 0 0
t$perf-norm:sp_pay_to_supplier 6117 6117 0 0.00 0 9794 10 0 0
t$perf-norm:sp_reserve_write_off 10512 10512 0 0.00 0 19759 12 0 0
t$perf-norm:sp_supplier_invoice 5732 5732 0 0.00 0 7430 17 0 0
t$perf-norm:sp_supplier_order 5752 5752 0 0.00 0 4707 14 0 0
t$perf-norm:srv_aggregate_perf_data 110 110 0 0.00 0 777 16 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 65 1 0 0
t$perf-norm:srv_make_invnt_saldo 607 607 0 0.00 0 1290 24 0 0
t$perf-norm:srv_make_money_saldo 857 857 0 0.00 0 4924 23 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 137 39 0 0
v_add_invoice_to_stock 10664 10418 246 2.30 0 7360 20 0 246
v_all_customers 39204 39204 0 0.00 0 19 0 0 0
v_all_suppliers 32004 32004 0 0.00 0 21 1 0 0
v_all_wares 43208 43208 0 0.00 0 85 1 0 0
v_cancel_adding_invoice 870 870 0 0.00 0 2813 11 0 0
v_cancel_client_order 3632 3632 0 0.00 0 2087 5 0 0
v_cancel_customer_prepayment 1562 1562 0 0.00 0 3836 14 0 0
v_cancel_customer_reserve 1436 1436 0 0.00 0 2777 8 0 0
v_cancel_payment_to_supplier 864 864 0 0.00 0 1428 5 0 0
v_cancel_supplier_invoice 842 822 20 2.37 0 7106 28 0 20
v_cancel_supplier_order 850 850 0 0.00 0 1744 5 0 0
v_cancel_write_off 2072 2072 0 0.00 0 1665 12 0 0
v_max_id_clo_ord 5752 5752 0 0.00 0 2689 2 0 0
v_max_id_clo_res 957 957 0 0.00 0 2179 23 0 0
v_max_id_ord_sup 5731 5731 0 0.00 0 5294 3 0 0
v_max_non_paid_invoice 1598 1598 0 0.00 0 7380 9 0 0
v_max_non_paid_realizn 3973 3973 0 0.00 0 1623 3 0 0
v_min_id_clo_ord 5752 5752 0 0.00 0 2291 6 0 0
v_min_id_clo_res 957 957 0 0.00 0 1829 6 0 0
v_min_id_ord_sup 5731 5731 0 0.00 0 8180 6 0 0
v_min_non_paid_invoice 6117 6117 0 0.00 0 16413 26 0 0
v_min_non_paid_realizn 7336 7336 0 0.00 0 18969 30 0 0
v_our_firm 10730 10730 0 0.00 0 24 1 0 0
v_reserve_write_off 21024 21024 0 0.00 0 5178 6 0 0
x_lock_depdocs_on_canc_invoice 11963 11963 0 0.00 0 21085 130 0 0
x_lock_depdocs_on_canc_sup_ord 425 425 0 0.00 0 19435 357 0 0
x_make_qstorno_1000_1200 5752 5752 0 0.00 2 64829 1175 0 0
x_make_qstorno_1200_2000 5732 5732 0 0.00 2 74052 3063 0 0
x_make_qstorno_2100_3300 481260 481260 0 0.00 0 77742 155 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 16-06-2022 19:39:43 to 16-06-2022 19:39:47.

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
17:08:25 9 8192000000 8798902176 8810233856 5315664 0.064 2 1 2 0 5415264 56816 42784
17:09:27 29217 8192000000 9930917712 10247049216 1009310064 10.969 100 89 178 0 1038461264 6813088 22338112
17:11:03 12824 8192000000 9966753680 10292494336 1045756624 11.320 100 92 184 0 1078856256 9598160 23501472
17:12:21 11621 8192000000 9985928768 10319044608 1069031504 11.543 100 87 174 0 1101115984 10373888 21710592
17:13:50 9464 8192000000 10027075328 10376687616 1115092880 11.981 100 81 161 1 1145796720 11346784 19357056
17:15:06 12232 8192000000 10079771264 10438905856 1167175808 12.470 100 75 150 0 1195502560 11478400 16848352
17:16:21 4176 8192000000 10130342720 10499772416 1218653760 12.949 100 88 176 0 1251804384 13792720 19357904
17:17:29 1744 8192000000 10171605600 10548645888 1263451984 13.362 100 83 160 3 1294068048 12194288 18421776
17:18:33 1766 8192000000 10205803904 10592088064 1290765792 13.611 100 91 181 3 1324148000 12999264 20382944
17:19:36 1253 8192000000 10220301232 10608345088 1308545280 13.773 100 89 180 2 1338533488 10048816 19939392
17:20:38 7774 8192000000 10205076480 10614628352 1315512480 13.836 100 35 70 1 1330715360 7247040 7955840
17:21:51 48143 8192000000 10231232000 10632744960 1322405824 13.898 100 56 113 1 1341914896 5115104 14393968
17:23:40 5960 8192000000 10220507040 10639998976 1330683280 13.973 100 33 66 0 1346612256 8525984 7402992
17:24:51 6444 8192000000 10224886192 10647445504 1336855776 14.029 100 18 35 2 1347601584 6023120 4722688
17:26:00 24286 8192000000 10221984288 10652573696 1340933024 14.066 100 44 90 0 1362475200 10840992 10701184
17:27:26 4340 8192000000 10227199552 10641190912 1313521424 13.818 98 27 54 2 1325881344 6200912 6159008
17:28:32 8188 8192000000 10154101632 10551349248 1201677088 12.792 94 21 41 1 1211386224 4514592 5194544
17:29:44 63573 8192000000 10157968032 10572693504 1233164848 13.083 96 39 77 0 1248466560 6323072 8978640
17:31:48 6572 8192000000 10120590768 10513485824 1206183232 12.834 97 23 46 2 1217188208 5554896 5450080
17:32:56 9221 8192000000 10164953200 10563174400 1263154512 13.359 100 26 51 1 1274820544 5215376 6450656
17:34:07 77824 8192000000 10184500048 10601586688 1301433328 13.708 100 51 104 0 1319807616 6214016 12160272
17:36:25 5320 8192000000 10192798160 10610008064 1305008144 13.741 100 30 60 2 1318635856 7194544 6433168
17:37:31 12778 8192000000 10218473296 10630041600 1316827792 13.848 100 27 52 2 1328685056 5882704 5974560
17:38:50 35435 8192000000 10241700752 10659405824 1347677408 14.127 100 35 70 4 1360530688 5196080 7657200
17:40:39 82486 8192000000 10272462064 10702024704 1371264224 14.338 100 31 62 0 1396155264 18145408 6745632
17:43:07 15745 8192000000 10291378688 10719391744 1382078128 14.435 100 37 74 0 1411900384 20933712 8888544
17:44:26 8911 8192000000 10311214672 10733322240 1388134400 14.489 100 48 96 0 1425260720 25965008 11161312
17:45:43 8893 8192000000 10327719104 10743353344 1394071120 14.542 100 55 110 0 1435846688 28793104 12982464
17:46:55 6529 8192000000 10318992000 10740740096 1372628704 14.351 98 46 92 2 1413710672 29811680 11270288
17:48:17 8897 8192000000 10341897120 10766958592 1395082896 14.551 99 50 100 0 1443637488 35978896 12575696
17:49:37 10258 8192000000 10351101424 10769448960 1403381728 14.625 100 60 117 3 1462125456 43106672 15637056
17:50:58 4106 8192000000 10336792096 10746028032 1376081856 14.382 99 71 142 1 1441742640 47623408 18037376
17:52:09 3322 8192000000 10335796864 10749628416 1375914976 14.380 99 61 123 2 1437581504 46149328 15517200
17:53:18 3997 8192000000 10361115056 10773299200 1406429344 14.652 100 69 137 2 1468400720 44796816 17174560
17:54:25 3370 8192000000 10382970752 10785972224 1410090080 14.685 100 92 184 2 1485596304 51729840 23776384
17:55:38 1170 8192000000 10415469392 10821406720 1434452368 14.901 100 89 179 2 1521149248 63359104 23337776
17:56:43 2331 8192000000 10394904768 10797281280 1405281504 14.642 99 91 180 2 1497796896 67784608 24730784
17:57:50 4529 8192000000 10410111728 10821242880 1418081296 14.756 100 81 162 0 1519010640 79547424 21381920
17:58:59 11806 8192000000 10374873984 10795732992 1394366816 14.545 99 49 98 1 1479962768 70677456 14918496
18:00:26 16344 8192000000 10362770736 10794975232 1397799216 14.575 100 34 68 3 1473575776 65645808 10130752
18:01:46 20882 8192000000 10353670144 10793897984 1387166112 14.481 100 31 60 0 1464520208 67784448 9569648
18:03:17 36232 8192000000 10299704304 10741149696 1309326192 13.780 96 17 34 0 1387060048 72086416 5647440
18:04:54 48212 8192000000 10264993104 10689462272 1296432224 13.663 96 25 50 1 1319335952 16709584 6194144
18:06:44 53213 8192000000 10264413344 10706214912 1348006816 14.130 99 18 34 2 1367589664 15241312 4341536
18:08:43 60450 8192000000 10290502480 10740797440 1373717040 14.360 100 18 36 1 1399766672 21655552 4394080
18:10:44 71580 8192000000 10269894752 10714038272 1335670960 14.018 97 37 75 4 1355346960 11293632 8382368
18:13:13 6103 8192000000 10282980096 10733879296 1381013312 14.426 100 22 44 0 1392510944 6903456 4594176
18:14:23 57163 8192000000 10302751440 10746925056 1370286528 14.330 98 24 48 0 1388073392 12606720 5180144
18:16:22 49723 8192000000 10287190752 10748874752 1370214480 14.329 99 15 29 0 1391272176 17673152 3384544
18:18:50 44938 8192000000 10300042128 10757332992 1388421328 14.492 100 18 36 0 1407581920 14448384 4712208
18:20:39 50758 8192000000 10289438208 10753122304 1384110592 14.453 99 19 38 0 1395326992 7477536 3738864
18:22:43 22559 8192000000 10280632992 10743386112 1377404112 14.393 99 31 61 1 1392271680 7741072 7126496
18:24:07 45841 8192000000 10294166880 10757169152 1385775392 14.468 99 21 42 2 1404049104 13868704 4405008
18:26:01 46555 8192000000 10296992608 10755547136 1380465920 14.421 99 19 38 0 1399513744 13906896 5140928
18:27:55 63924 8192000000 10285373936 10755125248 1380889248 14.424 99 18 36 0 1400639872 15249216 4501408
18:30:13 23399 8192000000 10282190368 10736316416 1350564528 14.153 97 32 64 0 1368515040 10317504 7633008
18:31:43 30690 8192000000 10297750368 10749255680 1352049904 14.166 97 23 47 0 1364795744 7144976 5600864
18:33:14 56100 8192000000 10291443792 10755915776 1378724064 14.405 99 25 50 1 1401613664 17004976 5884624
18:35:10 95692 8192000000 10286444336 10741309440 1353964240 14.183 98 8 16 1 1371980640 15905888 2110512
18:37:46 69424 8192000000 10296047792 10764234752 1375986832 14.381 99 8 16 1 1391160320 13169984 2003504
18:40:09 100223 8192000000 10295811744 10731040768 1348329360 14.132 97 34 65 1 1366687792 10751552 7606880
18:42:52 56656 8192000000 10276535200 10737647616 1377988352 14.399 99 38 76 1 1399610896 13211504 8411040
18:45:00 44037 8192000000 10272087952 10730602496 1364663648 14.279 99 9 18 0 1377972512 11172336 2136528
18:46:45 32063 8192000000 10279702864 10739965952 1369521216 14.323 100 25 50 1 1388737184 13292656 5923312
18:48:37 37214 8192000000 10263390080 10712395776 1319844448 13.875 96 25 50 3 1336950352 11520608 5585296
18:50:15 67831 8192000000 10264903648 10716262400 1324947840 13.921 97 18 36 1 1342844368 13586032 4310496
18:52:30 26930 8192000000 10260779648 10696921088 1319091168 13.868 97 37 73 1 1338953728 11691120 8171440
18:54:03 42587 8192000000 10273220384 10726694912 1334843072 14.011 98 23 46 0 1356379824 16970624 4566128
18:55:47 28027 8192000000 10284584000 10715525120 1360660064 14.243 100 29 57 0 1377383232 10604880 6118288
18:57:17 15593 8192000000 10271519248 10695725056 1333836176 14.002 99 45 88 1 1351993040 8798432 9358432
18:58:34 44876 8192000000 10274558640 10728247296 1357990848 14.219 99 26 52 0 1379675824 15835680 5849296
19:00:22 111094 8192000000 10275117136 10728992768 1374482224 14.367 100 30 60 0 1397992032 16229104 7280704
19:03:27 37373 8192000000 10268359392 10728755200 1367619040 14.306 99 28 56 0 1379969744 6850736 5499968
19:05:08 55104 8192000000 10271090096 10738720768 1380547088 14.421 100 3 6 0 1389847712 8594448 706176
19:07:03 74961 8192000000 10273530032 10733330432 1348373680 14.133 97 19 38 1 1359386576 6076496 4936400
19:09:19 42547 8192000000 10293957968 10749317120 1357167824 14.212 97 20 40 0 1370525296 9052496 4304976
19:11:10 115456 8192000000 10276071424 10729369600 1354263104 14.186 98 17 34 5 1377259696 18287328 4709264
19:14:06 83710 8192000000 10283689456 10745864192 1357114656 14.211 98 13 27 0 1371323296 11219184 2989456
19:16:33 30683 8192000000 10279308400 10728050688 1329192896 13.960 96 31 62 0 1346056096 10399312 6463888
19:18:28 78862 8192000000 10289515984 10745352192 1350355760 14.151 98 13 26 1 1363767232 10427552 2983920
19:20:48 54717 8192000000 10279399904 10726543360 1321133808 13.887 96 20 40 0 1338072032 12238224 4700000
19:22:43 92444 8192000000 10265942960 10724777984 1349733440 14.145 98 33 66 0 1368510016 10882064 7894512
19:25:21 54125 8192000000 10247797776 10705215488 1330934768 13.976 97 19 38 1 1344331120 9410848 3985504
19:28:11 82149 8192000000 10248991632 10702454784 1338620688 14.045 99 12 25 0 1348431696 6943216 2867792
19:30:58 20552 8192000000 10255584208 10700648448 1333510704 13.999 98 24 48 1 1345671264 6611808 5548752
19:32:20 34109 8192000000 10261537616 10704998400 1333685456 14.000 98 20 40 1 1348972480 10511280 4775744
19:33:55 84112 8192000000 10269160000 10722287616 1346487200 14.116 99 17 34 0 1369628800 18988384 4153216
19:36:50 74302 8192000000 10281675312 10730074112 1330139136 13.968 97 19 38 2 1349561264 15375648 4046480

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 16-06-2022 19:39:49 to 16-06-2022 19:40:04.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 123 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 16-06-2022 19:40:05 to 16-06-2022 19:40:06.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          250219
MON$OLDEST_ACTIVE               252172
MON$OLDEST_SNAPSHOT             252172
MON$NEXT_TRANSACTION            252172
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-03 16:41:55.3470
MON$PAGES                       2021888
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33602 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33602 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33602 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33602 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.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 ---
:723: 
:724: mscs-test-161.budzdorov.ru    Thu Jun 16 19:38:40 2022
:725:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:726: 
:727: 
:728: mscs-test-161.budzdorov.ru    Thu Jun 16 19:38:40 2022
:729:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:730: 
--- end of diff output ---

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

16.06.22 19:40:07. There are 3 logs in /var/tmp/logs-oltp30 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-06-16 19:40:10 No severe PSQL-related problems occured
16.06.22 19:40:11. End of report.