Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 24.03.2022 01: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 16697608 kB
MemAvailable 31622020 kB
Buffers 0 kB
Cached 14987364 kB
SwapCached 2464 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 3.0.10
DB_NAME                         /home/bases/oltp30-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3333
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp30-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp30
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-03-24 01:38:12
DTS_END                         2022-03-24 01:38:12.4910
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/56896
TRN_ID                          258869
ATT_ID                          704
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: 9561.65 17159 1147398
customer order: creation 190.33 358 22839
customer order: refuse 16.56 298 1987
order to supplier: creation 50.09 1210 6011
order to supplier: removal 3.82 80679 458
invoice (draft): creation 50.53 3373 6063
invoice (draft): removal 54.40 768 6528
invoice accept: apply 44.50 15924 5340
invoice accept: cancel 49.15 711 5898
customer reserve: creation 3981.15 163 477738
customer reserve: removal 4806.44 50 576773
realization accept: apply 88.53 145 10624
realization accept: cancel 82.78 107 9934
payment from customer: creation 64.42 91 7730
payment from customer: removal 6.36 58 763
payment to supplier: creation 55.48 118 6658
payment to supplier: removal 3.68 55 442
service: total inventory turnovers 5.47 792 656
service: total monetary turnovers 7.07 40 848
service: aggregate perf. data 0.87 17860 104
service: refresh index statistics 0.03 220382 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 24-03-2022 01:38:50 to 24-03-2022 01:38:52.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2645
TEST_TIME 2 3644
TEST_TIME 3 3722
TEST_TIME 4 4382
TEST_TIME 5 4397
TEST_TIME 6 4473
TEST_TIME 7 4959
TEST_TIME 8 4904
TEST_TIME 9 5040
TEST_TIME 10 5452
TEST_TIME 11 5879
TEST_TIME 12 6619
TEST_TIME 13 6820
TEST_TIME 14 7336
TEST_TIME 15 7651
TEST_TIME 16 7787
TEST_TIME 17 8826
TEST_TIME 18 8983
TEST_TIME 19 8986
TEST_TIME 20 9215
TEST_TIME 21 10313
TEST_TIME 22 10373
TEST_TIME 23 11060
TEST_TIME 24 10975
TEST_TIME 25 10920
TEST_TIME 26 10848
TEST_TIME 27 10642
TEST_TIME 28 10824
TEST_TIME 29 10625
TEST_TIME 30 10474
TEST_TIME 31 10411
TEST_TIME 32 10195
TEST_TIME 33 10192
TEST_TIME 34 10282
TEST_TIME 35 10449
TEST_TIME 36 10321
TEST_TIME 37 10215
TEST_TIME 38 10177
TEST_TIME 39 10140
TEST_TIME 40 9973
TEST_TIME 41 10006
TEST_TIME 42 10078
TEST_TIME 43 10047
TEST_TIME 44 10036
TEST_TIME 45 10064
TEST_TIME 46 9999
TEST_TIME 47 9971
TEST_TIME 48 9904
TEST_TIME 49 9955
TEST_TIME 50 10350
TEST_TIME 51 10315
TEST_TIME 52 10239
TEST_TIME 53 10346
TEST_TIME 54 10384
TEST_TIME 55 10344
TEST_TIME 56 10174
TEST_TIME 57 10098
TEST_TIME 58 10090
TEST_TIME 59 9977
TEST_TIME 60 9927
TEST_TIME 61 9930
TEST_TIME 62 9859
TEST_TIME 63 9807
TEST_TIME 64 9879
TEST_TIME 65 9820
TEST_TIME 66 9791
TEST_TIME 67 9965
TEST_TIME 68 9909
TEST_TIME 69 9917
TEST_TIME 70 10008
TEST_TIME 71 10113
TEST_TIME 72 10129
TEST_TIME 73 10109
TEST_TIME 74 10090
TEST_TIME 75 10104
TEST_TIME 76 10073
TEST_TIME 77 9984
TEST_TIME 78 9914
TEST_TIME 79 9876
TEST_TIME 80 9848
TEST_TIME 81 9784
TEST_TIME 82 9731
TEST_TIME 83 9741
TEST_TIME 84 9668
TEST_TIME 85 9670
TEST_TIME 86 9675
TEST_TIME 87 9654
TEST_TIME 88 9680
TEST_TIME 89 9641
TEST_TIME 90 9693
TEST_TIME 91 9719
TEST_TIME 92 9693
TEST_TIME 93 9687
TEST_TIME 94 9726
TEST_TIME 95 9753
TEST_TIME 96 9760
TEST_TIME 97 9708
TEST_TIME 98 9767
TEST_TIME 99 9786
TEST_TIME 100 9735
TEST_TIME 101 9672
TEST_TIME 102 9610
TEST_TIME 103 9629
TEST_TIME 104 9577
TEST_TIME 105 9568
TEST_TIME 106 9519
TEST_TIME 107 9515
TEST_TIME 108 9524
TEST_TIME 109 9496
TEST_TIME 110 9490
TEST_TIME 111 9517
TEST_TIME 112 9564
TEST_TIME 113 9549
TEST_TIME 114 9603
TEST_TIME 115 9590
TEST_TIME 116 9599
TEST_TIME 117 9667
TEST_TIME 118 9636
TEST_TIME 119 9633
TEST_TIME 120 9567

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 24-03-2022 01:38:54 to 24-03-2022 01:38:57.

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 1592157 1592157 0 0.00 0 23415 6 0 0
doc_list_biud 1592147 1592147 0 0.00 0 39553 24 0 0
sp_add_invoice_to_stock 5476 5340 136 2.48 29 173017 15925 0 136
sp_cancel_adding_invoice 5898 5898 0 0.00 5 63334 712 0 0
sp_cancel_client_order 1987 1987 0 0.00 3 15470 299 0 0
sp_cancel_customer_reserve 576773 576773 0 0.00 1 30646 50 0 0
sp_cancel_pay_from_customer 763 763 0 0.00 2 3286 59 0 0
sp_cancel_pay_to_supplier 442 442 0 0.00 3 3228 55 0 0
sp_cancel_supplier_invoice 6548 6528 20 0.30 2 37643 769 0 20
sp_cancel_supplier_order 458 458 0 0.00 8 966928 80680 0 0
sp_cancel_write_off 9934 9934 0 0.00 1 10986 107 0 0
sp_client_order 22839 22839 0 0.00 3 18975 358 0 0
sp_customer_reserve 477738 477738 0 0.00 0 68011 163 0 0
sp_fill_shopping_cart 34912 34912 0 0.00 1 16488 42 0 0
sp_fill_shopping_cart_clo_res 477722 477722 0 0.00 0 24055 13 0 0
sp_get_clo_for_invoice 5324 5324 0 0.00 0 18806 288 0 0
sp_kill_cost_storno 17037 17037 0 0.00 0 5295 45 0 0
sp_kill_qstorno_ret_qs2qd 1170466 1170466 0 0.00 0 37561 28 0 0
sp_kill_qty_storno 617523 617523 0 0.00 0 39553 61 0 0
sp_lock_selected_doc 22351 22351 0 0.00 0 6937 4 0 0
sp_make_cost_storno 30336 30336 0 0.00 0 23196 44 0 0
sp_multiply_rows_for_pdistr 30332 30332 0 0.00 0 5532 23 0 0
sp_multiply_rows_for_qdistr 22839 22839 0 0.00 0 13240 178 0 0
sp_pay_from_customer 7730 7730 0 0.00 4 9259 91 0 0
sp_pay_to_supplier 6658 6658 0 0.00 5 14951 118 0 0
sp_payment_common 14387 14387 0 0.00 1 9246 21 0 0
sp_qd_handle_on_cancel_clo 1987 1987 0 0.00 0 15461 276 0 0
sp_qd_handle_on_invoice_upd_sts 11220 11220 0 0.00 0 39551 304 0 0
sp_qd_handle_on_reserve_upd_sts 10623 10623 0 0.00 0 22045 56 0 0
sp_reserve_write_off 10624 10624 0 0.00 6 22052 145 0 0
sp_supplier_invoice 6063 6063 0 0.00 7 102771 3374 0 0
sp_supplier_order 6011 6011 0 0.00 5 25114 1211 0 0
srv_aggregate_perf_data 105 104 1 0.95 3015 263782 17861 0 1
srv_fill_mon_cache_memory 64 64 0 0.00 2121 137456 45945 0 0
srv_find_qd_qs_mism 1097043 1097043 0 0.00 0 18499 5 0 0
srv_make_invnt_saldo 656 656 0 0.00 32 39142 792 0 0
srv_make_money_saldo 848 848 0 0.00 0 18103 40 0 0
srv_recalc_idx_stat 4 4 0 0.00 174427 319032 220383 0 0
t$perf-abend:sp_add_invoice_to_stock 136 136 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 20 20 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 5340 5340 0 0.00 0 21938 140 0 0
t$perf-norm:sp_cancel_adding_invoice 491 491 0 0.00 0 20119 139 0 0
t$perf-norm:sp_cancel_client_order 1987 1987 0 0.00 0 18426 22 0 0
t$perf-norm:sp_cancel_customer_reserve 726 726 0 0.00 0 7794 27 0 0
t$perf-norm:sp_cancel_pay_from_customer 763 763 0 0.00 0 864 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 442 442 0 0.00 0 737 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 439 439 0 0.00 0 580 8 0 0
t$perf-norm:sp_cancel_supplier_order 458 458 0 0.00 0 27803 1365 0 0
t$perf-norm:sp_cancel_write_off 1100 1100 0 0.00 0 4852 11 0 0
t$perf-norm:sp_client_order 22839 22839 0 0.00 0 21231 19 0 0
t$perf-norm:sp_customer_reserve 1113 1113 0 0.00 0 1434 6 0 0
t$perf-norm:sp_pay_from_customer 7730 7730 0 0.00 0 13767 14 0 0
t$perf-norm:sp_pay_to_supplier 6658 6658 0 0.00 0 9203 11 0 0
t$perf-norm:sp_reserve_write_off 10624 10624 0 0.00 0 20083 13 0 0
t$perf-norm:sp_supplier_invoice 6063 6063 0 0.00 0 17066 26 0 0
t$perf-norm:sp_supplier_order 6011 6011 0 0.00 0 5585 15 0 0
t$perf-norm:srv_aggregate_perf_data 104 104 0 0.00 0 44 1 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 16 1 0 0
t$perf-norm:srv_make_invnt_saldo 656 656 0 0.00 0 1249 17 0 0
t$perf-norm:srv_make_money_saldo 848 848 0 0.00 0 3905 14 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 3 0 0
v_add_invoice_to_stock 10915 10643 272 2.49 0 9797 18 0 272
v_all_customers 41304 41304 0 0.00 0 43 0 0 0
v_all_suppliers 34230 34230 0 0.00 0 13 1 0 0
v_all_wares 45678 45678 0 0.00 0 105 1 0 0
v_cancel_adding_invoice 982 982 0 0.00 0 1279 8 0 0
v_cancel_client_order 3974 3974 0 0.00 0 5265 6 0 0
v_cancel_customer_prepayment 1526 1526 0 0.00 0 556 4 0 0
v_cancel_customer_reserve 1452 1452 0 0.00 0 2058 6 0 0
v_cancel_payment_to_supplier 884 884 0 0.00 0 3205 10 0 0
v_cancel_supplier_invoice 918 878 40 4.35 0 1607 15 0 40
v_cancel_supplier_order 916 916 0 0.00 0 2979 10 0 0
v_cancel_write_off 2200 2200 0 0.00 0 9804 15 0 0
v_max_id_clo_ord 6011 6011 0 0.00 0 14000 3 0 0
v_max_id_clo_res 1113 1113 0 0.00 0 6280 19 0 0
v_max_id_ord_sup 6062 6062 0 0.00 0 4804 4 0 0
v_max_non_paid_invoice 1615 1615 0 0.00 0 2529 4 0 0
v_max_non_paid_realizn 4130 4130 0 0.00 0 4477 4 0 0
v_min_id_clo_ord 6011 6011 0 0.00 0 2622 8 0 0
v_min_id_clo_res 1113 1113 0 0.00 0 2994 10 0 0
v_min_id_ord_sup 6062 6062 0 0.00 0 15641 23 0 0
v_min_non_paid_invoice 6657 6657 0 0.00 0 8872 16 0 0
v_min_non_paid_realizn 7730 7730 0 0.00 0 9234 12 0 0
v_our_firm 11574 11574 0 0.00 0 275 1 0 0
v_reserve_write_off 21246 21246 0 0.00 0 9850 5 0 0
x_lock_depdocs_on_canc_invoice 11987 11987 0 0.00 0 16422 97 0 0
x_lock_depdocs_on_canc_sup_ord 458 458 0 0.00 0 9983 248 0 0
x_make_qstorno_1000_1200 6011 6011 0 0.00 2 25086 1137 0 0
x_make_qstorno_1200_2000 6063 6063 0 0.00 4 102526 3210 0 0
x_make_qstorno_2100_3300 477738 477738 0 0.00 0 68009 148 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 24-03-2022 01:38:58 to 24-03-2022 01:39:02.

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:16 13 8192000000 8801434688 8813772800 5315664 0.064 2 1 2 0 5415264 56816 42784
23:09:17 30552 8192000000 9876980704 10182307840 961053184 10.499 100 91 182 0 991491904 5567536 24871184
23:10:51 22633 8192000000 9920933568 10239148032 1002904768 10.907 100 89 178 0 1034160080 8041024 23214288
23:12:19 26171 8192000000 9976569536 10309709824 1060055600 11.457 100 83 166 0 1090953888 9871520 21026768
23:13:51 9676 8192000000 10036171648 10390192128 1131251520 12.133 100 76 152 0 1160698576 11479168 17967888
23:15:03 5172 8192000000 10095840528 10460516352 1186363488 12.650 100 76 152 0 1214791584 11277632 17150464
23:16:11 3997 8192000000 10137319776 10507292672 1227530656 13.031 100 83 163 0 1258944608 13394464 18019488
23:17:17 2520 8192000000 10178828352 10554548224 1262230368 13.350 100 88 174 2 1295043312 13979920 18833024
23:18:21 2196 8192000000 10205803248 10587975680 1291775744 13.620 100 93 186 3 1325664704 13004064 20884896
23:19:24 32382 8192000000 10203388272 10610376704 1311806624 13.802 100 34 67 2 1328915760 8572272 8536864
23:20:57 13030 8192000000 10212025536 10629603328 1317987296 13.858 100 21 39 2 1328773312 6196656 4589360
23:22:17 7069 8192000000 10210010560 10631299072 1328461104 13.953 100 12 24 0 1336762336 5810800 2490432
23:23:24 47168 8192000000 10229907024 10657505280 1336715136 14.028 100 25 50 0 1349051472 6028688 6307648
23:25:43 5027 8192000000 10234686688 10660667392 1343726192 14.091 100 35 68 1 1358813568 7298736 7788640
23:26:49 8913 8192000000 10230712752 10653511680 1329977616 13.967 99 16 32 0 1339546528 5415168 4153744
23:27:59 5796 8192000000 10204273248 10618380288 1301184224 13.706 99 23 44 1 1312309504 5231264 5894016
23:29:05 60799 8192000000 10154759600 10553851904 1168286912 12.481 91 29 59 1 1184659184 10296352 6075920
23:31:09 4348 8192000000 10157013424 10552201216 1247908768 13.219 99 39 76 2 1264525504 6890416 9726320
23:32:16 4967 8192000000 10170144608 10567737344 1268145088 13.405 100 39 77 1 1283612976 5272688 10195200
23:33:23 67329 8192000000 10181289152 10589388800 1262259488 13.351 98 40 80 1 1277124960 5641360 9224112
23:35:31 6215 8192000000 10195269360 10610765824 1302217552 13.715 100 24 48 0 1315345792 7335200 5793040
23:36:39 6097 8192000000 10216019760 10626404352 1316734928 13.847 100 32 65 3 1329971840 5516240 7720672
23:37:45 9476 8192000000 10214342608 10629591040 1317931808 13.858 100 26 52 0 1329445424 5270736 6242880
23:38:56 89884 8192000000 10255632640 10669776896 1347182784 14.122 100 40 80 0 1366176848 10008784 8985280
23:41:31 16205 8192000000 10291731456 10707001344 1372955808 14.354 100 55 108 2 1403698096 18400880 12341408
23:42:52 20258 8192000000 10290640336 10713952256 1377086384 14.390 100 31 61 1 1402120272 16885424 8148464
23:44:15 9659 8192000000 10319791792 10743087104 1400579632 14.600 100 35 69 0 1432285520 23132592 8573296
23:45:28 8902 8192000000 10345363408 10768904192 1411847776 14.700 100 52 103 1 1451973232 27266928 12858528
23:46:44 9631 8192000000 10329525104 10751971328 1405086544 14.640 100 45 90 0 1444118976 27689616 11342816
23:48:01 6724 8192000000 10347669248 10765017088 1400562624 14.600 99 75 150 0 1450438048 31791120 18084304
23:49:11 2119 8192000000 10349610496 10756145152 1395298208 14.553 100 82 161 4 1453711488 38250032 20163248
23:50:16 3545 8192000000 10321960816 10730278912 1372910112 14.353 100 66 131 1 1427423296 37201568 17311616
23:51:30 6559 8192000000 10317952848 10733654016 1370066976 14.328 100 61 122 1 1428091344 42454816 15569552
23:52:40 3812 8192000000 10326470784 10725019648 1344752800 14.100 97 78 156 3 1405541008 41200960 19587248
23:53:47 3210 8192000000 10332728160 10734718976 1369059872 14.319 100 77 154 1 1427953792 38925280 19968640
23:54:54 5675 8192000000 10333180784 10746544128 1358263968 14.222 99 61 123 0 1420850816 45485152 17101696
23:56:04 5099 8192000000 10308181296 10729992192 1374622704 14.368 100 43 86 0 1417713504 31708576 11382224
23:57:10 11432 8192000000 10302633120 10728083456 1366132848 14.292 100 41 82 2 1409457584 32364880 10959856
23:58:26 19374 8192000000 10315354640 10746396672 1371705920 14.342 100 33 66 3 1419335824 38075344 9554560
23:59:46 19178 8192000000 10296755632 10744139776 1371122192 14.337 100 27 54 1 1407683472 29614224 6947056
00:01:07 37256 8192000000 10282823920 10725961728 1349710704 14.145 99 15 31 0 1374710576 20918560 4081312
00:02:44 33112 8192000000 10278194752 10708062208 1346472944 14.116 99 36 73 0 1366774640 11820512 8481184
00:04:27 67506 8192000000 10268470016 10705989632 1347821952 14.128 99 20 40 0 1364460416 11380960 5257504
00:06:39 104794 8192000000 10275036624 10719961088 1365325424 14.285 100 32 64 0 1381126016 7936336 7864256
00:09:24 85337 8192000000 10269128352 10713923584 1359873088 14.236 99 19 38 1 1375974880 11806208 4295584
00:12:00 49104 8192000000 10272394800 10730627072 1345186176 14.104 97 26 52 0 1364361472 13377568 5797728
00:14:45 31575 8192000000 10281897264 10739019776 1391249456 14.517 100 19 38 0 1402919600 7451296 4218848
00:16:18 35968 8192000000 10291065312 10746630144 1373695168 14.360 98 18 36 0 1389060544 10770000 4595376
00:17:56 29606 8192000000 10290291232 10752724992 1389052720 14.497 100 10 20 2 1405730224 14579264 2098240
00:20:07 72421 8192000000 10281505328 10742857728 1365317824 14.285 98 6 12 0 1378643648 11908528 1417296
00:22:28 21065 8192000000 10308252128 10758639616 1399220096 14.588 100 13 26 0 1409367360 7388896 2758368
00:23:51 26359 8192000000 10295755392 10740264960 1371322240 14.339 98 36 73 0 1395322848 15751040 8249568
00:25:17 72639 8192000000 10309518752 10761650176 1372109648 14.346 98 19 38 1 1398765104 22022080 4633376
00:27:47 47707 8192000000 10290975984 10758737920 1371811392 14.343 100 11 22 2 1402709040 27759328 3138320
00:30:17 20635 8192000000 10277615776 10713067520 1328656080 13.955 97 36 72 1 1346857632 10299808 7901744
00:31:42 33816 8192000000 10278780384 10720780288 1349430704 14.142 99 19 38 0 1363924336 10171632 4322000
00:33:34 107351 8192000000 10257642944 10694000640 1343448528 14.088 100 39 78 0 1362855264 10036880 9369856
00:36:27 78107 8192000000 10239330976 10696601600 1310589200 13.791 96 8 16 0 1321380288 8967264 1823824
00:39:07 23603 8192000000 10251267040 10701664256 1350998528 14.156 100 16 31 1 1361495376 7027968 3468880
00:40:31 35917 8192000000 10230499808 10666991616 1257404560 13.306 93 17 34 0 1269180320 8231904 3543856
00:42:07 55478 8192000000 10246031184 10690166784 1291078192 13.614 95 8 16 0 1300039360 7290416 1670752
00:44:03 54749 8192000000 10285004192 10735747072 1369702192 14.324 100 24 48 1 1393391136 18098160 5590784
00:46:03 61881 8192000000 10291898816 10735738880 1380411440 14.420 100 26 52 3 1404486240 17599504 6475296
00:48:05 30586 8192000000 10304131760 10745606144 1363727632 14.271 99 46 92 2 1392090528 18230000 10132896
00:49:37 79705 8192000000 10278164064 10734239744 1333696528 14.001 97 17 34 1 1354712768 17283232 3733008
00:51:56 27394 8192000000 10286503344 10713485312 1330318944 13.970 97 37 71 1 1348083264 10284512 7479808
00:53:24 35020 8192000000 10294619344 10732519424 1364951472 14.282 99 35 70 1 1385765088 13079216 7734400
00:54:59 83007 8192000000 10274088480 10716340224 1318023712 13.859 96 29 58 1 1331812240 6735616 7052912
00:57:36 45397 8192000000 10254263824 10704343040 1334369520 14.007 98 16 32 2 1352952576 14818640 3764416
01:00:00 73802 8192000000 10272134304 10720079872 1325084368 13.923 97 14 28 0 1344296368 16411952 2800048
01:02:14 96550 8192000000 10265714832 10729340928 1361535360 14.251 99 13 26 0 1377319136 12831072 2952704
01:04:51 32044 8192000000 10290247024 10732802048 1377271424 14.392 100 30 61 2 1391358416 6942784 7144208
01:06:23 54368 8192000000 10272563440 10724499456 1337358816 14.034 97 32 64 0 1352687680 8560384 6768480
01:08:18 47987 8192000000 10297126960 10743160832 1384065920 14.453 100 19 37 1 1395566288 7465360 4035008
01:10:09 40244 8192000000 10285919024 10724958208 1322060800 13.895 95 27 54 0 1338139040 10214656 5863584
01:11:51 93844 8192000000 10289129568 10734981120 1305029552 13.741 94 16 32 0 1319975760 11452080 3494128
01:14:29 63133 8192000000 10286068752 10719735808 1335180560 14.014 97 33 63 0 1350292896 8177888 6934448
01:16:35 63053 8192000000 10268483136 10712858624 1293384320 13.635 94 7 14 0 1303982992 9098160 1500512
01:18:39 137454 8192000000 10259923280 10704560128 1312616736 13.810 96 21 40 2 1328043024 10794000 4632288
01:22:02 53624 8192000000 10255637168 10714746880 1346119888 14.113 98 6 12 2 1360683936 12394672 2169376
01:23:56 71657 8192000000 10260353472 10721517568 1346544736 14.116 98 19 38 2 1358661728 7894176 4222816
01:26:31 70447 8192000000 10260241664 10711298048 1310985136 13.795 95 16 32 1 1326637616 11744336 3908144
01:28:42 103556 8192000000 10281559648 10732527616 1325193424 13.924 96 19 38 0 1344045280 14467680 4384176
01:31:33 85086 8192000000 10272690768 10736144384 1323171440 13.905 96 15 30 0 1346747456 20386608 3189408
01:34:02 45405 8192000000 10279363184 10725957632 1360725488 14.244 99 15 30 1 1377939056 14067984 3145584
01:35:56 46273 8192000000 10291533840 10742845440 1354117008 14.185 98 17 33 0 1372710064 14611696 3981360
01:37:46 33462 8192000000 9265442112 9539448832 411086496 4.778 30 14 28 0 417802512 3775904 2940112

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 24-03-2022 01:39:04 to 24-03-2022 01:39:19.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 136 v_add_invoice_to_stock 335544517
except 20 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 24-03-2022 01:39:20 to 24-03-2022 01:39:22.

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          258423
MON$OLDEST_ACTIVE               259151
MON$OLDEST_SNAPSHOT             259151
MON$NEXT_TRANSACTION            259151
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                       2031648
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33574 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33574 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33574 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33574 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 ---
:337: 
:338: mscs-test-161.budzdorov.ru    Thu Mar 24 01:38:22 2022
:339:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:340: 
:341: 
:342: mscs-test-161.budzdorov.ru    Thu Mar 24 01:38:22 2022
:343:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:344: 
:345: 
:346: mscs-test-161.budzdorov.ru    Thu Mar 24 01:38:24 2022
:347:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:348: 
:349: 
:350: mscs-test-161.budzdorov.ru    Thu Mar 24 01:38:24 2022
:351:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:352: 
--- end of diff output ---

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

24.03.22 01:39:23. 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-03-24 01:39:25 No severe PSQL-related problems occured
24.03.22 01:39:26. End of report.