Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 01.03.2023 01: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 15354300 kB
MemAvailable 30467772 kB
Buffers 0 kB
Cached 16380020 kB
SwapCached 3080 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 2023-03-01 01:38:39
DTS_END                         2023-03-01 01:38:39.7730
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(127:13) ==> X_MAKE_QSTORNO_2100_3300(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/35192
TRN_ID                          239451
ATT_ID                          659
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: 9408.44 22180 1129013
customer order: creation 166.37 396 19964
customer order: refuse 13.87 309 1664
order to supplier: creation 43.85 1257 5262
order to supplier: removal 3.52 100725 422
invoice (draft): creation 45.30 3291 5436
invoice (draft): removal 55.08 737 6609
invoice accept: apply 40.14 17012 4817
invoice accept: cancel 49.76 766 5971
customer reserve: creation 3756.96 166 450835
customer reserve: removal 4938.51 56 592621
realization accept: apply 82.98 186 9957
realization accept: cancel 85.18 107 10221
payment from customer: creation 58.80 123 7056
payment from customer: removal 5.54 86 665
payment to supplier: creation 47.11 163 5653
payment to supplier: removal 3.69 53 443
service: total inventory turnovers 4.66 1026 559
service: total monetary turnovers 6.35 40 762
service: aggregate perf. data 0.77 21932 92
service: refresh index statistics 0.03 295161 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 01-03-2023 01:40:51 to 01-03-2023 01:40:53.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3485
TEST_TIME 2 3762
TEST_TIME 3 3919
TEST_TIME 4 4300
TEST_TIME 5 4099
TEST_TIME 6 4816
TEST_TIME 7 4548
TEST_TIME 8 4740
TEST_TIME 9 4909
TEST_TIME 10 5177
TEST_TIME 11 5274
TEST_TIME 12 5283
TEST_TIME 13 5388
TEST_TIME 14 5484
TEST_TIME 15 6041
TEST_TIME 16 6145
TEST_TIME 17 6666
TEST_TIME 18 6649
TEST_TIME 19 7191
TEST_TIME 20 7840
TEST_TIME 21 7713
TEST_TIME 22 8163
TEST_TIME 23 8405
TEST_TIME 24 8682
TEST_TIME 25 9107
TEST_TIME 26 9080
TEST_TIME 27 10476
TEST_TIME 28 10397
TEST_TIME 29 10536
TEST_TIME 30 10320
TEST_TIME 31 10285
TEST_TIME 32 10207
TEST_TIME 33 10057
TEST_TIME 34 9922
TEST_TIME 35 9900
TEST_TIME 36 9786
TEST_TIME 37 9822
TEST_TIME 38 9917
TEST_TIME 39 9893
TEST_TIME 40 9834
TEST_TIME 41 9867
TEST_TIME 42 9714
TEST_TIME 43 9697
TEST_TIME 44 9728
TEST_TIME 45 9690
TEST_TIME 46 9544
TEST_TIME 47 9564
TEST_TIME 48 9579
TEST_TIME 49 9551
TEST_TIME 50 9532
TEST_TIME 51 9550
TEST_TIME 52 9564
TEST_TIME 53 9688
TEST_TIME 54 9812
TEST_TIME 55 9800
TEST_TIME 56 9718
TEST_TIME 57 9698
TEST_TIME 58 9872
TEST_TIME 59 10026
TEST_TIME 60 10082
TEST_TIME 61 10149
TEST_TIME 62 10015
TEST_TIME 63 9942
TEST_TIME 64 9852
TEST_TIME 65 9825
TEST_TIME 66 9816
TEST_TIME 67 9742
TEST_TIME 68 9706
TEST_TIME 69 9720
TEST_TIME 70 9658
TEST_TIME 71 9613
TEST_TIME 72 9635
TEST_TIME 73 9581
TEST_TIME 74 9523
TEST_TIME 75 9452
TEST_TIME 76 9462
TEST_TIME 77 9424
TEST_TIME 78 9431
TEST_TIME 79 9398
TEST_TIME 80 9398
TEST_TIME 81 9478
TEST_TIME 82 9520
TEST_TIME 83 9498
TEST_TIME 84 9635
TEST_TIME 85 9585
TEST_TIME 86 9657
TEST_TIME 87 9632
TEST_TIME 88 9619
TEST_TIME 89 9636
TEST_TIME 90 9540
TEST_TIME 91 9481
TEST_TIME 92 9469
TEST_TIME 93 9413
TEST_TIME 94 9423
TEST_TIME 95 9434
TEST_TIME 96 9429
TEST_TIME 97 9444
TEST_TIME 98 9466
TEST_TIME 99 9449
TEST_TIME 100 9462
TEST_TIME 101 9477
TEST_TIME 102 9544
TEST_TIME 103 9537
TEST_TIME 104 9669
TEST_TIME 105 9658
TEST_TIME 106 9701
TEST_TIME 107 9662
TEST_TIME 108 9708
TEST_TIME 109 9628
TEST_TIME 110 9600
TEST_TIME 111 9571
TEST_TIME 112 9564
TEST_TIME 113 9549
TEST_TIME 114 9517
TEST_TIME 115 9556
TEST_TIME 116 9534
TEST_TIME 117 9512
TEST_TIME 118 9491
TEST_TIME 119 9444
TEST_TIME 120 9411

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 01-03-2023 01:40:55 to 01-03-2023 01:40:58.

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 1547591 1547591 0 0.00 0 34680 6 0 0
doc_list_biud 1547589 1547589 0 0.00 0 41696 27 0 0
sp_add_invoice_to_stock 4868 4817 51 1.04 33 214123 17013 0 51
sp_cancel_adding_invoice 5971 5971 0 0.00 5 78840 767 0 0
sp_cancel_client_order 1664 1664 0 0.00 3 11622 310 0 0
sp_cancel_customer_reserve 592621 592621 0 0.00 0 41697 57 0 0
sp_cancel_pay_from_customer 665 665 0 0.00 2 9382 87 0 0
sp_cancel_pay_to_supplier 443 443 0 0.00 3 1701 53 0 0
sp_cancel_supplier_invoice 6619 6609 10 0.15 1 15826 738 0 10
sp_cancel_supplier_order 422 422 0 0.00 11 1003464 100726 0 0
sp_cancel_write_off 10221 10221 0 0.00 1 15111 107 0 0
sp_client_order 19964 19964 0 0.00 3 20339 396 0 0
sp_customer_reserve 450835 450835 0 0.00 0 178933 167 0 0
sp_fill_shopping_cart 30655 30655 0 0.00 1 10584 40 0 0
sp_fill_shopping_cart_clo_res 450829 450829 0 0.00 0 16625 14 0 0
sp_get_clo_for_invoice 4811 4811 0 0.00 0 18279 327 0 0
sp_kill_cost_storno 17300 17300 0 0.00 0 18327 52 0 0
sp_kill_qstorno_ret_qs2qd 1202494 1202494 0 0.00 0 41694 31 0 0
sp_kill_qty_storno 632276 632276 0 0.00 0 41696 66 0 0
sp_lock_selected_doc 20358 20358 0 0.00 0 8403 6 0 0
sp_make_cost_storno 27477 27477 0 0.00 0 34663 58 0 0
sp_multiply_rows_for_pdistr 27477 27477 0 0.00 0 18673 33 0 0
sp_multiply_rows_for_qdistr 19964 19964 0 0.00 0 14575 182 0 0
sp_pay_from_customer 7056 7056 0 0.00 4 11258 124 0 0
sp_pay_to_supplier 5653 5653 0 0.00 5 35425 163 0 0
sp_payment_common 12709 12709 0 0.00 1 16820 36 0 0
sp_qd_handle_on_cancel_clo 1664 1664 0 0.00 0 11609 281 0 0
sp_qd_handle_on_invoice_upd_sts 10782 10782 0 0.00 0 23486 316 0 0
sp_qd_handle_on_reserve_upd_sts 9957 9957 0 0.00 0 18514 60 0 0
sp_reserve_write_off 9957 9957 0 0.00 6 18738 186 0 0
sp_supplier_invoice 5436 5436 0 0.00 6 93590 3292 0 0
sp_supplier_order 5262 5262 0 0.00 5 37641 1257 0 0
srv_aggregate_perf_data 93 92 1 1.07 2419 311794 21933 0 1
srv_fill_mon_cache_memory 62 62 0 0.00 1030 153786 47432 0 0
srv_find_qd_qs_mism 1083031 1083031 0 0.00 0 17138 5 0 0
srv_make_invnt_saldo 559 559 0 0.00 38 29626 1026 0 0
srv_make_money_saldo 762 762 0 0.00 0 12974 41 0 0
srv_recalc_idx_stat 4 4 0 0.00 141895 425552 295161 0 0
t$perf-abend:sp_add_invoice_to_stock 51 51 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4817 4817 0 0.00 0 18603 183 0 0
t$perf-norm:sp_cancel_adding_invoice 387 387 0 0.00 0 12403 102 0 0
t$perf-norm:sp_cancel_client_order 1664 1664 0 0.00 0 9623 26 0 0
t$perf-norm:sp_cancel_customer_reserve 691 691 0 0.00 0 3692 33 0 0
t$perf-norm:sp_cancel_pay_from_customer 665 665 0 0.00 0 2470 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 443 443 0 0.00 0 1041 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 362 362 0 0.00 0 815 10 0 0
t$perf-norm:sp_cancel_supplier_order 422 422 0 0.00 0 30118 1926 0 0
t$perf-norm:sp_cancel_write_off 956 956 0 0.00 0 3737 16 0 0
t$perf-norm:sp_client_order 19964 19964 0 0.00 0 18601 22 0 0
t$perf-norm:sp_customer_reserve 952 952 0 0.00 0 1293 5 0 0
t$perf-norm:sp_pay_from_customer 7056 7056 0 0.00 0 13282 13 0 0
t$perf-norm:sp_pay_to_supplier 5653 5653 0 0.00 0 6182 13 0 0
t$perf-norm:sp_reserve_write_off 9957 9957 0 0.00 0 20585 16 0 0
t$perf-norm:sp_supplier_invoice 5436 5436 0 0.00 0 21491 28 0 0
t$perf-norm:sp_supplier_order 5262 5262 0 0.00 0 9650 19 0 0
t$perf-norm:srv_aggregate_perf_data 92 92 0 0.00 0 531 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 864 15 0 0
t$perf-norm:srv_make_invnt_saldo 559 559 0 0.00 0 1578 17 0 0
t$perf-norm:srv_make_money_saldo 762 762 0 0.00 0 707 10 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 2 2 0 0
v_add_invoice_to_stock 9724 9622 102 1.04 0 10664 18 0 102
v_all_customers 36742 36742 0 0.00 0 99 0 0 0
v_all_suppliers 29442 29442 0 0.00 0 100 1 0 0
v_all_wares 39928 39928 0 0.00 0 23 1 0 0
v_cancel_adding_invoice 774 774 0 0.00 0 533 7 0 0
v_cancel_client_order 3328 3328 0 0.00 0 5373 9 0 0
v_cancel_customer_prepayment 1330 1330 0 0.00 0 854 6 0 0
v_cancel_customer_reserve 1382 1382 0 0.00 0 3252 10 0 0
v_cancel_payment_to_supplier 886 886 0 0.00 0 1690 5 0 0
v_cancel_supplier_invoice 744 724 20 2.68 0 10332 31 0 20
v_cancel_supplier_order 844 844 0 0.00 0 1972 7 0 0
v_cancel_write_off 1912 1912 0 0.00 0 1810 15 0 0
v_max_id_clo_ord 5262 5262 0 0.00 0 295 1 0 0
v_max_id_clo_res 952 952 0 0.00 0 3824 13 0 0
v_max_id_ord_sup 5428 5428 0 0.00 0 5170 6 0 0
v_max_non_paid_invoice 1622 1622 0 0.00 0 271 2 0 0
v_max_non_paid_realizn 3664 3664 0 0.00 0 5873 7 0 0
v_min_id_clo_ord 5262 5262 0 0.00 0 8525 13 0 0
v_min_id_clo_res 952 952 0 0.00 0 2887 12 0 0
v_min_id_ord_sup 5428 5428 0 0.00 0 6737 9 0 0
v_min_non_paid_invoice 5653 5653 0 0.00 0 16815 25 0 0
v_min_non_paid_realizn 7056 7056 0 0.00 0 10068 28 0 0
v_our_firm 9970 9970 0 0.00 0 99 1 0 0
v_reserve_write_off 19914 19914 0 0.00 0 12198 8 0 0
x_lock_depdocs_on_canc_invoice 12218 12218 0 0.00 0 16668 115 0 0
x_lock_depdocs_on_canc_sup_ord 422 422 0 0.00 0 9013 352 0 0
x_make_qstorno_1000_1200 5262 5262 0 0.00 1 37624 1179 0 0
x_make_qstorno_1200_2000 5436 5436 0 0.00 2 93232 3149 0 0
x_make_qstorno_2100_3300 450835 450835 0 0.00 0 178931 150 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 01-03-2023 01:40:59 to 01-03-2023 01:41:03.

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
23:08:43 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:44 31099 8192000000 9882243456 10188886016 966380256 10.551 100 83 166 0 994052896 5658016 22014624
23:11:21 28871 8192000000 9951556528 10271887360 1030605568 11.174 100 93 186 0 1063049184 8726016 23717600
23:12:54 17118 8192000000 9976947184 10312425472 1067260544 11.526 100 78 156 0 1097038640 10131328 19646768
23:14:14 10388 8192000000 10024147120 10368020480 1116649824 11.995 100 77 155 0 1146307984 11310816 18347344
23:15:29 4993 8192000000 10071746736 10429206528 1159370800 12.397 100 79 154 0 1188735408 11231952 18132656
23:16:37 3770 8192000000 10129141712 10499776512 1220164128 12.963 100 72 144 1 1248934016 13004944 15764944
23:17:43 1770 8192000000 10153503264 10532786176 1248691664 13.226 100 77 152 1 1278764880 13021104 17052112
23:18:52 1150 8192000000 10195928224 10577764352 1281566512 13.527 100 88 177 0 1314927360 14427184 18933664
23:19:55 22925 8192000000 10196904704 10588065792 1295733616 13.656 100 72 143 1 1322100704 9766560 16600528
23:21:20 2450 8192000000 10191659168 10597269504 1301979072 13.713 100 38 77 0 1317629312 7256400 8393840
23:22:23 6041 8192000000 10200731952 10609864704 1309259984 13.779 100 31 63 0 1323414672 7163024 6991664
23:23:32 7139 8192000000 10198658512 10621657088 1316780496 13.848 100 26 52 0 1333858768 11610496 5467776
23:24:40 47220 8192000000 10206235216 10632085504 1324378960 13.916 100 27 53 1 1336058208 5403936 6275312
23:26:28 5484 8192000000 10216786032 10636242944 1329180816 13.960 100 24 48 0 1340350640 5712032 5457792
23:27:36 12176 8192000000 10225259136 10650619904 1335780192 14.019 100 20 40 1 1350926656 10463440 4683024
23:28:49 7219 8192000000 10185900688 10596708352 1253918800 13.274 95 26 53 0 1266367232 6228080 6220352
23:29:58 25997 8192000000 10165525040 10578235392 1237003072 13.119 95 29 58 1 1249202144 6271744 5927328
23:31:25 7024 8192000000 10129350208 10528350208 1219204928 12.954 98 26 51 0 1230469648 4951648 6313072
23:32:34 7024 8192000000 10134060320 10534027264 1233396432 13.085 99 31 62 1 1245946928 4830064 7720432
23:33:42 15267 8192000000 10164742144 10574610432 1277293280 13.488 100 29 58 0 1291613712 5532304 8788128
23:34:59 4284 8192000000 10195867744 10594074624 1287560720 13.582 100 51 103 2 1305450000 5355888 12533392
23:36:03 2775 8192000000 10195760464 10604613632 1298609472 13.683 100 37 75 0 1314871136 7861312 8400352
23:37:08 7323 8192000000 10201298768 10618163200 1309362752 13.780 100 25 50 2 1320961056 5599680 5998624
23:38:16 9740 8192000000 10218459648 10636976128 1329136800 13.959 100 18 36 0 1342274224 9344416 3793008
23:39:36 30086 8192000000 10241618992 10659549184 1344418544 14.097 100 32 64 0 1358254608 6930592 6905472
23:41:09 46908 8192000000 10252563888 10681221120 1353975712 14.183 100 35 70 0 1370620512 8208112 8436688
23:43:51 13526 8192000000 10283540592 10707865600 1373609504 14.359 100 42 82 0 1399688016 16009328 10069184
23:45:10 7350 8192000000 10295263168 10719657984 1383325584 14.446 100 56 111 1 1413839760 16942560 13571616
23:46:37 17103 8192000000 10317431936 10742849536 1395094992 14.551 100 51 102 0 1428372864 21126176 12151696
23:48:00 1029 8192000000 10320816304 10742734848 1397600368 14.574 100 53 106 2 1429445504 19463696 12381440
23:49:18 2844 8192000000 10334286336 10762993664 1411879408 14.701 100 51 96 1 1448065984 24486656 11699920
23:50:27 7310 8192000000 10366132032 10789773312 1423796752 14.806 100 48 96 0 1465468208 29891392 11780064
23:51:39 3270 8192000000 10372049856 10795311104 1430009792 14.861 100 60 119 0 1480480592 36035904 14434896
23:52:51 1876 8192000000 10350980400 10761101312 1399321120 14.589 100 81 162 0 1459649472 40357168 19971184
23:54:00 1889 8192000000 10346412160 10752016384 1377776352 14.397 99 80 156 1 1440331152 43610352 18944448
23:55:06 4404 8192000000 10329831968 10737651712 1361029184 14.247 99 65 131 2 1428862912 51461936 16371792
23:56:18 9061 8192000000 10315790432 10721476608 1353831952 14.182 100 60 120 0 1422747344 52593680 16321712
23:57:32 5130 8192000000 10330248128 10738188288 1355293808 14.195 99 62 123 3 1435532064 63797712 16440544
23:58:40 10351 8192000000 10336947264 10755334144 1391375312 14.518 100 44 89 0 1442757376 39678912 11703152
23:59:54 23471 8192000000 10347705712 10776772608 1394412048 14.545 100 40 80 0 1455538272 50107664 11018560
00:01:46 11783 8192000000 10341552032 10765172736 1372398368 14.349 99 44 88 0 1433264448 49495600 11370480
00:03:01 19582 8192000000 10321169888 10759131136 1364570848 14.278 99 27 54 0 1419409952 46980640 7858464
00:04:27 27349 8192000000 10295826976 10724556800 1307286944 13.761 96 19 38 0 1364255120 50823312 6144864
00:06:02 39920 8192000000 10277772240 10711146496 1351478112 14.161 100 19 37 0 1369832176 14356192 3997872
00:07:43 35989 8192000000 10275593504 10717188096 1344277296 14.096 98 21 43 1 1362266992 13401952 4587744
00:09:33 71500 8192000000 10278148912 10711552000 1311200560 13.797 96 31 62 0 1326142096 7930528 7011008
00:11:48 41697 8192000000 10266969824 10709647360 1352978176 14.174 99 17 33 0 1366082336 9338528 3765632
00:13:31 45274 8192000000 10304439760 10736746496 1380987184 14.425 100 22 44 1 1401541008 15257600 5296224
00:15:23 12434 8192000000 10293121536 10727800832 1356525424 14.206 98 41 80 1 1383230960 16753600 9951936
00:16:36 153786 8192000000 10293706992 10746372096 1357980256 14.219 98 10 20 0 1370586912 10168224 2438432
00:20:11 82969 8192000000 10298080960 10748997632 1384319136 14.455 99 23 46 0 1402165120 11433728 6412256
00:22:44 23443 8192000000 10297789104 10752008192 1396899232 14.567 100 22 44 0 1410720752 8787536 5033984
00:24:14 77568 8192000000 10302208240 10770776064 1401218256 14.606 100 12 24 0 1420389664 16033600 3137808
00:26:45 75676 8192000000 10311807808 10767749120 1392384640 14.527 99 16 33 1 1406263600 10519920 3359040
00:29:01 52751 8192000000 10334844480 10794868736 1407149712 14.659 99 28 56 0 1426107728 12014208 6943808
00:31:00 34941 8192000000 10317754704 10787299328 1397949904 14.577 99 15 30 0 1418658272 17071536 3636832
00:32:36 70742 8192000000 10292995456 10747551744 1326421520 13.935 95 13 26 0 1339378592 9830048 3127024
00:35:05 46300 8192000000 10327851472 10775224320 1403045088 14.622 100 35 70 0 1428362224 17043120 8274016
00:37:45 116003 8192000000 10275370640 10726289408 1262391504 13.352 91 9 18 0 1277822768 13777360 1653904
00:40:43 71198 8192000000 10259580272 10701152256 1309497040 13.782 96 31 58 2 1322040304 6334144 6209120
00:42:55 11107 8192000000 10245685984 10667900928 1320666336 13.883 100 70 142 0 1347474560 10799872 16008352
00:44:06 35873 8192000000 10245683104 10695430144 1342259888 14.078 99 17 34 0 1353727776 6770432 4697456
00:45:43 59609 8192000000 10244763504 10698280960 1338927248 14.048 99 22 44 1 1352211968 8252848 5031872
00:48:18 78309 8192000000 10254190064 10703441920 1330074768 13.968 98 11 23 1 1343402912 10859872 2468272
00:50:36 49524 8192000000 10251065008 10693488640 1301124192 13.705 96 24 45 0 1315287392 8811840 5351360
00:52:30 101842 8192000000 10235401136 10698625024 1335671696 14.018 99 11 22 0 1349122816 10866352 2584768
00:55:14 64976 8192000000 10252052384 10701602816 1319453904 13.872 97 19 39 1 1330853552 7293856 4105792
00:57:37 23996 8192000000 10271815136 10712010752 1324416640 13.917 97 40 80 0 1349051072 15783104 8851328
00:59:04 67784 8192000000 10246529712 10707947520 1318608960 13.864 97 23 46 0 1340484944 16729664 5146320
01:01:16 24532 8192000000 10269507888 10716184576 1352515232 14.170 99 26 51 0 1370011936 11860064 5636640
01:02:50 63251 8192000000 10300310208 10735091712 1371421424 14.340 100 31 63 0 1390154576 11679008 7054144
01:05:04 56799 8192000000 10281901248 10732822528 1345862512 14.110 98 22 44 0 1360181872 9825200 4494160
01:07:03 63770 8192000000 10255558352 10716782592 1338286672 14.042 98 16 32 0 1354688480 12288272 4113536
01:09:59 46524 8192000000 10279296640 10736631808 1373447424 14.358 99 24 48 0 1387193200 7633920 6111856
01:11:46 76740 8192000000 10282445920 10759143424 1376370720 14.384 99 5 10 0 1388074720 10810624 893376
01:14:04 79787 8192000000 10291235408 10751987712 1352388816 14.169 97 17 34 0 1372100016 15445952 4265248
01:16:28 64910 8192000000 10296810448 10746097664 1353056768 14.175 97 31 62 0 1369331136 9399840 6874528
01:18:36 39518 8192000000 10302926576 10761760768 1387267376 14.481 100 16 32 0 1404458544 13485040 3706128
01:20:16 120588 8192000000 10310421408 10775941120 1368682144 14.315 98 9 18 0 1389695072 18875600 2137328
01:23:17 68021 8192000000 10294826032 10746605568 1348094240 14.130 98 17 34 0 1363166560 11618672 3453648
01:25:34 39272 8192000000 10283533984 10730967040 1315556864 13.836 95 22 43 0 1330445968 10091088 4798016
01:27:28 111947 8192000000 10266134208 10733514752 1341346048 14.070 98 24 48 0 1355544448 8454384 5744016
01:30:26 50622 8192000000 10276855072 10725384192 1310085024 13.787 95 30 58 0 1328781728 12324384 6372320
01:32:25 62158 8192000000 10247587264 10717503488 1333398352 13.998 99 8 16 0 1351152736 15594304 2160080
01:34:48 76262 8192000000 10240814160 10695954432 1311797024 13.802 96 20 40 0 1322125456 6402672 3925760
01:37:06 106487 8192000000 9267979952 9556369408 421014064 4.888 31 9 18 0 426139056 3202080 1922912

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 01-03-2023 01:41:05 to 01-03-2023 01:41:20.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 51 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 01-03-2023 01:41:21 to 01-03-2023 01:41:23.

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          239027
MON$OLDEST_ACTIVE               239904
MON$OLDEST_SNAPSHOT             239904
MON$NEXT_TRANSACTION            239904
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                       2029280
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.33665 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33665 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33665 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33665 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 ---
:801: 
:802: mscs-test-161.budzdorov.ru    Wed Mar  1 01:39:03 2023
:803:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:804: 
:805: 
:806: mscs-test-161.budzdorov.ru    Wed Mar  1 01:39:03 2023
:807:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:808: 
:809: 
:810: mscs-test-161.budzdorov.ru    Wed Mar  1 01:39:03 2023
:811:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:812: 
--- end of diff output ---

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

01.03.23 01:41:25. 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
2023-03-01 01:41:27 No severe PSQL-related problems occured
01.03.23 01:41:29. End of report.