Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 14.05.2022 16: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 16661268 kB
MemAvailable 31466580 kB
Buffers 0 kB
Cached 15064524 kB
SwapCached 2648 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-14 16:38:17
DTS_END                         2022-05-14 16:38:17.0970
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(131:22) ==> SP_CANCEL_ADDING_INVOICE(33:5) ==> SP_ADD_INVOICE_TO_STOCK(69:14) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/56362
TRN_ID                          248103
ATT_ID                          687
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: 9075.78 20878 1089093
customer order: creation 179.83 361 21580
customer order: refuse 14.98 368 1798
order to supplier: creation 47.79 1263 5735
order to supplier: removal 3.37 108157 404
invoice (draft): creation 47.71 3526 5725
invoice (draft): removal 49.59 758 5951
invoice accept: apply 42.63 16829 5116
invoice accept: cancel 44.91 918 5389
customer reserve: creation 3934.03 166 472083
customer reserve: removal 4400.57 67 528068
realization accept: apply 89.87 173 10784
realization accept: cancel 83.62 108 10034
payment from customer: creation 63.01 92 7561
payment from customer: removal 5.72 73 686
payment to supplier: creation 51.79 109 6215
payment to supplier: removal 3.69 81 443
service: total inventory turnovers 4.63 1030 556
service: total monetary turnovers 7.05 23 846
service: aggregate perf. data 0.96 16189 115
service: refresh index statistics 0.03 267271 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 14-05-2022 16:39:44 to 14-05-2022 16:39:46.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3648
TEST_TIME 2 3597
TEST_TIME 3 4688
TEST_TIME 4 4735
TEST_TIME 5 5223
TEST_TIME 6 4955
TEST_TIME 7 5354
TEST_TIME 8 5579
TEST_TIME 9 5561
TEST_TIME 10 5716
TEST_TIME 11 5868
TEST_TIME 12 6052
TEST_TIME 13 6286
TEST_TIME 14 6380
TEST_TIME 15 6983
TEST_TIME 16 7251
TEST_TIME 17 7538
TEST_TIME 18 7538
TEST_TIME 19 8175
TEST_TIME 20 8125
TEST_TIME 21 8364
TEST_TIME 22 8789
TEST_TIME 23 8960
TEST_TIME 24 9124
TEST_TIME 25 9517
TEST_TIME 26 9733
TEST_TIME 27 10224
TEST_TIME 28 10855
TEST_TIME 29 10726
TEST_TIME 30 10447
TEST_TIME 31 10273
TEST_TIME 32 10271
TEST_TIME 33 10460
TEST_TIME 34 10380
TEST_TIME 35 10309
TEST_TIME 36 10153
TEST_TIME 37 10076
TEST_TIME 38 10070
TEST_TIME 39 9933
TEST_TIME 40 9850
TEST_TIME 41 9721
TEST_TIME 42 9755
TEST_TIME 43 9669
TEST_TIME 44 9535
TEST_TIME 45 9404
TEST_TIME 46 9428
TEST_TIME 47 9454
TEST_TIME 48 9386
TEST_TIME 49 9306
TEST_TIME 50 9230
TEST_TIME 51 9219
TEST_TIME 52 9128
TEST_TIME 53 9136
TEST_TIME 54 9166
TEST_TIME 55 9319
TEST_TIME 56 9313
TEST_TIME 57 9354
TEST_TIME 58 9291
TEST_TIME 59 9305
TEST_TIME 60 9391
TEST_TIME 61 9453
TEST_TIME 62 9478
TEST_TIME 63 9474
TEST_TIME 64 9483
TEST_TIME 65 9612
TEST_TIME 66 9626
TEST_TIME 67 9667
TEST_TIME 68 9595
TEST_TIME 69 9552
TEST_TIME 70 9443
TEST_TIME 71 9458
TEST_TIME 72 9382
TEST_TIME 73 9382
TEST_TIME 74 9400
TEST_TIME 75 9360
TEST_TIME 76 9330
TEST_TIME 77 9270
TEST_TIME 78 9218
TEST_TIME 79 9216
TEST_TIME 80 9218
TEST_TIME 81 9215
TEST_TIME 82 9213
TEST_TIME 83 9252
TEST_TIME 84 9239
TEST_TIME 85 9240
TEST_TIME 86 9302
TEST_TIME 87 9321
TEST_TIME 88 9300
TEST_TIME 89 9302
TEST_TIME 90 9389
TEST_TIME 91 9404
TEST_TIME 92 9399
TEST_TIME 93 9340
TEST_TIME 94 9271
TEST_TIME 95 9253
TEST_TIME 96 9194
TEST_TIME 97 9126
TEST_TIME 98 9170
TEST_TIME 99 9158
TEST_TIME 100 9152
TEST_TIME 101 9184
TEST_TIME 102 9169
TEST_TIME 103 9167
TEST_TIME 104 9198
TEST_TIME 105 9195
TEST_TIME 106 9189
TEST_TIME 107 9256
TEST_TIME 108 9244
TEST_TIME 109 9266
TEST_TIME 110 9265
TEST_TIME 111 9286
TEST_TIME 112 9280
TEST_TIME 113 9239
TEST_TIME 114 9212
TEST_TIME 115 9183
TEST_TIME 116 9148
TEST_TIME 117 9123
TEST_TIME 118 9117
TEST_TIME 119 9137
TEST_TIME 120 9080

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 14-05-2022 16:39:48 to 14-05-2022 16:39:51.

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 1527782 1527782 0 0.00 0 28615 6 0 0
doc_list_biud 1527781 1527781 0 0.00 0 34166 29 0 0
sp_add_invoice_to_stock 5223 5116 107 2.04 39 171858 16829 0 107
sp_cancel_adding_invoice 5389 5389 0 0.00 6 70866 919 0 0
sp_cancel_client_order 1798 1798 0 0.00 4 12297 369 0 0
sp_cancel_customer_reserve 528068 528068 0 0.00 1 31044 68 0 0
sp_cancel_pay_from_customer 686 686 0 0.00 2 2821 73 0 0
sp_cancel_pay_to_supplier 443 443 0 0.00 3 4263 82 0 0
sp_cancel_supplier_invoice 5960 5951 9 0.15 2 34170 759 0 9
sp_cancel_supplier_order 404 404 0 0.00 7 1077071 108158 0 0
sp_cancel_write_off 10034 10034 0 0.00 1 9680 109 0 0
sp_client_order 21580 21580 0 0.00 3 39060 362 0 0
sp_customer_reserve 472083 472083 0 0.00 0 48973 167 0 0
sp_fill_shopping_cart 33027 33027 0 0.00 1 18388 38 0 0
sp_fill_shopping_cart_clo_res 472080 472080 0 0.00 0 19183 14 0 0
sp_get_clo_for_invoice 5113 5113 0 0.00 0 19494 293 0 0
sp_kill_cost_storno 16552 16552 0 0.00 0 8745 57 0 0
sp_kill_qstorno_ret_qs2qd 1072525 1072525 0 0.00 0 34165 36 0 0
sp_kill_qty_storno 567541 567541 0 0.00 0 34166 77 0 0
sp_lock_selected_doc 21905 21905 0 0.00 0 8242 6 0 0
sp_make_cost_storno 29673 29673 0 0.00 0 13961 48 0 0
sp_multiply_rows_for_pdistr 29673 29673 0 0.00 0 6764 27 0 0
sp_multiply_rows_for_qdistr 21580 21580 0 0.00 0 12580 164 0 0
sp_pay_from_customer 7561 7561 0 0.00 4 7197 92 0 0
sp_pay_to_supplier 6215 6215 0 0.00 4 9771 109 0 0
sp_payment_common 13776 13776 0 0.00 1 9763 25 0 0
sp_qd_handle_on_cancel_clo 1798 1798 0 0.00 0 12221 325 0 0
sp_qd_handle_on_invoice_upd_sts 10502 10502 0 0.00 0 32040 340 0 0
sp_qd_handle_on_reserve_upd_sts 10784 10784 0 0.00 0 15426 61 0 0
sp_reserve_write_off 10784 10784 0 0.00 6 15452 174 0 0
sp_supplier_invoice 5725 5725 0 0.00 6 99644 3526 0 0
sp_supplier_order 5735 5735 0 0.00 4 22658 1263 0 0
srv_aggregate_perf_data 115 115 0 0.00 2601 252286 16189 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1692 155558 50355 0 0
srv_find_qd_qs_mism 1040215 1040215 0 0.00 0 28615 5 0 0
srv_make_invnt_saldo 556 556 0 0.00 25 59688 1030 0 0
srv_make_money_saldo 846 846 0 0.00 0 1681 23 0 0
srv_recalc_idx_stat 4 4 0 0.00 166682 548249 267272 0 0
t$perf-abend:sp_add_invoice_to_stock 107 107 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5116 5116 0 0.00 0 11581 157 0 0
t$perf-norm:sp_cancel_adding_invoice 471 471 0 0.00 1 12445 136 0 0
t$perf-norm:sp_cancel_client_order 1798 1798 0 0.00 0 10841 22 0 0
t$perf-norm:sp_cancel_customer_reserve 678 678 0 0.00 0 1002 8 0 0
t$perf-norm:sp_cancel_pay_from_customer 686 686 0 0.00 0 1100 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 443 443 0 0.00 0 588 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 456 456 0 0.00 0 1867 22 0 0
t$perf-norm:sp_cancel_supplier_order 404 404 0 0.00 0 24872 1530 0 0
t$perf-norm:sp_cancel_write_off 1072 1072 0 0.00 0 2889 16 0 0
t$perf-norm:sp_client_order 21580 21580 0 0.00 0 16375 20 0 0
t$perf-norm:sp_customer_reserve 1106 1106 0 0.00 0 3031 11 0 0
t$perf-norm:sp_pay_from_customer 7561 7561 0 0.00 0 10358 12 0 0
t$perf-norm:sp_pay_to_supplier 6215 6215 0 0.00 0 10476 12 0 0
t$perf-norm:sp_reserve_write_off 10784 10784 0 0.00 0 8992 11 0 0
t$perf-norm:sp_supplier_invoice 5725 5725 0 0.00 0 9600 22 0 0
t$perf-norm:sp_supplier_order 5735 5735 0 0.00 0 3994 20 0 0
t$perf-norm:srv_aggregate_perf_data 115 115 0 0.00 0 628 17 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 4 0 0 0
t$perf-norm:srv_make_invnt_saldo 556 556 0 0.00 0 2183 19 0 0
t$perf-norm:srv_make_money_saldo 846 846 0 0.00 0 8983 27 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 9 4 0 0
v_add_invoice_to_stock 10440 10226 214 2.04 0 4614 15 0 214
v_all_customers 39524 39524 0 0.00 0 147 1 0 0
v_all_suppliers 31998 31998 0 0.00 0 67 1 0 0
v_all_wares 43160 43160 0 0.00 0 16 1 0 0
v_cancel_adding_invoice 942 942 0 0.00 0 1191 8 0 0
v_cancel_client_order 3596 3596 0 0.00 0 6891 9 0 0
v_cancel_customer_prepayment 1372 1372 0 0.00 0 1287 7 0 0
v_cancel_customer_reserve 1356 1356 0 0.00 0 5011 9 0 0
v_cancel_payment_to_supplier 886 886 0 0.00 0 737 5 0 0
v_cancel_supplier_invoice 930 912 18 1.93 0 533 15 0 18
v_cancel_supplier_order 808 808 0 0.00 0 1053 5 0 0
v_cancel_write_off 2144 2144 0 0.00 0 773 11 0 0
v_max_id_clo_ord 5735 5735 0 0.00 0 2438 2 0 0
v_max_id_clo_res 1106 1106 0 0.00 0 3487 13 0 0
v_max_id_ord_sup 5710 5710 0 0.00 0 2721 4 0 0
v_max_non_paid_invoice 1661 1661 0 0.00 0 4313 7 0 0
v_max_non_paid_realizn 3945 3945 0 0.00 0 1527 5 0 0
v_min_id_clo_ord 5735 5735 0 0.00 0 4322 8 0 0
v_min_id_clo_res 1106 1106 0 0.00 0 18875 31 0 0
v_min_id_ord_sup 5710 5710 0 0.00 0 7097 5 0 0
v_min_non_paid_invoice 6215 6215 0 0.00 0 9758 16 0 0
v_min_non_paid_realizn 7561 7561 0 0.00 0 7174 19 0 0
v_our_firm 10868 10868 0 0.00 0 18 1 0 0
v_reserve_write_off 21568 21568 0 0.00 0 4824 5 0 0
x_lock_depdocs_on_canc_invoice 10884 10884 0 0.00 0 16978 119 0 0
x_lock_depdocs_on_canc_sup_ord 404 404 0 0.00 0 14967 352 0 0
x_make_qstorno_1000_1200 5735 5735 0 0.00 1 22608 1181 0 0
x_make_qstorno_1200_2000 5725 5725 0 0.00 3 99626 3392 0 0
x_make_qstorno_2100_3300 472083 472083 0 0.00 0 48971 151 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 14-05-2022 16:39:52 to 14-05-2022 16:39:56.

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:08:20 7 8192000000 8803020800 8815742976 8846464 0.107 3 1 2 0 8944736 55488 42784
14:09:22 8218 8192000000 9901981600 10212507648 992167520 10.803 100 91 182 0 1021839024 5603232 24068272
14:10:37 16102 8192000000 9952231904 10272563200 1032724528 11.195 100 88 174 1 1062979856 8088240 22167088
14:11:58 14990 8192000000 9972535264 10301005824 1055901376 11.417 100 90 180 0 1088570336 9803088 22865872
14:13:36 8967 8192000000 10033831072 10381791232 1116762448 11.996 100 76 152 0 1145936048 10933488 18240112
14:14:47 7716 8192000000 10079536800 10440048640 1170705440 12.503 100 79 158 1 1200801504 12556912 17539152
14:15:57 4578 8192000000 10129306560 10499608576 1215359344 12.919 100 79 157 0 1245598912 13097696 17141872
14:17:04 4276 8192000000 10157760480 10532204544 1246051808 13.202 100 87 174 2 1279371136 14561744 18757584
14:18:10 2692 8192000000 10187940112 10568728576 1265605120 13.381 100 80 156 0 1298850176 16197072 17047984
14:19:14 2055 8192000000 10215883216 10598359040 1293860192 13.639 100 92 185 3 1328605088 14119600 20625296
14:20:17 14579 8192000000 10203792048 10599514112 1298412032 13.681 100 76 152 0 1327996144 11993504 17590608
14:21:33 6092 8192000000 10193311120 10606743552 1305815296 13.748 100 35 70 1 1320380496 6610816 7954384
14:22:40 5500 8192000000 10204042352 10614956032 1313612256 13.819 100 33 66 0 1327613760 6668928 7332576
14:23:46 6221 8192000000 10205940192 10624880640 1323082000 13.905 100 17 34 1 1333350896 6149792 4119104
14:24:56 35996 8192000000 10220930592 10644094976 1327773664 13.947 100 33 66 3 1345962176 10356432 7832080
14:26:33 7921 8192000000 10225058816 10641895424 1317062320 13.850 99 30 61 3 1330088080 6190864 6834896
14:27:41 8582 8192000000 10225335104 10641203200 1319156640 13.869 99 23 43 2 1329492960 5211600 5124720
14:28:51 69265 8192000000 10176652064 10591371264 1222296848 12.983 94 21 42 1 1232494064 5440976 4756240
14:31:01 6161 8192000000 10161752256 10561200128 1262866736 13.356 100 44 90 0 1280319040 6831648 10620656
14:32:11 7437 8192000000 10132894080 10527039488 1179383760 12.584 94 25 50 1 1190779424 5492272 5903392
14:33:19 61025 8192000000 10150833552 10557284352 1257551360 13.308 100 26 51 2 1269402512 5475312 6375840
14:35:21 5384 8192000000 10183177024 10591793152 1286722464 13.574 100 27 54 1 1299692112 6750032 6219616
14:36:29 6325 8192000000 10190082112 10601697280 1296578112 13.664 100 30 60 0 1309225328 5445248 7201968
14:37:36 18905 8192000000 10199872384 10615623680 1304422304 13.735 100 21 42 1 1314929712 5567248 4940160
14:38:56 24268 8192000000 10231006336 10639192064 1320750160 13.883 100 47 87 3 1335262752 4247040 10265552
14:40:25 33398 8192000000 10263515792 10671460352 1346015088 14.112 100 56 110 1 1370642288 12637088 11990112
14:42:16 22869 8192000000 10274992992 10697089024 1369216016 14.320 100 55 108 3 1402084752 20801232 12067504
14:43:43 11074 8192000000 10286072608 10708762624 1367187104 14.302 99 37 73 0 1393911488 17443248 9281136
14:44:59 20580 8192000000 10301366112 10729410560 1388143184 14.489 100 45 91 1 1420617504 20979216 11495104
14:46:24 9422 8192000000 10318326320 10742013952 1394601856 14.547 100 57 115 1 1432806064 24348768 13855440
14:47:43 2921 8192000000 10348258064 10764365824 1413306720 14.713 100 70 140 1 1455228560 24454144 17467696
14:48:49 18649 8192000000 10330387280 10759127040 1404429856 14.634 100 44 88 1 1441750960 25969296 11351808
14:50:17 13688 8192000000 10335672608 10762887168 1402953152 14.621 100 51 102 0 1446198384 30146848 13098384
14:51:40 7547 8192000000 10331061104 10747199488 1380906688 14.425 100 69 138 0 1430997504 32722704 17368112
14:52:54 2089 8192000000 10317314720 10715930624 1347459024 14.125 99 89 177 2 1411501216 42125184 21917008
14:54:03 1690 8192000000 10327209376 10728189952 1366387776 14.295 99 88 175 1 1428398880 40236752 21774352
14:55:09 5461 8192000000 10336138240 10739576832 1372928128 14.353 99 81 162 0 1434086656 40520912 20637616
14:56:22 4537 8192000000 10351130960 10769285120 1400338928 14.598 100 63 121 1 1458686848 42460464 15887456
14:57:31 5901 8192000000 10345939984 10766139392 1397799904 14.575 100 47 94 0 1452875792 42317328 12758560
14:58:50 4313 8192000000 10354219120 10771968000 1380907584 14.425 98 53 105 1 1444870464 49805120 14157760
14:59:59 20839 8192000000 10348255088 10785116160 1390200832 14.508 99 45 90 3 1454328144 51131120 12996192
15:01:26 18385 8192000000 10327193984 10760208384 1375277152 14.374 99 31 62 1 1425736368 41548144 8911072
15:03:04 19126 8192000000 10326277136 10766528512 1377563808 14.395 100 27 54 1 1430800032 45030864 8205360
15:04:25 28147 8192000000 10320576688 10764611584 1362983504 14.264 100 25 50 2 1423006000 52738112 7284384
15:05:58 51492 8192000000 10263447120 10712055808 1322475168 13.899 98 12 24 0 1353788816 27882112 3431536
15:07:55 59892 8192000000 10307510480 10748289024 1377476976 14.394 100 23 47 2 1401338784 17996736 5865072
15:09:55 105966 8192000000 10290242560 10742538240 1340239888 14.060 97 32 64 0 1379122064 30814128 8068048
15:12:59 21454 8192000000 10290859456 10732118016 1370337952 14.330 99 52 101 0 1393386720 11515280 11533488
15:14:21 78537 8192000000 10269576848 10718109696 1318033600 13.859 95 26 47 1 1329490496 6527200 4929696
15:16:40 27460 8192000000 10275770384 10738933760 1377086192 14.390 100 6 12 0 1388627024 9980192 1560640
15:18:09 25323 8192000000 10290869600 10749018112 1389424768 14.501 100 24 49 0 1403512176 8184544 5902864
15:19:34 29142 8192000000 10293286368 10745671680 1378849968 14.406 99 38 76 0 1397979280 9958416 9170896
15:21:21 74791 8192000000 10302898768 10765062144 1388187744 14.490 99 14 28 1 1403945888 12757120 3001024
15:23:56 99998 8192000000 10301002976 10765742080 1402026128 14.613 100 25 50 0 1420677472 13207392 5443952
15:26:39 40173 8192000000 10289057744 10751692800 1378354528 14.402 99 9 18 1 1395553520 14768912 2430080
15:28:22 68096 8192000000 10310276496 10771230720 1404400528 14.634 100 15 30 1 1424727792 16817504 3509760
15:30:31 29637 8192000000 10316376592 10771456000 1404982256 14.639 100 16 33 1 1429775280 20662512 4130512
15:32:03 81678 8192000000 10294009776 10784927744 1388993504 14.497 99 15 30 0 1411661648 19101664 3566480
15:34:29 74036 8192000000 10281738640 10744643584 1339359792 14.052 96 11 22 1 1350425024 8594352 2470880
15:36:43 53353 8192000000 10284993568 10741645312 1333437712 13.998 96 30 59 2 1354255616 13501408 7316496
15:38:37 72022 8192000000 10273744864 10732261376 1318777488 13.866 95 21 42 1 1338903360 15276512 4849360
15:40:57 47156 8192000000 10284668848 10725183488 1355642464 14.198 99 41 82 0 1376669888 11912512 9114912
15:42:54 110417 8192000000 10283593808 10750644224 1323856672 13.912 96 15 30 0 1342741184 15453056 3431456
15:45:46 66591 8192000000 10298355520 10735194112 1355863840 14.200 98 27 54 1 1371737136 10380608 5492688
15:47:53 155555 8192000000 10292202480 10737655808 1373937584 14.362 99 29 58 1 1393666896 13420112 6309200
15:51:31 49706 8192000000 10269784832 10735431680 1367094688 14.301 99 19 38 0 1381104144 9728272 4281184
15:53:23 56805 8192000000 10267353632 10719449088 1356072688 14.202 99 15 30 0 1365545808 6348128 3124992
15:55:20 63057 8192000000 10259260784 10714001408 1327823424 13.947 96 9 18 0 1342126880 11473456 2830000
15:57:47 60499 8192000000 10267664192 10734641152 1355059888 14.193 99 8 17 1 1373400560 15886304 2454368
15:59:55 82789 8192000000 10282002704 10723348480 1357839296 14.218 99 16 32 0 1373746848 12254768 3652784
16:02:19 37486 8192000000 10268393792 10711044096 1341131248 14.068 98 24 48 1 1355673408 9291936 5250224
16:03:56 70603 8192000000 10283018064 10747408384 1368647392 14.315 99 28 56 1 1387652192 12607744 6397056
16:06:14 56199 8192000000 10273527904 10714935296 1310379552 13.790 95 19 38 1 1330033504 15244400 4409552
16:08:10 109390 8192000000 10293114384 10737758208 1325092768 13.923 96 14 28 1 1339085408 11135152 2857488
16:11:03 112392 8192000000 10284087424 10740518912 1370262448 14.329 99 22 44 0 1386123728 11249168 4612112
16:13:56 59053 8192000000 10279318624 10732802048 1353261088 14.177 97 10 20 0 1366876912 11005456 2610368
16:16:55 29617 8192000000 10293045824 10741055488 1373950128 14.362 99 37 76 3 1395231184 10966256 10314800
16:18:31 83139 8192000000 10286547104 10739634176 1362777584 14.262 98 20 40 1 1378804816 11460080 4567152
16:20:54 96922 8192000000 10279580304 10734903296 1311065840 13.796 94 24 48 0 1325314096 8850768 5397488
16:23:41 97248 8192000000 10278351504 10735882240 1322334832 13.898 96 19 38 0 1336419536 10048144 4036560
16:26:22 57418 8192000000 10265936368 10709266432 1325605664 13.927 97 15 30 0 1339023408 10249328 3168416
16:28:20 32748 8192000000 10269407648 10713501696 1355227376 14.194 99 14 28 0 1365062176 6393232 3441568
16:29:53 137619 8192000000 10274289296 10727976960 1358593504 14.225 99 14 28 0 1372307248 10696768 3016976
16:33:23 65995 8192000000 10245783152 10702790656 1331447520 13.980 97 15 30 2 1342197296 7328448 3421328
16:35:30 86073 8192000000 10244906272 10698457088 1323169984 13.905 97 6 12 1 1334849424 10311760 1367680
16:38:00 29543 8192000000 9077499488 9314996224 246096704 2.916 18 3 4 1 249299872 2540704 662464

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 14-05-2022 16:39:58 to 14-05-2022 16:40:13.

Exceptions occured during test was in run

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

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

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          248772
MON$OLDEST_ACTIVE               248773
MON$OLDEST_SNAPSHOT             248773
MON$NEXT_TRANSACTION            248773
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                       2016768
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.33587 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33587 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33587 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33587 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 ---
--- end of diff output ---

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

14.05.22 16:40:17. 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-14 16:40:19 No severe PSQL-related problems occured
14.05.22 16:40:21. End of report.