Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 29.06.2022 22:40
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 16358584 kB
MemAvailable 31226472 kB
Buffers 0 kB
Cached 15397444 kB
SwapCached 2496 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-29 22:38:23
DTS_END                         2022-06-29 22:38:23.1160
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/60760
TRN_ID                          235815
ATT_ID                          656
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: 9020.63 21976 1082475
customer order: creation 165.80 346 19896
customer order: refuse 14.59 388 1751
order to supplier: creation 43.85 1255 5262
order to supplier: removal 3.37 88878 404
invoice (draft): creation 45.45 3494 5454
invoice (draft): removal 51.30 724 6156
invoice accept: apply 40.74 19082 4889
invoice accept: cancel 45.83 742 5500
customer reserve: creation 3753.05 191 450366
customer reserve: removal 4565.33 50 547840
realization accept: apply 81.96 214 9835
realization accept: cancel 81.09 99 9731
payment from customer: creation 59.67 149 7160
payment from customer: removal 5.56 52 667
payment to supplier: creation 48.12 156 5774
payment to supplier: removal 3.54 58 425
service: total inventory turnovers 4.37 1080 524
service: total monetary turnovers 6.32 30 758
service: aggregate perf. data 0.66 27067 79
service: refresh index statistics 0.03 295457 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 29-06-2022 22:40:55 to 29-06-2022 22:40:57.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3421
TEST_TIME 2 3971
TEST_TIME 3 3682
TEST_TIME 4 4148
TEST_TIME 5 5386
TEST_TIME 6 5329
TEST_TIME 7 5040
TEST_TIME 8 5104
TEST_TIME 9 5176
TEST_TIME 10 5361
TEST_TIME 11 5639
TEST_TIME 12 5723
TEST_TIME 13 5949
TEST_TIME 14 6457
TEST_TIME 15 6846
TEST_TIME 16 7281
TEST_TIME 17 7330
TEST_TIME 18 7614
TEST_TIME 19 7825
TEST_TIME 20 7887
TEST_TIME 21 8372
TEST_TIME 22 9065
TEST_TIME 23 9172
TEST_TIME 24 9149
TEST_TIME 25 9497
TEST_TIME 26 9651
TEST_TIME 27 9674
TEST_TIME 28 9888
TEST_TIME 29 9807
TEST_TIME 30 9791
TEST_TIME 31 9590
TEST_TIME 32 9788
TEST_TIME 33 9605
TEST_TIME 34 9500
TEST_TIME 35 9413
TEST_TIME 36 9327
TEST_TIME 37 9143
TEST_TIME 38 9180
TEST_TIME 39 9113
TEST_TIME 40 9072
TEST_TIME 41 9033
TEST_TIME 42 8949
TEST_TIME 43 9037
TEST_TIME 44 8948
TEST_TIME 45 8854
TEST_TIME 46 8855
TEST_TIME 47 8926
TEST_TIME 48 8823
TEST_TIME 49 8917
TEST_TIME 50 9078
TEST_TIME 51 9144
TEST_TIME 52 9021
TEST_TIME 53 9117
TEST_TIME 54 9176
TEST_TIME 55 9130
TEST_TIME 56 9132
TEST_TIME 57 9239
TEST_TIME 58 9295
TEST_TIME 59 9232
TEST_TIME 60 9294
TEST_TIME 61 9305
TEST_TIME 62 9307
TEST_TIME 63 9335
TEST_TIME 64 9505
TEST_TIME 65 9398
TEST_TIME 66 9264
TEST_TIME 67 9275
TEST_TIME 68 9256
TEST_TIME 69 9264
TEST_TIME 70 9213
TEST_TIME 71 9140
TEST_TIME 72 9119
TEST_TIME 73 9094
TEST_TIME 74 9040
TEST_TIME 75 9082
TEST_TIME 76 9062
TEST_TIME 77 9067
TEST_TIME 78 9053
TEST_TIME 79 9020
TEST_TIME 80 8987
TEST_TIME 81 9000
TEST_TIME 82 8991
TEST_TIME 83 9006
TEST_TIME 84 8961
TEST_TIME 85 9041
TEST_TIME 86 9092
TEST_TIME 87 9076
TEST_TIME 88 9091
TEST_TIME 89 9104
TEST_TIME 90 9126
TEST_TIME 91 9181
TEST_TIME 92 9224
TEST_TIME 93 9239
TEST_TIME 94 9266
TEST_TIME 95 9353
TEST_TIME 96 9308
TEST_TIME 97 9217
TEST_TIME 98 9182
TEST_TIME 99 9151
TEST_TIME 100 9136
TEST_TIME 101 9137
TEST_TIME 102 9123
TEST_TIME 103 9089
TEST_TIME 104 9090
TEST_TIME 105 9112
TEST_TIME 106 9121
TEST_TIME 107 9100
TEST_TIME 108 9066
TEST_TIME 109 9135
TEST_TIME 110 9111
TEST_TIME 111 9153
TEST_TIME 112 9160
TEST_TIME 113 9183
TEST_TIME 114 9242
TEST_TIME 115 9198
TEST_TIME 116 9136
TEST_TIME 117 9145
TEST_TIME 118 9087
TEST_TIME 119 9078
TEST_TIME 120 9024

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 29-06-2022 22:40:59 to 29-06-2022 22:41:02.

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 1499730 1499730 0 0.00 0 38225 7 0 0
doc_list_biud 1499711 1499711 0 0.00 0 65684 24 0 0
sp_add_invoice_to_stock 5001 4889 112 2.23 23 186235 19082 0 112
sp_cancel_adding_invoice 5500 5500 0 0.00 4 55754 743 0 0
sp_cancel_client_order 1751 1751 0 0.00 4 13704 388 0 0
sp_cancel_customer_reserve 547840 547840 0 0.00 0 65685 51 0 0
sp_cancel_pay_from_customer 667 667 0 0.00 2 1351 52 0 0
sp_cancel_pay_to_supplier 425 425 0 0.00 3 1051 58 0 0
sp_cancel_supplier_invoice 6164 6156 8 0.12 1 22030 725 0 8
sp_cancel_supplier_order 404 404 0 0.00 5 846163 88878 0 0
sp_cancel_write_off 9731 9731 0 0.00 1 24813 100 0 0
sp_client_order 19896 19896 0 0.00 3 65350 347 0 0
sp_customer_reserve 450366 450366 0 0.00 0 37776 191 0 0
sp_fill_shopping_cart 30608 30608 0 0.00 0 34256 38 0 0
sp_fill_shopping_cart_clo_res 450363 450363 0 0.00 0 35862 14 0 0
sp_get_clo_for_invoice 4883 4883 0 0.00 0 30674 330 0 0
sp_kill_cost_storno 16323 16323 0 0.00 0 6198 46 0 0
sp_kill_qstorno_ret_qs2qd 1111971 1111971 0 0.00 0 65682 28 0 0
sp_kill_qty_storno 586087 586087 0 0.00 0 65684 61 0 0
sp_lock_selected_doc 20405 20405 0 0.00 0 7030 4 0 0
sp_make_cost_storno 27652 27652 0 0.00 0 38195 90 0 0
sp_multiply_rows_for_pdistr 27638 27638 0 0.00 0 13965 27 0 0
sp_multiply_rows_for_qdistr 19896 19896 0 0.00 0 65340 141 0 0
sp_pay_from_customer 7160 7160 0 0.00 3 29977 149 0 0
sp_pay_to_supplier 5774 5774 0 0.00 4 30707 156 0 0
sp_payment_common 12928 12928 0 0.00 1 24151 31 0 0
sp_qd_handle_on_cancel_clo 1751 1751 0 0.00 0 13692 358 0 0
sp_qd_handle_on_invoice_upd_sts 10381 10381 0 0.00 0 36080 315 0 0
sp_qd_handle_on_reserve_upd_sts 9824 9824 0 0.00 0 8681 57 0 0
sp_reserve_write_off 9835 9835 0 0.00 6 30832 214 0 0
sp_supplier_invoice 5454 5454 0 0.00 6 107176 3494 0 0
sp_supplier_order 5262 5262 0 0.00 4 53645 1255 0 0
srv_aggregate_perf_data 79 79 0 0.00 3046 484725 27068 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1469 163611 48895 0 0
srv_find_qd_qs_mism 1035891 1035891 0 0.00 0 24791 5 0 0
srv_make_invnt_saldo 524 524 0 0.00 32 37923 1081 0 0
srv_make_money_saldo 758 758 0 0.00 1 4392 30 0 0
srv_recalc_idx_stat 4 4 0 0.00 173350 366641 295457 0 0
t$perf-abend:sp_add_invoice_to_stock 112 112 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4890 4890 0 0.00 0 22368 190 0 0
t$perf-norm:sp_cancel_adding_invoice 374 374 0 0.00 0 4784 71 0 0
t$perf-norm:sp_cancel_client_order 1751 1751 0 0.00 0 3570 14 0 0
t$perf-norm:sp_cancel_customer_reserve 674 674 0 0.00 0 6473 20 0 0
t$perf-norm:sp_cancel_pay_from_customer 667 667 0 0.00 0 3905 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 425 425 0 0.00 0 288 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 402 402 0 0.00 0 8772 53 0 0
t$perf-norm:sp_cancel_supplier_order 404 404 0 0.00 0 18378 1575 0 0
t$perf-norm:sp_cancel_write_off 1003 1003 0 0.00 0 3292 11 0 0
t$perf-norm:sp_client_order 19896 19896 0 0.00 0 6909 18 0 0
t$perf-norm:sp_customer_reserve 950 950 0 0.00 0 1552 7 0 0
t$perf-norm:sp_pay_from_customer 7160 7160 0 0.00 0 5252 11 0 0
t$perf-norm:sp_pay_to_supplier 5774 5774 0 0.00 0 7171 11 0 0
t$perf-norm:sp_reserve_write_off 9835 9835 0 0.00 0 14552 15 0 0
t$perf-norm:sp_supplier_invoice 5454 5454 0 0.00 0 9477 19 0 0
t$perf-norm:sp_supplier_order 5262 5262 0 0.00 0 15848 19 0 0
t$perf-norm:srv_aggregate_perf_data 79 79 0 0.00 0 392 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 32 1 0 0
t$perf-norm:srv_make_invnt_saldo 524 524 0 0.00 0 3715 36 0 0
t$perf-norm:srv_make_money_saldo 758 758 0 0.00 0 1199 16 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 6 3 0 0
v_add_invoice_to_stock 9982 9758 224 2.24 0 5797 20 0 224
v_all_customers 36540 36540 0 0.00 0 34 1 0 0
v_all_suppliers 29740 29740 0 0.00 0 97 1 0 0
v_all_wares 39792 39792 0 0.00 0 35 1 0 0
v_cancel_adding_invoice 748 748 0 0.00 0 2429 13 0 0
v_cancel_client_order 3502 3502 0 0.00 0 6076 7 0 0
v_cancel_customer_prepayment 1334 1334 0 0.00 0 1186 4 0 0
v_cancel_customer_reserve 1348 1348 0 0.00 0 2999 8 0 0
v_cancel_payment_to_supplier 850 850 0 0.00 0 593 5 0 0
v_cancel_supplier_invoice 820 804 16 1.95 0 2769 23 0 16
v_cancel_supplier_order 808 808 0 0.00 0 8235 25 0 0
v_cancel_write_off 2006 2006 0 0.00 0 6737 20 0 0
v_max_id_clo_ord 5262 5262 0 0.00 0 168 1 0 0
v_max_id_clo_res 950 950 0 0.00 0 6245 19 0 0
v_max_id_ord_sup 5450 5450 0 0.00 0 5514 2 0 0
v_max_non_paid_invoice 1612 1612 0 0.00 0 3461 5 0 0
v_max_non_paid_realizn 3790 3790 0 0.00 0 6174 8 0 0
v_min_id_clo_ord 5262 5262 0 0.00 0 4351 8 0 0
v_min_id_clo_res 950 950 0 0.00 0 3763 20 0 0
v_min_id_ord_sup 5450 5450 0 0.00 0 5364 7 0 0
v_min_non_paid_invoice 5770 5770 0 0.00 0 10284 18 0 0
v_min_non_paid_realizn 7158 7158 0 0.00 0 13520 21 0 0
v_our_firm 9988 9988 0 0.00 0 19 1 0 0
v_reserve_write_off 19648 19648 0 0.00 0 7968 5 0 0
x_lock_depdocs_on_canc_invoice 11254 11254 0 0.00 0 32002 119 0 0
x_lock_depdocs_on_canc_sup_ord 404 404 0 0.00 0 20913 513 0 0
x_make_qstorno_1000_1200 5262 5262 0 0.00 1 53420 1181 0 0
x_make_qstorno_1200_2000 5454 5454 0 0.00 3 106589 3359 0 0
x_make_qstorno_2100_3300 450366 450366 0 0.00 0 37776 174 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 29-06-2022 22:41:03 to 29-06-2022 22:41:07.

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
20:08:26 12 8192000000 8806550864 8820334592 11749296 0.143 3 3 4 0 11933216 76800 107120
20:09:28 24088 8192000000 9904884736 10212823040 983453600 10.718 100 83 166 0 1011322288 5870272 21998416
20:11:00 24090 8192000000 9946348608 10267410432 1024673760 11.117 100 90 180 0 1056004848 8402016 22929072
20:12:34 16590 8192000000 9983181520 10320207872 1070060304 11.553 100 74 148 0 1097812064 9571872 18179888
20:14:07 8184 8192000000 10040675088 10387587072 1130446800 12.126 100 83 164 0 1160617680 10631488 19539392
20:15:23 7046 8192000000 10093056512 10450313216 1174948064 12.543 100 79 158 0 1204274032 10953936 18372032
20:16:34 4086 8192000000 10121950528 10492289024 1211495424 12.883 100 79 158 0 1242134736 13516464 17122848
20:17:41 3203 8192000000 10159799024 10531905536 1246992096 13.211 100 87 169 1 1279798544 13752656 19053792
20:18:46 1438 8192000000 10186670240 10567311360 1278495920 13.499 100 84 169 0 1309532064 12566128 18470016
20:19:50 1149 8192000000 10218142640 10604883968 1306014000 13.750 100 89 177 1 1338193120 12685184 19493936
20:20:52 18755 8192000000 10197301200 10605965312 1311183904 13.797 100 34 69 0 1325493552 5765568 8544080
20:22:15 7406 8192000000 10208234448 10623696896 1321029568 13.886 100 21 42 0 1332206656 6444496 4732592
20:23:24 7121 8192000000 10215678000 10632589312 1328940224 13.958 100 14 28 0 1337800592 5288352 3572016
20:24:32 11596 8192000000 10227743824 10646020096 1338301808 14.042 100 29 59 2 1351778368 6316096 7160464
20:25:46 8367 8192000000 10252347664 10658037760 1338373456 14.043 100 60 117 0 1358376144 6228080 13774608
20:26:55 3543 8192000000 10243728448 10660540416 1342222064 14.077 100 40 80 1 1358890752 7334112 9334576
20:28:01 16036 8192000000 10232197744 10651713536 1325978672 13.931 99 15 31 1 1334775360 5470528 3326160
20:29:17 69688 8192000000 10193879248 10610982912 1255408608 13.288 95 24 46 1 1265066240 4412960 5244672
20:31:29 7034 8192000000 10154303936 10554519552 1228943296 13.044 97 31 62 1 1243102928 7132192 7027440
20:32:36 6988 8192000000 10142478048 10535215104 1213459712 12.901 97 27 53 0 1229938048 10473840 6004496
20:33:45 50013 8192000000 10170214848 10567913472 1253177632 13.267 99 33 65 3 1266180992 4898352 8105008
20:35:44 6292 8192000000 10184968400 10591854592 1289313632 13.598 100 25 50 2 1301393600 6140144 5939824
20:36:50 10036 8192000000 10200044208 10609545216 1304270528 13.734 100 20 41 0 1314633392 5159008 5203856
20:38:01 24037 8192000000 10224462624 10632118272 1318661968 13.865 100 5 10 0 1323994192 4708208 624016
20:39:28 65850 8192000000 10238943424 10662178816 1346785088 14.119 100 47 94 0 1366225520 8845248 10595184
20:41:41 21315 8192000000 10261373568 10681724928 1359773120 14.235 100 30 56 2 1375098384 8954448 6370816
20:43:08 71575 8192000000 10285898528 10717388800 1383216240 14.445 100 23 46 0 1400678336 11516160 5945936
20:45:28 25079 8192000000 10305118720 10735476736 1396285168 14.562 100 48 94 2 1423308560 16095488 10927904
20:47:04 10611 8192000000 10326361776 10747682816 1394729536 14.548 99 50 100 1 1426281728 19441920 12110272
20:48:22 11790 8192000000 10331502848 10762383360 1409720816 14.681 100 42 82 1 1441249584 21529136 9999632
20:49:36 1465 8192000000 10349604896 10764169216 1410074416 14.685 100 81 155 2 1451967856 23561392 18332048
20:50:41 2289 8192000000 10369237328 10788392960 1419810816 14.771 100 75 149 0 1467359536 29881456 17667264
20:51:52 9954 8192000000 10364359376 10787975168 1419877904 14.772 100 63 125 1 1469486768 34337664 15271200
20:53:08 5198 8192000000 10351108912 10768830464 1403529168 14.626 100 71 142 0 1454117584 33325536 17262880
20:54:21 1489 8192000000 10348348192 10757496832 1395352832 14.554 100 91 184 1 1454069504 36184032 22532640
20:55:27 9526 8192000000 10339849888 10754314240 1387491984 14.483 99 62 125 1 1435876912 32899296 15485632
20:56:46 2361 8192000000 10327292800 10745274368 1387462304 14.483 100 51 100 1 1431601568 31695264 12444000
20:57:57 2723 8192000000 10311967472 10721452032 1364463792 14.277 99 64 126 0 1413738768 33596432 15678544
20:59:05 1640 8192000000 10317495248 10731663360 1367178576 14.302 100 60 120 2 1420744016 38544880 15020560
21:00:14 12123 8192000000 10301023696 10724864000 1362690064 14.262 100 42 84 1 1404757664 32283872 9783728
21:01:30 21294 8192000000 10280726240 10717052928 1321752704 13.893 96 25 50 0 1352559696 24222240 6584752
21:02:55 20013 8192000000 10292006448 10720505856 1355391936 14.196 100 29 58 1 1392482848 29135232 7955680
21:04:19 30873 8192000000 10260872000 10696777728 1345953440 14.111 100 18 37 0 1369614752 19378928 4282384
21:05:52 6678 8192000000 10276117376 10711244800 1356201648 14.203 100 26 52 0 1380684384 17965856 6516880
21:07:14 58633 8192000000 10288151584 10727280640 1367009920 14.300 100 42 84 2 1392014816 15036832 9968064
21:09:20 21486 8192000000 10297496992 10730078208 1377974208 14.398 100 47 88 1 1398769984 10051328 10744448
21:10:54 19447 8192000000 10277150864 10735099904 1376571696 14.386 100 15 28 0 1388852048 9435216 2845136
21:12:18 53742 8192000000 10285820592 10724052992 1347613808 14.126 97 27 54 0 1360714976 7093008 6008160
21:14:12 42511 8192000000 10274534640 10727751680 1350219056 14.149 98 10 20 0 1362439936 10120912 2099968
21:15:56 106079 8192000000 10273625568 10723946496 1365270272 14.285 99 25 51 0 1383891120 12720000 5900848
21:18:50 49448 8192000000 10269692656 10723151872 1335304032 14.015 96 27 54 1 1355206832 13885248 6017552
21:20:46 82993 8192000000 10296276352 10743590912 1388978320 14.497 100 19 38 0 1400608480 7216992 4413168
21:23:14 39368 8192000000 10314949696 10759516160 1386466176 14.474 99 37 74 0 1416659744 21404448 8789120
21:24:58 54338 8192000000 10328778656 10788454400 1411721424 14.699 100 16 33 0 1443216560 27023408 4471728
21:26:58 51529 8192000000 10316259456 10781818880 1406786528 14.655 100 21 42 0 1443176416 30992832 5397056
21:28:56 58313 8192000000 10310033200 10776350720 1407858160 14.665 100 15 30 0 1433972800 22547264 3567376
21:31:31 68289 8192000000 10306477280 10769612800 1407178448 14.659 100 14 26 0 1429671600 19630768 2862384
21:33:40 20823 8192000000 10333272128 10777182208 1413248336 14.713 100 57 108 0 1435194688 9461616 12484736
21:35:01 62981 8192000000 10326209248 10786570240 1391062016 14.515 99 36 71 0 1418818592 19944816 7811760
21:37:04 60478 8192000000 10322517040 10763026432 1359331552 14.231 97 23 46 1 1374744448 10643392 4769504
21:39:11 61355 8192000000 10288061024 10729701376 1312197488 13.806 94 15 30 0 1322570816 7443120 2930208
21:41:16 58224 8192000000 10295542560 10748661760 1364651696 14.279 99 16 32 0 1386068928 17815280 3601952
21:43:21 91297 8192000000 10280327104 10725281792 1348047248 14.130 98 19 38 0 1376852944 24405392 4400304
21:46:03 60233 8192000000 10274442944 10736197632 1356579664 14.207 99 11 23 0 1377019648 17842896 2597088
21:48:34 61010 8192000000 10258235584 10702938112 1338495232 14.044 98 20 40 0 1353159440 9632624 5031584
21:50:36 47000 8192000000 10253923136 10703036416 1344848848 14.101 99 19 38 0 1361906688 12829392 4228448
21:52:25 54853 8192000000 10255515280 10706075648 1331105408 13.977 98 22 44 0 1352785696 16069312 5610976
21:54:21 61427 8192000000 10255236496 10699722752 1330473728 13.971 98 27 54 1 1350039632 13695184 5870720
21:56:36 69407 8192000000 10253166208 10711130112 1355993504 14.201 100 14 28 0 1371230400 12277744 2959152
21:58:46 90972 8192000000 10250130656 10708893696 1318146784 13.860 97 3 6 0 1332277088 13661120 469184
22:01:18 90995 8192000000 10265749504 10700722176 1307708176 13.765 96 25 48 1 1323872304 10418256 5745872
22:03:55 77133 8192000000 10237431712 10687086592 1288849648 13.594 96 17 34 1 1304369392 11840352 3679392
22:06:12 37725 8192000000 10268400448 10711048192 1356213520 14.203 100 31 62 0 1372067312 9044976 6808816
22:07:54 45960 8192000000 10290459392 10729377792 1348649632 14.135 98 37 74 0 1369341568 12937696 7754240
22:09:43 137466 8192000000 10276683152 10726674432 1308850816 13.776 95 12 24 0 1326833184 15554752 2427616
22:13:00 163611 8192000000 10290974832 10756448256 1372110256 14.346 99 8 16 0 1394548336 20841488 1596592
22:16:46 72428 8192000000 10291596128 10758807552 1388753840 14.495 100 12 24 0 1408353296 16812928 2786528
22:18:59 75121 8192000000 10292654864 10748248064 1380545728 14.421 99 10 20 0 1391003504 8393712 2064064
22:21:15 73353 8192000000 10308636416 10771320832 1399844896 14.594 100 26 52 0 1420550240 14700752 6004592
22:23:30 78216 8192000000 10282795808 10739118080 1329828400 13.966 95 12 24 1 1343347904 11025680 2493824
22:25:50 48003 8192000000 10315330096 10763567104 1370817648 14.334 98 35 70 1 1391154880 13025824 7311408
22:28:15 60233 8192000000 10279990496 10731077632 1329015984 13.958 96 15 30 0 1344662448 12261408 3385056
22:30:19 53218 8192000000 10292513424 10731667456 1338543584 14.044 97 19 38 0 1356973328 13509264 4920480
22:32:13 73680 8192000000 10253355408 10706767872 1300620624 13.701 95 1 2 0 1317224992 16562016 42352
22:34:27 111151 8192000000 10253856544 10694991872 1293821856 13.639 95 24 47 0 1309200400 9834992 5543552
22:37:21 17930 8192000000 10271465968 10715082752 1355514752 14.197 99 33 66 0 1374286784 11724576 7047456

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 29-06-2022 22:41:09 to 29-06-2022 22:41:23.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 29-06-2022 22:41:24 to 29-06-2022 22:41:26.

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          233991
MON$OLDEST_ACTIVE               235965
MON$OLDEST_SNAPSHOT             235965
MON$NEXT_TRANSACTION            235965
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                       2020096
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.33605 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33605 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33605 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33605 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 ---
:72: 
:73: mscs-test-161.budzdorov.ru    Wed Jun 29 22:38:54 2022
:74:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:75: 
--- end of diff output ---

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

29.06.22 22:41:27. 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-29 22:41:30 No severe PSQL-related problems occured
29.06.22 22:41:31. End of report.