Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 23.12.2022 19:38
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 14858136 kB
MemAvailable 30076632 kB
Buffers 0 kB
Cached 16833292 kB
SwapCached 3236 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.11
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-12-23 19:38:31
DTS_END                         2022-12-23 19:38:31.4180
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/47354
TRN_ID                          250910
ATT_ID                          661
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: 9613.74 20548 1153649
customer order: creation 184.22 324 22106
customer order: refuse 15.52 350 1862
order to supplier: creation 49.72 1187 5966
order to supplier: removal 3.34 90419 401
invoice (draft): creation 48.62 3208 5834
invoice (draft): removal 53.16 717 6379
invoice accept: apply 45.96 17076 5515
invoice accept: cancel 48.38 717 5805
customer reserve: creation 4166.65 173 499998
customer reserve: removal 4681.07 49 561728
realization accept: apply 89.84 177 10781
realization accept: cancel 86.03 99 10324
payment from customer: creation 65.43 101 7852
payment from customer: removal 6.27 79 752
payment to supplier: creation 52.56 133 6307
payment to supplier: removal 3.68 57 442
service: total inventory turnovers 5.14 1238 617
service: total monetary turnovers 7.25 49 870
service: aggregate perf. data 0.88 19910 106
service: refresh index statistics 0.03 274888 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 23-12-2022 19:39:12 to 23-12-2022 19:39:15.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7124
TEST_TIME 2 6354
TEST_TIME 3 5954
TEST_TIME 4 5323
TEST_TIME 5 5575
TEST_TIME 6 5291
TEST_TIME 7 5238
TEST_TIME 8 5383
TEST_TIME 9 5598
TEST_TIME 10 5877
TEST_TIME 11 6257
TEST_TIME 12 6271
TEST_TIME 13 6016
TEST_TIME 14 6305
TEST_TIME 15 6771
TEST_TIME 16 7460
TEST_TIME 17 7984
TEST_TIME 18 8284
TEST_TIME 19 8365
TEST_TIME 20 8701
TEST_TIME 21 8889
TEST_TIME 22 8954
TEST_TIME 23 9085
TEST_TIME 24 9458
TEST_TIME 25 9681
TEST_TIME 26 9860
TEST_TIME 27 10082
TEST_TIME 28 10131
TEST_TIME 29 10709
TEST_TIME 30 10454
TEST_TIME 31 10595
TEST_TIME 32 10658
TEST_TIME 33 10806
TEST_TIME 34 10607
TEST_TIME 35 10614
TEST_TIME 36 10502
TEST_TIME 37 10534
TEST_TIME 38 10556
TEST_TIME 39 10468
TEST_TIME 40 10278
TEST_TIME 41 10165
TEST_TIME 42 10234
TEST_TIME 43 10080
TEST_TIME 44 10024
TEST_TIME 45 10016
TEST_TIME 46 9884
TEST_TIME 47 9815
TEST_TIME 48 9841
TEST_TIME 49 9896
TEST_TIME 50 9957
TEST_TIME 51 9949
TEST_TIME 52 9873
TEST_TIME 53 9775
TEST_TIME 54 9698
TEST_TIME 55 9650
TEST_TIME 56 9572
TEST_TIME 57 9544
TEST_TIME 58 9454
TEST_TIME 59 9631
TEST_TIME 60 9634
TEST_TIME 61 9619
TEST_TIME 62 9751
TEST_TIME 63 9750
TEST_TIME 64 9745
TEST_TIME 65 9927
TEST_TIME 66 9888
TEST_TIME 67 9918
TEST_TIME 68 9982
TEST_TIME 69 10029
TEST_TIME 70 9971
TEST_TIME 71 10013
TEST_TIME 72 9995
TEST_TIME 73 10089
TEST_TIME 74 9970
TEST_TIME 75 9917
TEST_TIME 76 9883
TEST_TIME 77 9899
TEST_TIME 78 9832
TEST_TIME 79 9798
TEST_TIME 80 9761
TEST_TIME 81 9723
TEST_TIME 82 9663
TEST_TIME 83 9608
TEST_TIME 84 9611
TEST_TIME 85 9577
TEST_TIME 86 9552
TEST_TIME 87 9552
TEST_TIME 88 9593
TEST_TIME 89 9631
TEST_TIME 90 9620
TEST_TIME 91 9559
TEST_TIME 92 9714
TEST_TIME 93 9721
TEST_TIME 94 9702
TEST_TIME 95 9787
TEST_TIME 96 9761
TEST_TIME 97 9853
TEST_TIME 98 9888
TEST_TIME 99 9872
TEST_TIME 100 9839
TEST_TIME 101 9772
TEST_TIME 102 9749
TEST_TIME 103 9740
TEST_TIME 104 9717
TEST_TIME 105 9676
TEST_TIME 106 9663
TEST_TIME 107 9613
TEST_TIME 108 9556
TEST_TIME 109 9583
TEST_TIME 110 9579
TEST_TIME 111 9591
TEST_TIME 112 9607
TEST_TIME 113 9579
TEST_TIME 114 9612
TEST_TIME 115 9568
TEST_TIME 116 9650
TEST_TIME 117 9656
TEST_TIME 118 9630
TEST_TIME 119 9664
TEST_TIME 120 9617

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 23-12-2022 19:39:16 to 23-12-2022 19:39:19.

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 1614423 1614423 0 0.00 0 15871 6 0 0
doc_list_biud 1614422 1614422 0 0.00 0 41524 23 0 0
sp_add_invoice_to_stock 5724 5515 209 3.65 19 145284 17076 0 209
sp_cancel_adding_invoice 5805 5805 0 0.00 4 37892 717 0 0
sp_cancel_client_order 1862 1862 0 0.00 4 10777 350 0 0
sp_cancel_customer_reserve 561728 561728 0 0.00 0 42131 50 0 0
sp_cancel_pay_from_customer 752 752 0 0.00 2 5232 80 0 0
sp_cancel_pay_to_supplier 442 442 0 0.00 3 1957 57 0 0
sp_cancel_supplier_invoice 6396 6379 17 0.26 1 12598 717 0 17
sp_cancel_supplier_order 401 401 0 0.00 5 997238 90420 0 0
sp_cancel_write_off 10324 10324 0 0.00 1 11586 99 0 0
sp_client_order 22106 22106 0 0.00 3 41705 325 0 0
sp_customer_reserve 499998 499998 0 0.00 0 80264 173 0 0
sp_fill_shopping_cart 33901 33901 0 0.00 1 14989 39 0 0
sp_fill_shopping_cart_clo_res 499994 499994 0 0.00 0 21476 12 0 0
sp_get_clo_for_invoice 5511 5511 0 0.00 0 14547 267 0 0
sp_kill_cost_storno 17323 17323 0 0.00 0 8741 48 0 0
sp_kill_qstorno_ret_qs2qd 1140560 1140560 0 0.00 0 41520 27 0 0
sp_kill_qty_storno 602791 602791 0 0.00 0 41524 60 0 0
sp_lock_selected_doc 22421 22421 0 0.00 0 7523 5 0 0
sp_make_cost_storno 30451 30451 0 0.00 0 13220 49 0 0
sp_multiply_rows_for_pdistr 30451 30451 0 0.00 0 10882 26 0 0
sp_multiply_rows_for_qdistr 22106 22106 0 0.00 0 25468 147 0 0
sp_pay_from_customer 7852 7852 0 0.00 4 11125 102 0 0
sp_pay_to_supplier 6307 6307 0 0.00 4 12223 133 0 0
sp_payment_common 14159 14159 0 0.00 1 7519 28 0 0
sp_qd_handle_on_cancel_clo 1862 1862 0 0.00 0 10770 322 0 0
sp_qd_handle_on_invoice_upd_sts 11316 11316 0 0.00 0 20719 325 0 0
sp_qd_handle_on_reserve_upd_sts 10781 10781 0 0.00 0 18623 56 0 0
sp_reserve_write_off 10781 10781 0 0.00 6 25896 177 0 0
sp_supplier_invoice 5834 5834 0 0.00 5 77838 3208 0 0
sp_supplier_order 5966 5966 0 0.00 5 19478 1188 0 0
srv_aggregate_perf_data 107 106 1 0.93 2327 547703 19910 0 1
srv_fill_mon_cache_memory 64 64 0 0.00 1668 156316 45744 0 0
srv_find_qd_qs_mism 1100935 1100935 0 0.00 0 15850 6 0 0
srv_make_invnt_saldo 617 617 0 0.00 27 33900 1239 0 0
srv_make_money_saldo 870 870 0 0.00 1 16742 49 0 0
srv_recalc_idx_stat 4 4 0 0.00 180787 375430 274889 0 0
t$perf-abend:sp_add_invoice_to_stock 209 209 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 17 17 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5516 5516 0 0.00 0 15829 169 0 0
t$perf-norm:sp_cancel_adding_invoice 427 427 0 0.00 0 10776 95 0 0
t$perf-norm:sp_cancel_client_order 1862 1862 0 0.00 0 5975 18 0 0
t$perf-norm:sp_cancel_customer_reserve 729 729 0 0.00 0 522 8 0 0
t$perf-norm:sp_cancel_pay_from_customer 752 752 0 0.00 0 677 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 442 442 0 0.00 0 482 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 388 388 0 0.00 0 2100 13 0 0
t$perf-norm:sp_cancel_supplier_order 401 401 0 0.00 0 25611 1556 0 0
t$perf-norm:sp_cancel_write_off 1128 1128 0 0.00 0 5189 13 0 0
t$perf-norm:sp_client_order 22106 22106 0 0.00 0 9562 15 0 0
t$perf-norm:sp_customer_reserve 1070 1070 0 0.00 0 13997 40 0 0
t$perf-norm:sp_pay_from_customer 7852 7852 0 0.00 0 5585 8 0 0
t$perf-norm:sp_pay_to_supplier 6307 6307 0 0.00 0 5426 11 0 0
t$perf-norm:sp_reserve_write_off 10781 10781 0 0.00 0 6794 9 0 0
t$perf-norm:sp_supplier_invoice 5834 5834 0 0.00 0 7163 16 0 0
t$perf-norm:sp_supplier_order 5966 5966 0 0.00 0 6191 17 0 0
t$perf-norm:srv_aggregate_perf_data 106 106 0 0.00 0 957 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 7 1 0 0
t$perf-norm:srv_make_invnt_saldo 617 617 0 0.00 0 1256 22 0 0
t$perf-norm:srv_make_money_saldo 870 870 0 0.00 0 1472 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 10 4 0 0
v_add_invoice_to_stock 11440 11022 418 3.65 0 7185 19 0 418
v_all_customers 40238 40238 0 0.00 0 206 1 0 0
v_all_suppliers 32300 32300 0 0.00 0 24 1 0 0
v_all_wares 44212 44212 0 0.00 0 47 1 0 0
v_cancel_adding_invoice 854 854 0 0.00 0 591 6 0 0
v_cancel_client_order 3724 3724 0 0.00 0 8159 7 0 0
v_cancel_customer_prepayment 1504 1504 0 0.00 0 4643 11 0 0
v_cancel_customer_reserve 1458 1458 0 0.00 0 3467 7 0 0
v_cancel_payment_to_supplier 884 884 0 0.00 0 1918 7 0 0
v_cancel_supplier_invoice 810 776 34 4.19 0 1324 15 0 34
v_cancel_supplier_order 802 802 0 0.00 0 678 5 0 0
v_cancel_write_off 2256 2256 0 0.00 0 11433 33 0 0
v_max_id_clo_ord 5963 5963 0 0.00 0 1213 2 0 0
v_max_id_clo_res 1070 1070 0 0.00 0 1063 7 0 0
v_max_id_ord_sup 5831 5831 0 0.00 0 3314 2 0 0
v_max_non_paid_invoice 1951 1951 0 0.00 0 229 2 0 0
v_max_non_paid_realizn 4242 4242 0 0.00 0 2639 6 0 0
v_min_id_clo_ord 5963 5963 0 0.00 0 14528 20 0 0
v_min_id_clo_res 1070 1070 0 0.00 0 2720 5 0 0
v_min_id_ord_sup 5831 5831 0 0.00 0 12142 11 0 0
v_min_non_paid_invoice 6307 6307 0 0.00 0 7507 22 0 0
v_min_non_paid_realizn 7852 7852 0 0.00 0 7502 18 0 0
v_our_firm 11194 11194 0 0.00 0 23 1 0 0
v_reserve_write_off 21562 21562 0 0.00 0 12141 9 0 0
x_lock_depdocs_on_canc_invoice 11796 11796 0 0.00 0 16196 107 0 0
x_lock_depdocs_on_canc_sup_ord 401 401 0 0.00 0 13141 328 0 0
x_make_qstorno_1000_1200 5966 5966 0 0.00 1 19411 1096 0 0
x_make_qstorno_1200_2000 5834 5834 0 0.00 2 77830 3078 0 0
x_make_qstorno_2100_3300 499998 499998 0 0.00 0 80260 159 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 23-12-2022 19:39:21 to 23-12-2022 19:39:25.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
17:08:35 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:36 20944 8192000000 9904312320 10213105664 986763824 10.750 100 94 188 0 1017325504 5996240 24565440
17:11:03 14395 8192000000 9943486912 10262429696 1024554080 11.116 100 88 176 0 1055599824 7895744 23150000
17:12:28 15049 8192000000 9978999968 10309693440 1061169136 11.468 100 84 169 0 1092112864 10122064 20821664
17:13:47 12782 8192000000 10033119632 10378276864 1119676112 12.024 100 82 164 0 1151128352 11802288 19649952
17:15:05 7835 8192000000 10081160400 10434727936 1161565536 12.418 100 83 162 0 1193304032 13068528 18669968
17:16:19 4232 8192000000 10111051616 10481049600 1207834992 12.849 100 73 145 0 1235968560 11408672 16724896
17:17:24 2369 8192000000 10152595424 10523803648 1234943104 13.100 100 79 158 0 1265951792 14083968 16924720
17:18:28 2153 8192000000 10166894864 10546167808 1260754688 13.337 100 72 144 0 1288620256 12132800 15732768
17:19:32 1196 8192000000 10204993872 10587054080 1288020416 13.586 100 87 177 2 1320799008 13781824 18996768
17:20:34 27536 8192000000 10196466976 10597306368 1300834528 13.703 100 54 104 0 1324986800 13027040 11125232
17:22:03 5653 8192000000 10208975680 10610372608 1307282400 13.761 100 34 69 1 1322262688 6995184 7985104
17:23:11 7636 8192000000 10203166144 10621132800 1322656432 13.901 100 17 34 0 1332987840 6515136 3816272
17:24:20 8043 8192000000 10225385536 10638704640 1330583648 13.972 100 20 36 0 1341991808 6119472 5288688
17:25:28 75495 8192000000 10242142832 10655363072 1348184784 14.131 100 46 91 0 1363687936 4828560 10674592
17:27:44 5040 8192000000 10239932336 10656550912 1341495728 14.071 100 34 68 0 1356452528 7585888 7370912
17:28:51 9746 8192000000 10218174352 10634657792 1316327696 13.843 99 19 38 1 1326518656 5892416 4298544
17:30:03 44444 8192000000 10173450032 10564255744 1257417232 13.306 97 57 113 0 1275610976 4884736 13309008
17:31:48 6003 8192000000 10151787936 10549575680 1230193136 13.056 97 30 60 0 1244031376 6558688 7279552
17:32:56 8117 8192000000 10136858032 10536337408 1218734816 12.950 97 25 50 0 1230397840 6194864 5468160
17:34:06 64466 8192000000 10153561168 10566107136 1258770864 13.319 99 40 80 1 1273739888 5205568 9763456
17:36:12 10755 8192000000 10181827184 10590404608 1285374720 13.562 100 21 42 1 1295611664 5513984 4722960
17:37:30 12257 8192000000 10193881680 10604171264 1300195888 13.697 100 14 29 0 1308940512 5108848 3635776
17:38:46 5935 8192000000 10221012416 10629926912 1321941040 13.894 100 37 71 0 1335047872 4916464 8190368
17:39:55 53677 8192000000 10246433792 10660446208 1335545056 14.017 100 40 79 1 1349493744 5230688 8718000
17:42:00 2043 8192000000 10273650192 10685714432 1354466480 14.188 100 63 123 0 1381861056 14256048 13138528
17:43:06 11264 8192000000 10287400576 10703659008 1372120560 14.346 100 58 116 1 1402363168 17160432 13082176
17:44:32 12294 8192000000 10290882560 10709311488 1368494992 14.314 100 39 79 0 1397213968 19483808 9235168
17:45:50 13310 8192000000 10304273168 10721857536 1380930560 14.425 100 55 109 0 1421996160 28514288 12551312
17:47:08 6605 8192000000 10314810336 10738659328 1379377424 14.411 99 53 106 0 1421936560 29620400 12938736
17:48:19 4504 8192000000 10330317984 10748485632 1384445952 14.456 99 63 122 0 1430761696 31984048 14331696
17:49:30 5054 8192000000 10348147376 10763530240 1397361008 14.571 99 84 165 0 1450256048 33272368 19622672
17:50:40 24028 8192000000 10320166304 10749554688 1373929968 14.362 98 35 68 1 1416804368 34678976 8195424
17:52:26 3553 8192000000 10345954480 10766331904 1401151744 14.605 100 61 120 0 1456017072 40473440 14391888
17:53:37 8823 8192000000 10330661328 10747002880 1379017104 14.408 100 73 145 1 1444849104 47348928 18483072
17:54:54 3057 8192000000 10324159040 10739380224 1375448640 14.376 100 61 120 1 1428244768 37974176 14821952
17:56:02 5297 8192000000 10303189504 10719633408 1361134336 14.248 100 67 131 3 1410313008 33306576 15872096
17:57:10 6908 8192000000 10304302064 10713796608 1359167136 14.230 100 48 92 1 1404401632 34236944 10997552
17:58:19 5638 8192000000 10287951552 10708971520 1350664288 14.153 99 38 76 1 1392932224 31796592 10471344
17:59:29 14434 8192000000 10275660816 10696798208 1315117456 13.832 97 32 64 0 1357277072 32658592 9501024
18:00:53 1668 8192000000 10319624992 10717884416 1360255840 14.240 100 85 170 2 1415576160 34140960 21179360
18:02:00 6531 8192000000 10307888480 10726449152 1352578416 14.171 99 39 75 0 1402604944 39359984 10666544
18:03:08 5985 8192000000 10303211184 10719059968 1341373968 14.070 99 52 103 0 1395335984 40272960 13689056
18:04:18 37707 8192000000 10289645872 10714943488 1349762272 14.145 99 36 72 0 1392641328 33942912 8936144
18:06:16 15038 8192000000 10276635920 10702024704 1335099792 14.013 99 31 60 0 1372098864 29222352 7776720
18:07:33 67175 8192000000 10262987536 10685222912 1320053760 13.877 98 17 35 1 1343969904 19620688 4295456
18:09:48 70621 8192000000 10276817120 10716319744 1345368240 14.106 99 12 24 0 1368721680 20376784 2976656
18:12:19 47541 8192000000 10286043744 10719703040 1334514512 14.008 97 46 86 0 1355547840 11739424 9293904
18:14:07 50118 8192000000 10302864880 10744168448 1380913840 14.425 100 20 40 0 1397278960 11839664 4525456
18:16:01 136862 8192000000 10306171040 10758500352 1362988416 14.264 98 5 10 0 1378942880 15093232 861232
18:19:18 38567 8192000000 10311122016 10762780672 1398400256 14.581 100 44 85 0 1420330992 12364848 9565888
18:21:02 105466 8192000000 10298633488 10730725376 1354675392 14.190 97 43 86 0 1372140272 8298080 9166800
18:23:50 33926 8192000000 10328931632 10774331392 1397606208 14.574 100 27 49 3 1416239200 12366288 6266704
18:25:29 49661 8192000000 10329925360 10794647552 1405891216 14.647 99 25 50 0 1433755632 21572672 6291744
18:27:19 8818 8192000000 10321663424 10795229184 1413513712 14.715 100 12 24 0 1433769696 17223728 3032256
18:28:28 29513 8192000000 10308361904 10784747520 1407648192 14.663 100 8 16 0 1418668656 9201072 1819392
18:30:03 25049 8192000000 10295160128 10761248768 1376175104 14.382 98 8 16 0 1388494416 10552208 1767104
18:31:31 72033 8192000000 10290771328 10755420160 1374135088 14.364 98 15 29 2 1383844720 6001584 3708048
18:33:43 19295 8192000000 10287327888 10749165568 1387186528 14.481 100 17 34 0 1403894224 12608928 4098768
18:35:04 57807 8192000000 10276641600 10741981184 1361066720 14.247 98 14 29 0 1377684944 13203488 3414736
18:37:03 61115 8192000000 10262992352 10734743552 1296704944 13.665 94 8 16 0 1315606672 16995696 1906032
18:39:04 31784 8192000000 10282122064 10734608384 1341461104 14.071 97 27 54 0 1360433664 13463456 5509104
18:40:40 40295 8192000000 10265069888 10719027200 1326348336 13.934 97 24 49 0 1342681568 11213248 5119984
18:42:23 73281 8192000000 10275529216 10733207552 1321237088 13.888 96 24 48 1 1346872944 20107632 5528224
18:44:42 39951 8192000000 10288849872 10722672640 1354861648 14.191 99 51 101 2 1376205952 9685440 11658864
18:46:28 109535 8192000000 10291527376 10732269568 1332435952 13.989 97 14 28 0 1346397456 11359904 2601600
18:49:20 107049 8192000000 10266998960 10719408128 1256289760 13.296 91 18 36 0 1280436032 19674544 4471728
18:52:12 73814 8192000000 10287404304 10715623424 1324742816 13.920 97 33 65 0 1340678320 8811744 7123760
18:54:27 29092 8192000000 10266161648 10717306880 1360057344 14.238 99 38 76 1 1381007216 13307600 7642272
18:56:05 74224 8192000000 10270565168 10735898624 1374285472 14.365 100 8 16 0 1385791200 9586912 1918816
18:58:20 51985 8192000000 10288646560 10752262144 1383222288 14.445 100 8 16 0 1398816864 14105504 1489072
19:00:16 46245 8192000000 10249407264 10713464832 1317720160 13.856 96 12 24 1 1327790720 7279840 2790720
19:02:03 63053 8192000000 10261141280 10726625280 1345368736 14.106 98 9 18 0 1356670144 9481488 1819920
19:04:06 21307 8192000000 10272161696 10720907264 1364770832 14.280 100 35 70 0 1379261584 6646848 7843904
19:05:28 71073 8192000000 10286536592 10755207168 1387684816 14.485 100 13 26 0 1402327696 11593120 3049760
19:07:42 120098 8192000000 10317561792 10770456576 1372385936 14.348 98 15 30 0 1390575904 14728064 3461904
19:10:42 63194 8192000000 10303885536 10762739712 1372864944 14.353 98 7 14 0 1386032368 11906864 1260560
19:12:55 76036 8192000000 10316358720 10781954048 1390998096 14.515 99 12 24 0 1410738544 17411568 2328880
19:15:31 156316 8192000000 10294473760 10748985344 1279422288 13.508 91 15 30 0 1297555920 14975472 3158160
19:19:08 101549 8192000000 10288486720 10738479104 1305379168 13.744 94 23 46 0 1316790784 6509200 4902416
19:22:58 84844 8192000000 10261713296 10730160128 1330864368 13.975 97 8 16 0 1348839248 16148544 1826336
19:25:36 102032 8192000000 10278447008 10739613696 1350199456 14.149 98 17 35 0 1367415744 12882400 4333888
19:28:25 47788 8192000000 10271375472 10727391232 1337183520 14.032 97 15 29 1 1350846512 10580736 3082256
19:30:33 45678 8192000000 10276783088 10726002688 1328683760 13.955 97 20 40 0 1342545920 9123456 4738704
19:32:24 48375 8192000000 10255848640 10705104896 1323974944 13.913 98 10 22 0 1339256880 12531584 2750352
19:34:13 68992 8192000000 10266182960 10724229120 1320303872 13.879 97 23 46 1 1345084976 18682080 6099024
19:36:56 53060 8192000000 10262452096 10709983232 1329667760 13.964 98 26 51 0 1348509680 13352288 5489632

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 23-12-2022 19:39:27 to 23-12-2022 19:39:42.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 209 v_add_invoice_to_stock 335544517
except 17 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 23-12-2022 19:39:43 to 23-12-2022 19:39:45.

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          250123
MON$OLDEST_ACTIVE               251104
MON$OLDEST_SNAPSHOT             251104
MON$NEXT_TRANSACTION            251104
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                       2049056
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33648 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33648 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33648 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33648 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 ---
:1129: 
:1130: mscs-test-161.budzdorov.ru    Fri Dec 23 19:38:36 2022
:1131:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1132: 
--- end of diff output ---

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

23.12.22 19:39:46. 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-12-23 19:39:49 No severe PSQL-related problems occured
23.12.22 19:39:50. End of report.