Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 05.07.2022 22:43
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 15997404 kB
MemAvailable 31160524 kB
Buffers 12 kB
Cached 15721432 kB
SwapCached 2544 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-07-05 22:42:14
DTS_END                         2022-07-05 22:42:14.9480
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/37598
TRN_ID                          253266
ATT_ID                          713
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: 9506.58 18506 1140789
customer order: creation 184.01 341 22081
customer order: refuse 16.32 338 1958
order to supplier: creation 49.52 1233 5942
order to supplier: removal 3.68 89856 442
invoice (draft): creation 48.87 3059 5864
invoice (draft): removal 53.23 737 6387
invoice accept: apply 44.73 18594 5367
invoice accept: cancel 48.10 865 5772
customer reserve: creation 4019.25 191 482310
customer reserve: removal 4723.27 55 566792
realization accept: apply 90.18 187 10822
realization accept: cancel 85.76 107 10291
payment from customer: creation 64.38 98 7725
payment from customer: removal 5.90 76 708
payment to supplier: creation 52.92 150 6350
payment to supplier: removal 3.76 74 451
service: total inventory turnovers 4.79 1162 575
service: total monetary turnovers 7.04 75 845
service: aggregate perf. data 0.86 20609 103
service: refresh index statistics 0.03 232320 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 05-07-2022 22:43:46 to 05-07-2022 22:43:48.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6163
TEST_TIME 2 5201
TEST_TIME 3 5209
TEST_TIME 4 5243
TEST_TIME 5 5724
TEST_TIME 6 5530
TEST_TIME 7 5424
TEST_TIME 8 6080
TEST_TIME 9 6121
TEST_TIME 10 6276
TEST_TIME 11 6287
TEST_TIME 12 6549
TEST_TIME 13 6621
TEST_TIME 14 6787
TEST_TIME 15 6793
TEST_TIME 16 6850
TEST_TIME 17 7086
TEST_TIME 18 7178
TEST_TIME 19 7527
TEST_TIME 20 7726
TEST_TIME 21 8281
TEST_TIME 22 8573
TEST_TIME 23 8644
TEST_TIME 24 9044
TEST_TIME 25 9417
TEST_TIME 26 9440
TEST_TIME 27 9830
TEST_TIME 28 10102
TEST_TIME 29 10837
TEST_TIME 30 10826
TEST_TIME 31 10796
TEST_TIME 32 10702
TEST_TIME 33 10569
TEST_TIME 34 10689
TEST_TIME 35 10735
TEST_TIME 36 10543
TEST_TIME 37 10515
TEST_TIME 38 10489
TEST_TIME 39 10337
TEST_TIME 40 10252
TEST_TIME 41 10066
TEST_TIME 42 10100
TEST_TIME 43 10133
TEST_TIME 44 10008
TEST_TIME 45 10015
TEST_TIME 46 9929
TEST_TIME 47 9984
TEST_TIME 48 10010
TEST_TIME 49 9996
TEST_TIME 50 9910
TEST_TIME 51 9860
TEST_TIME 52 9932
TEST_TIME 53 9787
TEST_TIME 54 9731
TEST_TIME 55 9664
TEST_TIME 56 9635
TEST_TIME 57 9662
TEST_TIME 58 9664
TEST_TIME 59 9816
TEST_TIME 60 9770
TEST_TIME 61 9804
TEST_TIME 62 9908
TEST_TIME 63 9870
TEST_TIME 64 9923
TEST_TIME 65 9960
TEST_TIME 66 9993
TEST_TIME 67 10062
TEST_TIME 68 10058
TEST_TIME 69 10116
TEST_TIME 70 10074
TEST_TIME 71 10185
TEST_TIME 72 10054
TEST_TIME 73 9989
TEST_TIME 74 9895
TEST_TIME 75 9916
TEST_TIME 76 9900
TEST_TIME 77 9899
TEST_TIME 78 9845
TEST_TIME 79 9758
TEST_TIME 80 9688
TEST_TIME 81 9696
TEST_TIME 82 9693
TEST_TIME 83 9708
TEST_TIME 84 9719
TEST_TIME 85 9756
TEST_TIME 86 9735
TEST_TIME 87 9763
TEST_TIME 88 9785
TEST_TIME 89 9751
TEST_TIME 90 9766
TEST_TIME 91 9828
TEST_TIME 92 9831
TEST_TIME 93 9886
TEST_TIME 94 9866
TEST_TIME 95 9827
TEST_TIME 96 9761
TEST_TIME 97 9735
TEST_TIME 98 9719
TEST_TIME 99 9687
TEST_TIME 100 9628
TEST_TIME 101 9571
TEST_TIME 102 9521
TEST_TIME 103 9553
TEST_TIME 104 9499
TEST_TIME 105 9514
TEST_TIME 106 9506
TEST_TIME 107 9541
TEST_TIME 108 9523
TEST_TIME 109 9572
TEST_TIME 110 9532
TEST_TIME 111 9546
TEST_TIME 112 9531
TEST_TIME 113 9576
TEST_TIME 114 9600
TEST_TIME 115 9600
TEST_TIME 116 9629
TEST_TIME 117 9654
TEST_TIME 118 9666
TEST_TIME 119 9589
TEST_TIME 120 9511

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 05-07-2022 22:43:49 to 05-07-2022 22:43:53.

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 1588175 1588175 0 0.00 0 25375 7 0 0
doc_list_biud 1588174 1588174 0 0.00 0 38986 25 0 0
sp_add_invoice_to_stock 5544 5367 177 3.19 25 183099 18595 0 177
sp_cancel_adding_invoice 5772 5772 0 0.00 5 110855 866 0 0
sp_cancel_client_order 1958 1958 0 0.00 4 19432 338 0 0
sp_cancel_customer_reserve 566792 566792 0 0.00 0 38993 55 0 0
sp_cancel_pay_from_customer 708 708 0 0.00 2 5361 77 0 0
sp_cancel_pay_to_supplier 451 451 0 0.00 2 6786 75 0 0
sp_cancel_supplier_invoice 6402 6387 15 0.23 1 16539 738 0 15
sp_cancel_supplier_order 442 442 0 0.00 6 1088800 89857 0 0
sp_cancel_write_off 10291 10291 0 0.00 1 10791 108 0 0
sp_client_order 22081 22081 0 0.00 3 44484 342 0 0
sp_customer_reserve 482310 482310 0 0.00 0 73721 191 0 0
sp_fill_shopping_cart 33875 33875 0 0.00 1 12612 35 0 0
sp_fill_shopping_cart_clo_res 482301 482301 0 0.00 0 11555 12 0 0
sp_get_clo_for_invoice 5357 5357 0 0.00 0 21594 249 0 0
sp_kill_cost_storno 17222 17222 0 0.00 0 9734 56 0 0
sp_kill_qstorno_ret_qs2qd 1150704 1150704 0 0.00 0 38981 30 0 0
sp_kill_qty_storno 607821 607821 0 0.00 0 38985 65 0 0
sp_lock_selected_doc 22446 22446 0 0.00 0 10649 6 0 0
sp_make_cost_storno 30254 30254 0 0.00 0 25366 64 0 0
sp_multiply_rows_for_pdistr 30254 30254 0 0.00 0 8400 27 0 0
sp_multiply_rows_for_qdistr 22081 22081 0 0.00 0 44477 152 0 0
sp_pay_from_customer 7725 7725 0 0.00 4 18523 99 0 0
sp_pay_to_supplier 6350 6350 0 0.00 4 24222 151 0 0
sp_payment_common 14075 14075 0 0.00 1 17681 34 0 0
sp_qd_handle_on_cancel_clo 1958 1958 0 0.00 0 19426 310 0 0
sp_qd_handle_on_invoice_upd_sts 11129 11129 0 0.00 0 23487 338 0 0
sp_qd_handle_on_reserve_upd_sts 10822 10822 0 0.00 0 27786 67 0 0
sp_reserve_write_off 10822 10822 0 0.00 6 27892 187 0 0
sp_supplier_invoice 5864 5864 0 0.00 7 73450 3060 0 0
sp_supplier_order 5942 5942 0 0.00 4 40947 1233 0 0
srv_aggregate_perf_data 104 103 1 0.96 2378 432155 20609 0 1
srv_fill_mon_cache_memory 65 65 0 0.00 1847 114556 44333 0 0
srv_find_qd_qs_mism 1090387 1090387 0 0.00 0 16831 7 0 0
srv_make_invnt_saldo 575 575 0 0.00 41 126533 1163 0 0
srv_make_money_saldo 845 845 0 0.00 1 36757 75 0 0
srv_recalc_idx_stat 4 4 0 0.00 159116 344293 232321 0 0
t$perf-abend:sp_add_invoice_to_stock 177 177 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 15 15 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 5367 5367 0 0.00 0 10297 165 0 0
t$perf-norm:sp_cancel_adding_invoice 462 462 0 0.00 0 9543 94 0 0
t$perf-norm:sp_cancel_client_order 1958 1958 0 0.00 0 4791 18 0 0
t$perf-norm:sp_cancel_customer_reserve 745 745 0 0.00 0 9582 24 0 0
t$perf-norm:sp_cancel_pay_from_customer 708 708 0 0.00 0 3204 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 451 451 0 0.00 0 2057 9 0 0
t$perf-norm:sp_cancel_supplier_invoice 417 417 0 0.00 0 900 13 0 0
t$perf-norm:sp_cancel_supplier_order 442 442 0 0.00 0 29355 1637 0 0
t$perf-norm:sp_cancel_write_off 1084 1084 0 0.00 0 2443 9 0 0
t$perf-norm:sp_client_order 22081 22081 0 0.00 0 9607 19 0 0
t$perf-norm:sp_customer_reserve 1036 1036 0 0.00 0 2575 7 0 0
t$perf-norm:sp_pay_from_customer 7725 7725 0 0.00 0 7677 12 0 0
t$perf-norm:sp_pay_to_supplier 6350 6350 0 0.00 0 11102 14 0 0
t$perf-norm:sp_reserve_write_off 10822 10822 0 0.00 0 5733 11 0 0
t$perf-norm:sp_supplier_invoice 5864 5864 0 0.00 0 15210 20 0 0
t$perf-norm:sp_supplier_order 5942 5942 0 0.00 0 5355 15 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 374 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 592 11 0 0
t$perf-norm:srv_make_invnt_saldo 575 575 0 0.00 0 820 20 0 0
t$perf-norm:srv_make_money_saldo 845 845 0 0.00 0 1108 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 48 13 0 0
v_add_invoice_to_stock 11068 10714 354 3.19 0 6571 17 0 354
v_all_customers 40480 40480 0 0.00 0 40 1 0 0
v_all_suppliers 32402 32402 0 0.00 0 165 1 0 0
v_all_wares 44162 44162 0 0.00 0 147 1 0 0
v_cancel_adding_invoice 924 924 0 0.00 0 2081 9 0 0
v_cancel_client_order 3916 3916 0 0.00 0 2911 4 0 0
v_cancel_customer_prepayment 1416 1416 0 0.00 0 275 3 0 0
v_cancel_customer_reserve 1490 1490 0 0.00 0 520 4 0 0
v_cancel_payment_to_supplier 902 902 0 0.00 0 430 3 0 0
v_cancel_supplier_invoice 864 834 30 3.47 0 3564 23 0 30
v_cancel_supplier_order 884 884 0 0.00 0 1598 7 0 0
v_cancel_write_off 2168 2168 0 0.00 0 1219 11 0 0
v_max_id_clo_ord 5938 5938 0 0.00 0 1096 1 0 0
v_max_id_clo_res 1036 1036 0 0.00 0 2050 16 0 0
v_max_id_ord_sup 5856 5856 0 0.00 0 6350 6 0 0
v_max_non_paid_invoice 1943 1943 0 0.00 0 202 2 0 0
v_max_non_paid_realizn 4109 4109 0 0.00 0 4886 6 0 0
v_min_id_clo_ord 5938 5938 0 0.00 0 11816 12 0 0
v_min_id_clo_res 1036 1036 0 0.00 0 5588 18 0 0
v_min_id_ord_sup 5856 5856 0 0.00 0 4239 5 0 0
v_min_non_paid_invoice 6350 6350 0 0.00 0 17674 31 0 0
v_min_non_paid_realizn 7725 7725 0 0.00 0 8316 23 0 0
v_our_firm 10914 10914 0 0.00 0 17 1 0 0
v_reserve_write_off 21644 21644 0 0.00 0 3479 5 0 0
x_lock_depdocs_on_canc_invoice 11742 11742 0 0.00 0 28565 113 0 0
x_lock_depdocs_on_canc_sup_ord 442 442 0 0.00 0 5504 290 0 0
x_make_qstorno_1000_1200 5942 5942 0 0.00 1 40914 1150 0 0
x_make_qstorno_1200_2000 5864 5864 0 0.00 3 73443 2937 0 0
x_make_qstorno_2100_3300 482309 482309 0 0.00 0 73720 176 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 05-07-2022 22:43:54 to 05-07-2022 22:43:58.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
20:12:18 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:13:19 34331 8192000000 9912876608 10225012736 983506464 10.718 100 85 171 0 1012116336 6050704 22559168
20:15:00 18081 8192000000 9952792896 10276827136 1035761120 11.224 100 88 174 0 1066340192 8125728 22453344
20:16:25 9268 8192000000 9981297216 10313199616 1067175792 11.525 100 83 166 0 1098008976 9688176 21145008
20:17:45 9082 8192000000 10041519808 10389938176 1124155168 12.066 100 80 159 0 1153131840 10457536 18519136
20:18:57 4217 8192000000 10081181328 10444804096 1173824176 12.533 100 77 154 0 1203716640 12607616 17284848
20:20:04 4714 8192000000 10131681184 10505617408 1220558784 12.967 100 75 150 1 1253467344 16129008 16779552
20:21:13 1795 8192000000 10163886880 10545143808 1253071024 13.266 100 78 157 0 1283103456 13023008 17009424
20:22:17 822 8192000000 10199404224 10582790144 1286315072 13.571 100 90 177 0 1321472960 15397520 19760368
20:23:19 11978 8192000000 10197097200 10591776768 1296636192 13.665 100 59 119 0 1320235920 10139616 13460112
20:24:32 23392 8192000000 10186081392 10607370240 1303755344 13.729 100 37 75 1 1318322864 6154192 8413328
20:25:58 5441 8192000000 10196948272 10613661696 1306241136 13.752 100 19 36 2 1319750768 9681408 3828224
20:27:04 5385 8192000000 10210332160 10629623808 1318422768 13.862 100 28 55 0 1336884608 11976272 6485568
20:28:11 13275 8192000000 10213957392 10635976704 1327374768 13.943 100 7 14 0 1334458800 5602752 1481280
20:29:25 62336 8192000000 10226197136 10649362432 1335755632 14.019 100 28 57 0 1348536016 6004112 6776272
20:31:29 5779 8192000000 10227501664 10649890816 1327832800 13.948 99 27 54 1 1339405376 5473920 6098656
20:32:35 7184 8192000000 10199012448 10619850752 1305931616 13.749 99 22 44 1 1316662512 5543440 5187456
20:33:45 59660 8192000000 10167121584 10574716928 1233502496 13.086 96 37 74 0 1248267728 5237696 9527536
20:35:56 5982 8192000000 10124619488 10529087488 1230893472 13.062 99 21 42 0 1242051248 6341184 4816592
20:37:04 9351 8192000000 10164628768 10562785280 1265861504 13.384 100 26 52 1 1277750928 6000576 5888848
20:38:14 19971 8192000000 10173855904 10588774400 1285962800 13.567 100 15 30 2 1294453744 5483744 3007200
20:39:37 17129 8192000000 10182668528 10596831232 1296386960 13.662 100 25 50 0 1308170624 5646320 6137344
20:40:54 5928 8192000000 10193607488 10611556352 1309136736 13.778 100 38 76 1 1323148736 5117968 8894032
20:42:02 10341 8192000000 10226109808 10639761408 1327029744 13.940 100 17 32 0 1334813744 4236560 3547440
20:43:20 17119 8192000000 10262090976 10673545216 1351533104 14.161 100 57 114 0 1373047456 7872992 13641360
20:44:46 70508 8192000000 10273044256 10698547200 1369882704 14.326 100 41 82 0 1391222864 11836640 9503520
20:46:59 12247 8192000000 10290377184 10711429120 1378524192 14.403 100 52 104 0 1402941744 12553472 11864080
20:48:24 31483 8192000000 10311798560 10737491968 1398720528 14.584 100 62 122 0 1430147632 17261296 14165808
20:50:07 2031 8192000000 10318598368 10739126272 1397391728 14.572 100 51 99 3 1431571088 22555344 11624016
20:51:15 17069 8192000000 10332816080 10759213056 1404087680 14.631 100 54 108 0 1444863152 28441488 12333984
20:52:41 13539 8192000000 10343796304 10772631552 1421827616 14.789 100 47 94 1 1455726608 22575600 11323392
20:53:58 5010 8192000000 10350114160 10769649664 1413331776 14.714 100 59 116 0 1455037600 28343392 13362432
20:55:10 2193 8192000000 10333291216 10742046720 1388639424 14.494 100 79 156 2 1437327728 30382064 18306240
20:56:18 1845 8192000000 10336648464 10751270912 1397499232 14.573 100 81 160 3 1449250160 31780976 19969952
20:57:26 3508 8192000000 10323690960 10725675008 1371724880 14.342 99 81 159 0 1425915824 35236880 18954064
20:58:38 4438 8192000000 10341345648 10750881792 1391543072 14.520 100 64 127 0 1447322832 39971872 15807888
20:59:47 3036 8192000000 10349442496 10752827392 1385785872 14.468 100 81 159 2 1449444240 43951120 19707248
21:00:55 2486 8192000000 10355228624 10760069120 1388408176 14.492 100 76 150 2 1456261664 48622240 19231248
21:02:07 2095 8192000000 10351838448 10756685824 1379908032 14.416 100 81 160 0 1449595520 49487312 20200176
21:03:13 7321 8192000000 10335453088 10752364544 1365117888 14.283 99 56 112 0 1432793200 52291216 15384096
21:04:24 9280 8192000000 10332726336 10750218240 1378445120 14.403 100 44 89 0 1438493200 47710720 12337360
21:05:38 10815 8192000000 10341038624 10761912320 1381130320 14.427 100 38 76 0 1441171184 49140416 10900448
21:06:53 9496 8192000000 10336668176 10764607488 1386745184 14.477 100 51 103 0 1448451792 48157824 13548784
21:08:03 26202 8192000000 10337299984 10761859072 1375494656 14.376 99 40 80 0 1428873632 41808736 11570240
21:09:31 16757 8192000000 10324661072 10766766080 1383878768 14.451 100 43 87 0 1428793280 34126576 10787936
21:11:23 37737 8192000000 10267502960 10713874432 1332175648 13.987 98 9 18 0 1362342432 27810624 2356160
21:13:01 90457 8192000000 10286397120 10738413568 1376093760 14.382 100 13 26 3 1389371376 10061360 3216256
21:15:32 97360 8192000000 10306669264 10767417344 1348163472 14.131 97 7 14 0 1374474992 24581728 1729792
21:18:11 114556 8192000000 10310776224 10744193024 1367094208 14.301 99 22 43 0 1386323680 14516016 4713456
21:21:05 43455 8192000000 10308255568 10745909248 1382639056 14.440 99 35 70 0 1401319712 10693904 7986752
21:22:51 106781 8192000000 10312869568 10760536064 1386307120 14.473 99 39 74 0 1404533168 9965488 8260560
21:25:39 71240 8192000000 10297202080 10742718464 1367239792 14.302 98 33 66 0 1382296304 7476720 7579792
21:27:51 29305 8192000000 10293452128 10746404864 1365237648 14.284 98 28 55 0 1381426080 9551280 6637152
21:29:25 56519 8192000000 10291412608 10747858944 1357389248 14.214 98 43 86 0 1376152480 8136400 10626832
21:31:31 101929 8192000000 10267381728 10719830016 1327270640 13.942 95 16 32 0 1338140864 6956608 3913616
21:34:14 85425 8192000000 10279252240 10748309504 1368663296 14.315 99 12 24 1 1387351648 16201760 2486592
21:37:07 66509 8192000000 10297587568 10765946880 1381872832 14.433 100 12 24 0 1410624672 25677568 3074272
21:39:14 66462 8192000000 10298889200 10763218944 1359855984 14.236 98 10 20 0 1382586464 19900000 2830480
21:41:21 47452 8192000000 10298945664 10772103168 1375806896 14.379 99 27 52 0 1401560992 19421200 6332896
21:43:09 32063 8192000000 10273810048 10722369536 1327221568 13.942 97 16 32 0 1344127088 13448896 3456624
21:45:30 42807 8192000000 10270297808 10707472384 1317244336 13.852 97 30 60 0 1340895872 17570432 6081104
21:47:13 44541 8192000000 10286007744 10727514112 1348087376 14.130 99 42 84 0 1367863248 10508832 9267040
21:49:06 96208 8192000000 10277912224 10725634048 1320998272 13.886 97 12 24 0 1343582688 20151184 2433232
21:52:00 79132 8192000000 10294067760 10751205376 1348298720 14.132 98 21 42 0 1370427408 17097616 5031072
21:55:29 58661 8192000000 10292424608 10739417088 1344894624 14.102 97 23 46 0 1357586336 8063104 4628608
21:57:30 43410 8192000000 10280634848 10741682176 1364647312 14.279 98 19 38 0 1380398048 11443440 4307296
21:59:14 20504 8192000000 10280976640 10757820416 1379432192 14.411 100 5 9 0 1396241520 15488192 1321136
22:00:35 72399 8192000000 10290299888 10752761856 1370571568 14.332 98 15 30 0 1387369568 13241472 3556528
22:02:53 101764 8192000000 10301412512 10772631552 1396585312 14.565 100 10 20 0 1413501296 14712720 2203264
22:05:37 51600 8192000000 10290719904 10755719168 1357011408 14.211 97 31 60 0 1374983088 11700576 6271104
22:07:44 45041 8192000000 10297937280 10751778816 1357348912 14.214 98 19 39 0 1373598768 11932720 4317136
22:09:31 52469 8192000000 10309031632 10767835136 1368266128 14.312 98 22 44 0 1389234080 15396032 5571920
22:11:25 11362 8192000000 10291219632 10733690880 1373874992 14.362 100 40 78 1 1391977648 9808320 8294336
22:12:37 35217 8192000000 10304557168 10762391552 1368203376 14.311 99 36 72 0 1394118656 18023792 7891488
22:14:14 36135 8192000000 10312840384 10762952704 1370126000 14.328 98 32 64 2 1386652176 9898000 6628176
22:15:53 68979 8192000000 10309212896 10760929280 1330501744 13.972 95 17 34 0 1355452640 21483040 3467856
22:18:08 104131 8192000000 10282781696 10736144384 1344045808 14.094 97 33 66 0 1366886928 15486976 7354144
22:21:08 55623 8192000000 10284321472 10754539520 1390730208 14.512 100 7 14 0 1398833280 6744800 1358272
22:23:04 54106 8192000000 10295883104 10751606784 1369145600 14.319 98 16 32 0 1382399808 8960192 4294016
22:24:58 65531 8192000000 10282226992 10753810432 1354390048 14.187 98 12 22 0 1365733600 9120576 2222976
22:27:06 78083 8192000000 10302132048 10772074496 1380178608 14.418 99 16 32 0 1401136288 17041072 3916608
22:29:28 26902 8192000000 10290879360 10747240448 1365580096 14.287 98 27 52 0 1378793504 7747008 5466400
22:30:56 30096 8192000000 10285295360 10739834880 1364618896 14.279 99 26 52 0 1382441344 12082752 5739696
22:32:26 24222 8192000000 10271415872 10718920704 1339860640 14.056 98 36 70 0 1356405520 8768736 7776144
22:33:54 34540 8192000000 10259192192 10707857408 1323165936 13.905 98 37 74 0 1346127760 15132224 7829600
22:35:29 50035 8192000000 10250413888 10706923520 1322046288 13.895 98 13 26 1 1341698144 16850032 2801824
22:37:19 25117 8192000000 10283269312 10716991488 1329354688 13.961 98 38 76 0 1350002944 12036624 8611632
22:38:48 84954 8192000000 10256753920 10714701824 1324032464 13.913 98 11 22 0 1337474096 11468032 1973600
22:41:15 72176 8192000000 9172608320 9433198592 338069184 3.963 25 8 16 0 342516768 2762960 1684624

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 05-07-2022 22:44:00 to 05-07-2022 22:44:14.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 177 v_add_invoice_to_stock 335544517
except 15 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 05-07-2022 22:44:15 to 05-07-2022 22:44:17.

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          253655
MON$OLDEST_ACTIVE               253656
MON$OLDEST_SNAPSHOT             253656
MON$NEXT_TRANSACTION            253656
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                       2049696
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.33608 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33608 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33608 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33608 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
:6: 
:7: mscs-test-161.budzdorov.ru    Tue Jul  5 22:42:28 2022
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Tue Jul  5 22:42:29 2022
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:13: 
:14: 
:15: mscs-test-161.budzdorov.ru    Tue Jul  5 22:42:29 2022
:16:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:17: 
--- end of diff output ---

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

05.07.22 22:44:18. 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-07-05 22:44:21 No severe PSQL-related problems occured
05.07.22 22:44:22. End of report.