Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 19.05.2022 16:44
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 16512352 kB
MemAvailable 31461248 kB
Buffers 0 kB
Cached 15235492 kB
SwapCached 2592 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-05-19 16:42:49
DTS_END                         2022-05-19 16:42:49.0220
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/47960
TRN_ID                          235642
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: 9059.39 23391 1087127
customer order: creation 164.48 376 19737
customer order: refuse 13.94 357 1673
order to supplier: creation 43.18 1395 5181
order to supplier: removal 3.26 108003 391
invoice (draft): creation 43.85 3958 5262
invoice (draft): removal 53.70 765 6444
invoice accept: apply 39.68 17681 4762
invoice accept: cancel 48.54 773 5825
customer reserve: creation 3616.21 176 433945
customer reserve: removal 4748.58 58 569830
realization accept: apply 78.58 206 9429
realization accept: cancel 82.80 110 9936
payment from customer: creation 55.98 135 6718
payment from customer: removal 5.38 125 645
payment to supplier: creation 46.97 157 5636
payment to supplier: removal 3.54 75 425
service: total inventory turnovers 4.09 1491 491
service: total monetary turnovers 5.80 27 696
service: aggregate perf. data 0.81 21107 97
service: refresh index statistics 0.03 310849 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 19-05-2022 16:44:54 to 19-05-2022 16:44:56.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2314
TEST_TIME 2 2849
TEST_TIME 3 3523
TEST_TIME 4 3553
TEST_TIME 5 4028
TEST_TIME 6 3877
TEST_TIME 7 4121
TEST_TIME 8 4385
TEST_TIME 9 4691
TEST_TIME 10 4815
TEST_TIME 11 5078
TEST_TIME 12 5447
TEST_TIME 13 5809
TEST_TIME 14 5998
TEST_TIME 15 6127
TEST_TIME 16 6502
TEST_TIME 17 6541
TEST_TIME 18 6719
TEST_TIME 19 7410
TEST_TIME 20 7764
TEST_TIME 21 8336
TEST_TIME 22 8410
TEST_TIME 23 8763
TEST_TIME 24 8677
TEST_TIME 25 8806
TEST_TIME 26 9474
TEST_TIME 27 9706
TEST_TIME 28 10502
TEST_TIME 29 10287
TEST_TIME 30 10278
TEST_TIME 31 10135
TEST_TIME 32 9962
TEST_TIME 33 10025
TEST_TIME 34 9922
TEST_TIME 35 9812
TEST_TIME 36 9724
TEST_TIME 37 9587
TEST_TIME 38 9620
TEST_TIME 39 9578
TEST_TIME 40 9474
TEST_TIME 41 9395
TEST_TIME 42 9282
TEST_TIME 43 9228
TEST_TIME 44 9113
TEST_TIME 45 9007
TEST_TIME 46 9000
TEST_TIME 47 8999
TEST_TIME 48 8942
TEST_TIME 49 8973
TEST_TIME 50 8857
TEST_TIME 51 9062
TEST_TIME 52 9184
TEST_TIME 53 9178
TEST_TIME 54 9200
TEST_TIME 55 9203
TEST_TIME 56 9231
TEST_TIME 57 9405
TEST_TIME 58 9399
TEST_TIME 59 9347
TEST_TIME 60 9313
TEST_TIME 61 9537
TEST_TIME 62 9561
TEST_TIME 63 9521
TEST_TIME 64 9401
TEST_TIME 65 9300
TEST_TIME 66 9277
TEST_TIME 67 9263
TEST_TIME 68 9184
TEST_TIME 69 9162
TEST_TIME 70 9126
TEST_TIME 71 9201
TEST_TIME 72 9147
TEST_TIME 73 9111
TEST_TIME 74 9062
TEST_TIME 75 9060
TEST_TIME 76 9035
TEST_TIME 77 9043
TEST_TIME 78 8949
TEST_TIME 79 8969
TEST_TIME 80 9022
TEST_TIME 81 9033
TEST_TIME 82 9024
TEST_TIME 83 9010
TEST_TIME 84 9027
TEST_TIME 85 9014
TEST_TIME 86 9125
TEST_TIME 87 9067
TEST_TIME 88 9113
TEST_TIME 89 9202
TEST_TIME 90 9162
TEST_TIME 91 9181
TEST_TIME 92 9335
TEST_TIME 93 9256
TEST_TIME 94 9165
TEST_TIME 95 9158
TEST_TIME 96 9189
TEST_TIME 97 9144
TEST_TIME 98 9141
TEST_TIME 99 9128
TEST_TIME 100 9087
TEST_TIME 101 9133
TEST_TIME 102 9127
TEST_TIME 103 9105
TEST_TIME 104 9141
TEST_TIME 105 9121
TEST_TIME 106 9187
TEST_TIME 107 9185
TEST_TIME 108 9240
TEST_TIME 109 9293
TEST_TIME 110 9292
TEST_TIME 111 9296
TEST_TIME 112 9225
TEST_TIME 113 9277
TEST_TIME 114 9223
TEST_TIME 115 9217
TEST_TIME 116 9199
TEST_TIME 117 9158
TEST_TIME 118 9147
TEST_TIME 119 9115
TEST_TIME 120 9063

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 19-05-2022 16:44:57 to 19-05-2022 16:45:01.

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 1491782 1491782 0 0.00 0 27462 6 0 0
doc_list_biud 1491782 1491782 0 0.00 0 30974 28 0 0
sp_add_invoice_to_stock 4831 4762 69 1.42 35 160837 17681 0 69
sp_cancel_adding_invoice 5825 5825 0 0.00 5 96197 774 0 0
sp_cancel_client_order 1673 1673 0 0.00 4 11149 357 0 0
sp_cancel_customer_reserve 569830 569830 0 0.00 1 30977 59 0 0
sp_cancel_pay_from_customer 645 645 0 0.00 3 14831 126 0 0
sp_cancel_pay_to_supplier 425 425 0 0.00 3 3698 75 0 0
sp_cancel_supplier_invoice 6446 6444 2 0.03 2 17989 766 0 2
sp_cancel_supplier_order 391 391 0 0.00 7 1105568 108004 0 0
sp_cancel_write_off 9936 9936 0 0.00 1 25546 110 0 0
sp_client_order 19737 19737 0 0.00 4 40595 377 0 0
sp_customer_reserve 433945 433945 0 0.00 0 100008 176 0 0
sp_fill_shopping_cart 30177 30177 0 0.00 1 22595 44 0 0
sp_fill_shopping_cart_clo_res 433941 433941 0 0.00 0 14014 14 0 0
sp_get_clo_for_invoice 4757 4757 0 0.00 0 33628 320 0 0
sp_kill_cost_storno 16831 16831 0 0.00 0 16733 55 0 0
sp_kill_qstorno_ret_qs2qd 1156431 1156431 0 0.00 0 30969 32 0 0
sp_kill_qty_storno 608285 608285 0 0.00 0 30973 69 0 0
sp_lock_selected_doc 19704 19704 0 0.00 0 5634 5 0 0
sp_make_cost_storno 26540 26540 0 0.00 0 17654 55 0 0
sp_multiply_rows_for_pdistr 26540 26540 0 0.00 0 16703 33 0 0
sp_multiply_rows_for_qdistr 19737 19737 0 0.00 0 40579 155 0 0
sp_pay_from_customer 6718 6718 0 0.00 4 26072 135 0 0
sp_pay_to_supplier 5636 5636 0 0.00 5 28654 157 0 0
sp_payment_common 12354 12354 0 0.00 1 19527 50 0 0
sp_qd_handle_on_cancel_clo 1673 1673 0 0.00 0 11128 320 0 0
sp_qd_handle_on_invoice_upd_sts 10581 10581 0 0.00 0 15338 339 0 0
sp_qd_handle_on_reserve_upd_sts 9429 9429 0 0.00 0 14981 62 0 0
sp_reserve_write_off 9429 9429 0 0.00 6 29371 206 0 0
sp_supplier_invoice 5262 5262 0 0.00 6 72547 3958 0 0
sp_supplier_order 5181 5181 0 0.00 5 41794 1395 0 0
srv_aggregate_perf_data 97 97 0 0.00 3054 200173 21108 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 2276 144019 51207 0 0
srv_find_qd_qs_mism 1043324 1043324 0 0.00 0 14929 5 0 0
srv_make_invnt_saldo 491 491 0 0.00 54 131259 1492 0 0
srv_make_money_saldo 696 696 0 0.00 1 788 28 0 0
srv_recalc_idx_stat 4 4 0 0.00 108848 395700 310850 0 0
t$perf-abend:sp_add_invoice_to_stock 69 69 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 2 2 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4762 4762 0 0.00 0 19071 186 0 0
t$perf-norm:sp_cancel_adding_invoice 414 414 0 0.00 1 15357 200 0 0
t$perf-norm:sp_cancel_client_order 1673 1673 0 0.00 0 3761 17 0 0
t$perf-norm:sp_cancel_customer_reserve 661 661 0 0.00 0 728 6 0 0
t$perf-norm:sp_cancel_pay_from_customer 645 645 0 0.00 0 445 2 0 0
t$perf-norm:sp_cancel_pay_to_supplier 425 425 0 0.00 0 2358 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 353 353 0 0.00 0 9061 31 0 0
t$perf-norm:sp_cancel_supplier_order 391 391 0 0.00 0 19914 1676 0 0
t$perf-norm:sp_cancel_write_off 957 957 0 0.00 0 3083 15 0 0
t$perf-norm:sp_client_order 19737 19737 0 0.00 0 14693 23 0 0
t$perf-norm:sp_customer_reserve 926 926 0 0.00 0 175 2 0 0
t$perf-norm:sp_pay_from_customer 6718 6718 0 0.00 0 4529 12 0 0
t$perf-norm:sp_pay_to_supplier 5636 5636 0 0.00 0 10600 15 0 0
t$perf-norm:sp_reserve_write_off 9429 9429 0 0.00 0 15641 17 0 0
t$perf-norm:sp_supplier_invoice 5262 5262 0 0.00 0 9629 25 0 0
t$perf-norm:sp_supplier_order 5181 5181 0 0.00 0 10945 21 0 0
t$perf-norm:srv_aggregate_perf_data 97 97 0 0.00 0 161 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 746 14 0 0
t$perf-norm:srv_make_invnt_saldo 491 491 0 0.00 0 4368 38 0 0
t$perf-norm:srv_make_money_saldo 696 696 0 0.00 0 808 11 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 6 3 0 0
v_add_invoice_to_stock 9650 9512 138 1.43 0 25414 27 0 138
v_all_customers 36070 36070 0 0.00 0 18 1 0 0
v_all_suppliers 29234 29234 0 0.00 0 16 1 0 0
v_all_wares 39474 39474 0 0.00 0 14 1 0 0
v_cancel_adding_invoice 828 828 0 0.00 0 27777 42 0 0
v_cancel_client_order 3346 3346 0 0.00 0 4411 11 0 0
v_cancel_customer_prepayment 1290 1290 0 0.00 0 4959 15 0 0
v_cancel_customer_reserve 1322 1322 0 0.00 0 12904 26 0 0
v_cancel_payment_to_supplier 850 850 0 0.00 0 3691 7 0 0
v_cancel_supplier_invoice 710 706 4 0.56 0 814 14 0 4
v_cancel_supplier_order 782 782 0 0.00 0 2984 8 0 0
v_cancel_write_off 1914 1914 0 0.00 0 25495 41 0 0
v_max_id_clo_ord 5181 5181 0 0.00 0 462 1 0 0
v_max_id_clo_res 926 926 0 0.00 0 2609 18 0 0
v_max_id_ord_sup 5259 5259 0 0.00 0 6581 4 0 0
v_max_non_paid_invoice 1459 1459 0 0.00 0 2802 7 0 0
v_max_non_paid_realizn 3583 3583 0 0.00 0 2134 5 0 0
v_min_id_clo_ord 5181 5181 0 0.00 0 3163 7 0 0
v_min_id_clo_res 926 926 0 0.00 0 2300 8 0 0
v_min_id_ord_sup 5259 5259 0 0.00 0 6580 7 0 0
v_min_non_paid_invoice 5636 5636 0 0.00 0 17031 38 0 0
v_min_non_paid_realizn 6718 6718 0 0.00 0 17561 43 0 0
v_our_firm 9674 9674 0 0.00 0 13 1 0 0
v_reserve_write_off 18858 18858 0 0.00 0 29228 14 0 0
x_lock_depdocs_on_canc_invoice 11916 11916 0 0.00 0 23199 118 0 0
x_lock_depdocs_on_canc_sup_ord 391 391 0 0.00 0 5344 354 0 0
x_make_qstorno_1000_1200 5181 5181 0 0.00 2 41784 1309 0 0
x_make_qstorno_1200_2000 5262 5262 0 0.00 2 72538 3800 0 0
x_make_qstorno_2100_3300 433945 433945 0 0.00 0 99978 159 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 19-05-2022 16:45:02 to 19-05-2022 16:45:06.

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
14:12:52 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:13:53 18279 8192000000 9888141632 10195353600 970503712 10.592 100 86 172 0 998735440 5210096 23021632
14:15:16 25779 8192000000 9955550864 10275233792 1029798880 11.167 100 86 172 0 1059639536 7639824 22200832
14:16:44 13226 8192000000 9978570592 10308726784 1064915888 11.504 100 87 172 1 1095547520 9094128 21537504
14:18:03 15423 8192000000 10041107120 10388508672 1124803120 12.072 100 74 148 0 1151929424 9342736 17783568
14:19:21 9796 8192000000 10082763936 10445119488 1174094528 12.535 100 72 144 0 1201469696 10813616 16561552
14:20:37 5860 8192000000 10129887216 10502352896 1219523136 12.957 100 82 164 0 1251555440 13930208 18102096
14:21:45 9932 8192000000 10163086016 10542931968 1256281312 13.296 100 80 160 0 1287494384 13820208 17392864
14:22:57 2197 8192000000 10203324800 10585305088 1285772672 13.566 100 88 177 3 1319471008 13997552 19700784
14:24:01 1529 8192000000 10213053984 10605760512 1303312496 13.725 100 67 134 1 1330216800 11890800 15013504
14:25:04 16539 8192000000 10228918512 10622279680 1315741568 13.838 100 72 141 0 1340004064 6631328 17631168
14:26:21 7491 8192000000 10203870096 10620805120 1317400048 13.853 100 34 68 1 1334405792 8252816 8752928
14:27:31 8933 8192000000 10215769616 10635313152 1326313232 13.934 100 25 50 0 1343017472 11312480 5391760
14:28:42 9816 8192000000 10218745776 10640691200 1332781984 13.992 100 19 38 1 1346857616 9956016 4119616
14:29:52 34543 8192000000 10247642800 10651590656 1336602240 14.027 100 60 122 2 1357312912 6346416 14364256
14:31:26 8480 8192000000 10223982512 10654601216 1344482416 14.098 100 18 36 0 1354469920 5965968 4021536
14:32:36 5761 8192000000 10201717744 10625945600 1307538400 13.764 98 28 56 0 1320095248 5813376 6743472
14:33:44 11309 8192000000 10182429104 10588512256 1239161216 13.139 96 16 32 0 1252266592 9374848 3730528
14:34:55 22088 8192000000 10149492832 10550431744 1185177552 12.638 92 35 70 1 1199215328 5639296 8398480
14:36:18 6859 8192000000 10119342800 10508713984 1206031856 12.832 97 27 54 1 1218193296 6084112 6077328
14:37:25 6922 8192000000 10161150528 10564251648 1268057168 13.404 100 19 38 1 1277486880 5049776 4379936
14:38:33 14823 8192000000 10178505632 10589872128 1290709840 13.611 100 14 28 0 1299615376 5345248 3560288
14:39:47 8856 8192000000 10211083920 10603200512 1294307664 13.643 100 80 158 3 1318821824 4966448 19547712
14:40:56 6443 8192000000 10193283024 10609971200 1305995424 13.750 100 32 62 0 1318935232 5026816 7912992
14:42:03 6387 8192000000 10225289344 10633883648 1319714880 13.874 100 16 30 1 1326989936 3960144 3314912
14:43:13 57737 8192000000 10247665088 10662391808 1340175616 14.059 100 44 88 1 1359426576 9045600 10205360
14:45:13 43541 8192000000 10281754560 10693435392 1361237952 14.248 100 47 94 2 1389528960 16003600 12287408
14:47:01 12748 8192000000 10289961664 10709381120 1373187424 14.356 100 53 107 0 1409112864 23270416 12655024
14:48:16 9410 8192000000 10286993280 10708049920 1366069696 14.292 99 41 82 1 1393897104 18229936 9597472
14:49:30 7513 8192000000 10310099232 10729492480 1389075536 14.498 100 57 114 0 1423116832 20708224 13333072
14:50:44 4376 8192000000 10340277968 10759237632 1411147728 14.694 100 68 133 1 1449811776 22960960 15703088
14:51:54 4806 8192000000 10341563824 10758496256 1412430368 14.706 99 62 123 1 1453532288 25957376 15144544
14:53:02 12033 8192000000 10318001984 10746142720 1390814224 14.513 99 41 81 0 1428410000 27118720 10477056
14:54:20 3866 8192000000 10310336272 10727133184 1376978848 14.390 99 59 118 2 1418278224 26334848 14964528
14:55:29 3790 8192000000 10347625632 10759335936 1400775088 14.602 100 81 160 0 1453874560 33772192 19327280
14:56:39 2272 8192000000 10316952080 10714025984 1359833552 14.236 98 90 179 4 1420829648 38563840 22432256
14:57:52 4989 8192000000 10334128160 10738700288 1370465216 14.331 99 69 138 3 1429845872 41843216 17537440
14:59:05 7440 8192000000 10331285712 10739806208 1364993872 14.282 99 57 114 0 1424434752 44269520 15171360
15:00:15 2725 8192000000 10362853360 10766725120 1385087728 14.462 100 91 183 1 1462979408 54084992 23806688
15:01:21 13795 8192000000 10348878656 10766561280 1384455104 14.456 100 57 113 0 1454727056 54519008 15752944
15:02:40 18209 8192000000 10331094848 10746863616 1363536224 14.269 99 48 94 0 1420978368 44876592 12565552
15:04:01 23085 8192000000 10316362576 10745073664 1373588688 14.359 100 31 62 0 1419447056 37395664 8462704
15:05:27 26143 8192000000 10316511296 10754318336 1383042528 14.444 100 23 46 1 1423300736 33997168 6261040
15:06:56 21471 8192000000 10308321680 10741432320 1359124192 14.229 99 28 56 1 1408837600 41775552 7937856
15:08:20 33644 8192000000 10289485232 10736660480 1355454416 14.197 100 19 38 0 1408859392 47585120 5819856
15:09:55 39210 8192000000 10290203744 10717024256 1349197904 14.140 99 32 64 1 1405019216 46625520 9195792
15:11:35 140398 8192000000 10299111472 10751270912 1383165088 14.445 100 27 54 0 1404934624 15931424 5838112
15:14:56 53835 8192000000 10274582704 10732531712 1329410800 13.962 96 8 16 0 1346970432 16072752 1486880
15:16:51 91984 8192000000 10292160976 10745774080 1386109856 14.471 100 21 43 0 1402862048 12285232 4466960
15:19:23 72028 8192000000 10291503808 10750353408 1385025072 14.461 100 14 28 0 1400772032 12331184 3415776
15:21:57 38835 8192000000 10312435840 10761019392 1399506528 14.591 100 23 46 0 1413528592 8929856 5092208
15:24:00 80624 8192000000 10289130176 10737238016 1342953616 14.084 96 32 64 0 1360625696 9381536 8290544
15:26:22 14534 8192000000 10290662064 10746257408 1388481536 14.492 100 18 36 2 1404266608 11414704 4370368
15:27:44 26408 8192000000 10295281152 10752544768 1392107920 14.525 100 37 74 0 1417169248 16571536 8489792
15:29:19 90650 8192000000 10313653104 10769661952 1393917488 14.541 99 16 32 0 1416937024 19095584 3923952
15:31:55 70345 8192000000 10315527312 10772643840 1408251392 14.668 100 17 34 1 1434842528 22564336 4026800
15:34:14 106751 8192000000 10298471680 10765885440 1342083344 14.076 96 8 15 0 1359087072 15359472 1644256
15:37:04 49300 8192000000 10326223760 10762936320 1386281264 14.473 99 41 82 1 1409231168 13567440 9382464
15:38:55 66696 8192000000 10295586080 10747162624 1365180704 14.284 98 12 24 1 1380557920 12583376 2793840
15:41:02 73107 8192000000 10302615664 10742702080 1333450880 13.998 96 20 40 0 1353047456 15221072 4375504
15:43:33 74947 8192000000 10290722016 10748276736 1343889472 14.092 97 19 38 0 1363349856 15310320 4150064
15:45:49 90737 8192000000 10272751536 10720120832 1357972384 14.219 99 33 65 1 1372786560 7008672 7805504
15:48:24 97601 8192000000 10245936032 10707922944 1334332080 14.006 98 15 30 0 1346014208 8009808 3672320
15:52:06 53766 8192000000 10274676368 10723520512 1369124752 14.319 100 18 36 0 1385279008 12014496 4139760
15:54:11 76408 8192000000 10257550656 10700058624 1319379248 13.871 96 28 55 4 1334481680 8931328 6171104
15:56:29 28382 8192000000 10242920096 10673594368 1296237952 13.661 96 58 115 1 1317554752 7742592 13574208
15:57:59 68548 8192000000 10255882224 10705707008 1319828096 13.875 97 20 40 0 1336224464 11943168 4453200
16:00:09 71769 8192000000 10245240032 10698653696 1324468704 13.917 98 12 24 0 1338750112 11565232 2716176
16:02:33 77560 8192000000 10242348480 10708131840 1307125200 13.760 97 7 14 0 1330015280 21302352 1587728
16:04:51 41210 8192000000 10252942112 10696826880 1348818304 14.137 100 20 39 1 1366052112 12857952 4375856
16:06:35 29294 8192000000 10269017248 10711572480 1332454240 13.989 98 35 70 1 1355103920 14808800 7840880
16:08:07 62122 8192000000 10281849648 10715271168 1354644160 14.189 99 3 7 0 1365973872 11014048 315664
16:10:13 44879 8192000000 10290577024 10743242752 1358018176 14.220 98 17 34 1 1383005008 21001632 3985200
16:12:29 34023 8192000000 10306003280 10747473920 1358385344 14.223 98 21 42 2 1373436752 10486624 4564784
16:14:11 121208 8192000000 10304548672 10740465664 1356743056 14.208 98 17 34 2 1387714960 26191600 4780304
16:17:54 76047 8192000000 10291507120 10763784192 1392708608 14.530 100 10 20 0 1409762736 14639088 2415040
16:20:10 63357 8192000000 10296600144 10751750144 1386797776 14.477 99 17 34 0 1399292240 8501312 3993152
16:22:21 28298 8192000000 10310561328 10759745536 1400506688 14.600 100 17 33 0 1412797088 8574384 3716016
16:23:51 62304 8192000000 10297833216 10753540096 1387357952 14.482 99 11 22 0 1398432976 8556928 2518096
16:25:53 24311 8192000000 10306610624 10756681728 1397606608 14.574 100 43 86 1 1415918752 8388192 9923952
16:27:18 144019 8192000000 10295167536 10754080768 1340957744 14.066 95 19 38 0 1361214656 15924384 4332528
16:30:42 102019 8192000000 10298045584 10752466944 1374085888 14.364 99 5 9 0 1390568016 15509824 972304
16:33:38 131104 8192000000 10291016672 10747781120 1355603680 14.198 98 8 16 0 1378371072 20839488 1927904
16:37:26 48588 8192000000 10279350592 10739003392 1368362048 14.312 99 9 18 0 1380897648 10491248 2044352
16:39:19 68465 8192000000 10287715408 10741522432 1375860752 14.380 99 10 20 1 1389570464 10689072 3020640
16:41:30 74319 8192000000 10275867488 10716848128 1301854688 13.712 94 22 44 2 1315364384 9288784 4220912

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 19-05-2022 16:45:08 to 19-05-2022 16:45:22.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 69 v_add_invoice_to_stock 335544517
except 2 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 19-05-2022 16:45:23 to 19-05-2022 16:45:25.

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          234127
MON$OLDEST_ACTIVE               236114
MON$OLDEST_SNAPSHOT             236114
MON$NEXT_TRANSACTION            236114
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                       2034400
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.33593 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33593 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33593 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33593 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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Thu May 19 16:42:53 2022
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Thu May 19 16:43:11 2022
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:13: 
:14: 
:15: mscs-test-161.budzdorov.ru    Thu May 19 16:43:11 2022
:16:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:17: 
:18: 
:19: mscs-test-161.budzdorov.ru    Thu May 19 16:43:11 2022
:20:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:21: 
:22: 
:23: mscs-test-161.budzdorov.ru    Thu May 19 16:43:11 2022
:24:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:25: 
--- end of diff output ---

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

19.05.22 16:45:26. 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-05-19 16:45:29 No severe PSQL-related problems occured
19.05.22 16:45:30. End of report.