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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16650168 kB
MemAvailable 31520316 kB
Buffers 0 kB
Cached 15092796 kB
SwapCached 2688 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-04-17 19:38:10
DTS_END                         2022-04-17 19:38:10.8560
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(131:22) ==> SP_CANCEL_ADDING_INVOICE(33:5) ==> SP_ADD_INVOICE_TO_STOCK(69:14) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/44408
TRN_ID                          237967
ATT_ID                          633
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: 9218.28 20296 1106193
customer order: creation 176.48 357 21178
customer order: refuse 15.04 319 1805
order to supplier: creation 46.61 1258 5593
order to supplier: removal 3.65 87413 438
invoice (draft): creation 46.43 3256 5571
invoice (draft): removal 52.53 795 6304
invoice accept: apply 42.55 17130 5106
invoice accept: cancel 47.11 742 5653
customer reserve: creation 3861.25 175 463350
customer reserve: removal 4620.33 53 554439
realization accept: apply 84.66 159 10159
realization accept: cancel 89.13 110 10696
payment from customer: creation 60.45 99 7254
payment from customer: removal 5.40 76 648
payment to supplier: creation 50.36 109 6043
payment to supplier: removal 3.68 64 441
service: total inventory turnovers 4.91 880 589
service: total monetary turnovers 6.81 18 817
service: aggregate perf. data 0.88 16423 105
service: refresh index statistics 0.03 276485 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 17-04-2022 19:39:48 to 17-04-2022 19:39:50.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4340
TEST_TIME 2 4873
TEST_TIME 3 4853
TEST_TIME 4 4627
TEST_TIME 5 4495
TEST_TIME 6 5148
TEST_TIME 7 5004
TEST_TIME 8 4970
TEST_TIME 9 4892
TEST_TIME 10 5277
TEST_TIME 11 5363
TEST_TIME 12 5630
TEST_TIME 13 5920
TEST_TIME 14 6960
TEST_TIME 15 7467
TEST_TIME 16 7736
TEST_TIME 17 8320
TEST_TIME 18 8650
TEST_TIME 19 8683
TEST_TIME 20 8940
TEST_TIME 21 9714
TEST_TIME 22 10418
TEST_TIME 23 10591
TEST_TIME 24 11056
TEST_TIME 25 10877
TEST_TIME 26 10839
TEST_TIME 27 11050
TEST_TIME 28 10965
TEST_TIME 29 10842
TEST_TIME 30 10669
TEST_TIME 31 10606
TEST_TIME 32 10582
TEST_TIME 33 10536
TEST_TIME 34 10428
TEST_TIME 35 10311
TEST_TIME 36 10272
TEST_TIME 37 10188
TEST_TIME 38 10246
TEST_TIME 39 10245
TEST_TIME 40 10115
TEST_TIME 41 10001
TEST_TIME 42 10026
TEST_TIME 43 9903
TEST_TIME 44 9881
TEST_TIME 45 9884
TEST_TIME 46 9764
TEST_TIME 47 9796
TEST_TIME 48 9740
TEST_TIME 49 9800
TEST_TIME 50 9805
TEST_TIME 51 9821
TEST_TIME 52 9884
TEST_TIME 53 9907
TEST_TIME 54 9888
TEST_TIME 55 9930
TEST_TIME 56 9891
TEST_TIME 57 9923
TEST_TIME 58 9913
TEST_TIME 59 9846
TEST_TIME 60 9921
TEST_TIME 61 9914
TEST_TIME 62 9792
TEST_TIME 63 9753
TEST_TIME 64 9646
TEST_TIME 65 9705
TEST_TIME 66 9627
TEST_TIME 67 9539
TEST_TIME 68 9483
TEST_TIME 69 9553
TEST_TIME 70 9521
TEST_TIME 71 9530
TEST_TIME 72 9513
TEST_TIME 73 9507
TEST_TIME 74 9582
TEST_TIME 75 9553
TEST_TIME 76 9574
TEST_TIME 77 9516
TEST_TIME 78 9547
TEST_TIME 79 9512
TEST_TIME 80 9572
TEST_TIME 81 9681
TEST_TIME 82 9617
TEST_TIME 83 9608
TEST_TIME 84 9694
TEST_TIME 85 9587
TEST_TIME 86 9591
TEST_TIME 87 9526
TEST_TIME 88 9505
TEST_TIME 89 9427
TEST_TIME 90 9449
TEST_TIME 91 9426
TEST_TIME 92 9389
TEST_TIME 93 9401
TEST_TIME 94 9412
TEST_TIME 95 9392
TEST_TIME 96 9421
TEST_TIME 97 9417
TEST_TIME 98 9461
TEST_TIME 99 9453
TEST_TIME 100 9522
TEST_TIME 101 9490
TEST_TIME 102 9491
TEST_TIME 103 9566
TEST_TIME 104 9602
TEST_TIME 105 9586
TEST_TIME 106 9556
TEST_TIME 107 9582
TEST_TIME 108 9627
TEST_TIME 109 9543
TEST_TIME 110 9516
TEST_TIME 111 9489
TEST_TIME 112 9455
TEST_TIME 113 9427
TEST_TIME 114 9381
TEST_TIME 115 9379
TEST_TIME 116 9350
TEST_TIME 117 9294
TEST_TIME 118 9286
TEST_TIME 119 9287
TEST_TIME 120 9226

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 17-04-2022 19:39:51 to 17-04-2022 19:39:55.

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 1536018 1536018 0 0.00 0 21391 6 0 0
doc_list_biud 1536018 1536018 0 0.00 0 42278 25 0 0
sp_add_invoice_to_stock 5192 5106 86 1.65 39 169794 17130 0 86
sp_cancel_adding_invoice 5653 5653 0 0.00 5 61465 742 0 0
sp_cancel_client_order 1805 1805 0 0.00 5 11256 319 0 0
sp_cancel_customer_reserve 554439 554439 0 0.00 1 42280 54 0 0
sp_cancel_pay_from_customer 648 648 0 0.00 3 5545 76 0 0
sp_cancel_pay_to_supplier 441 441 0 0.00 3 3860 65 0 0
sp_cancel_supplier_invoice 6307 6304 3 0.04 2 23065 796 0 3
sp_cancel_supplier_order 438 438 0 0.00 9 968039 87413 0 0
sp_cancel_write_off 10696 10696 0 0.00 1 11496 111 0 0
sp_client_order 21178 21178 0 0.00 3 38509 357 0 0
sp_customer_reserve 463350 463350 0 0.00 0 88879 175 0 0
sp_fill_shopping_cart 32339 32339 0 0.00 1 14251 36 0 0
sp_fill_shopping_cart_clo_res 463332 463332 0 0.00 0 11926 12 0 0
sp_get_clo_for_invoice 5088 5088 0 0.00 0 32563 247 0 0
sp_kill_cost_storno 17438 17438 0 0.00 0 16094 55 0 0
sp_kill_qstorno_ret_qs2qd 1126316 1126316 0 0.00 0 42276 30 0 0
sp_kill_qty_storno 594582 594582 0 0.00 0 42278 64 0 0
sp_lock_selected_doc 21153 21153 0 0.00 0 6725 4 0 0
sp_make_cost_storno 28544 28544 0 0.00 0 11746 43 0 0
sp_multiply_rows_for_pdistr 28544 28544 0 0.00 0 8579 26 0 0
sp_multiply_rows_for_qdistr 21178 21178 0 0.00 0 38502 154 0 0
sp_pay_from_customer 7254 7254 0 0.00 4 12984 100 0 0
sp_pay_to_supplier 6043 6043 0 0.00 4 10225 110 0 0
sp_payment_common 13297 13297 0 0.00 2 10059 20 0 0
sp_qd_handle_on_cancel_clo 1805 1805 0 0.00 0 11246 298 0 0
sp_qd_handle_on_invoice_upd_sts 10741 10741 0 0.00 0 22246 287 0 0
sp_qd_handle_on_reserve_upd_sts 10159 10159 0 0.00 0 26805 57 0 0
sp_reserve_write_off 10159 10159 0 0.00 6 26940 159 0 0
sp_supplier_invoice 5571 5571 0 0.00 6 101325 3257 0 0
sp_supplier_order 5593 5593 0 0.00 5 25936 1258 0 0
srv_aggregate_perf_data 106 105 1 0.94 2301 246801 16423 0 1
srv_fill_mon_cache_memory 65 65 0 0.00 2775 152562 44566 0 0
srv_find_qd_qs_mism 1058117 1058117 0 0.00 0 21390 5 0 0
srv_make_invnt_saldo 589 589 0 0.00 39 39911 881 0 0
srv_make_money_saldo 817 817 0 0.00 1 391 18 0 0
srv_recalc_idx_stat 4 4 0 0.00 180925 351759 276485 0 0
t$perf-abend:sp_add_invoice_to_stock 86 86 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 3 3 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 5106 5106 0 0.00 0 11821 147 0 0
t$perf-norm:sp_cancel_adding_invoice 405 405 0 0.00 1 3307 104 0 0
t$perf-norm:sp_cancel_client_order 1805 1805 0 0.00 0 1102 10 0 0
t$perf-norm:sp_cancel_customer_reserve 720 720 0 0.00 0 1650 7 0 0
t$perf-norm:sp_cancel_pay_from_customer 648 648 0 0.00 0 1356 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 441 441 0 0.00 0 497 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 402 402 0 0.00 0 517 7 0 0
t$perf-norm:sp_cancel_supplier_order 438 438 0 0.00 0 22634 1451 0 0
t$perf-norm:sp_cancel_write_off 1047 1047 0 0.00 0 1653 6 0 0
t$perf-norm:sp_client_order 21178 21178 0 0.00 0 12502 15 0 0
t$perf-norm:sp_customer_reserve 1039 1039 0 0.00 0 966 6 0 0
t$perf-norm:sp_pay_from_customer 7254 7254 0 0.00 0 7190 8 0 0
t$perf-norm:sp_pay_to_supplier 6043 6043 0 0.00 0 5290 7 0 0
t$perf-norm:sp_reserve_write_off 10159 10159 0 0.00 0 25674 14 0 0
t$perf-norm:sp_supplier_invoice 5571 5571 0 0.00 0 4571 15 0 0
t$perf-norm:sp_supplier_order 5593 5593 0 0.00 0 22274 17 0 0
t$perf-norm:srv_aggregate_perf_data 105 105 0 0.00 0 317 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 5 1 0 0
t$perf-norm:srv_make_invnt_saldo 589 589 0 0.00 0 5603 26 0 0
t$perf-norm:srv_make_money_saldo 817 817 0 0.00 0 840 9 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 2 0 0
v_add_invoice_to_stock 10348 10176 172 1.66 0 5367 17 0 172
v_all_customers 38828 38828 0 0.00 0 13 0 0 0
v_all_suppliers 31066 31066 0 0.00 0 51 1 0 0
v_all_wares 42356 42356 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 810 810 0 0.00 0 5591 13 0 0
v_cancel_client_order 3610 3610 0 0.00 0 3744 6 0 0
v_cancel_customer_prepayment 1296 1296 0 0.00 0 5528 13 0 0
v_cancel_customer_reserve 1440 1440 0 0.00 0 4085 7 0 0
v_cancel_payment_to_supplier 882 882 0 0.00 0 3745 9 0 0
v_cancel_supplier_invoice 810 804 6 0.74 0 3009 14 0 6
v_cancel_supplier_order 876 876 0 0.00 0 787 3 0 0
v_cancel_write_off 2094 2094 0 0.00 0 5823 24 0 0
v_max_id_clo_ord 5593 5593 0 0.00 0 332 1 0 0
v_max_id_clo_res 1039 1039 0 0.00 0 2760 15 0 0
v_max_id_ord_sup 5568 5568 0 0.00 0 6278 6 0 0
v_max_non_paid_invoice 1670 1670 0 0.00 0 2658 4 0 0
v_max_non_paid_realizn 3684 3684 0 0.00 0 2280 4 0 0
v_min_id_clo_ord 5593 5593 0 0.00 0 2207 5 0 0
v_min_id_clo_res 1039 1039 0 0.00 0 2231 6 0 0
v_min_id_ord_sup 5567 5567 0 0.00 0 3191 5 0 0
v_min_non_paid_invoice 6043 6043 0 0.00 0 10054 14 0 0
v_min_non_paid_realizn 7254 7254 0 0.00 0 6450 14 0 0
v_our_firm 10668 10668 0 0.00 0 13 1 0 0
v_reserve_write_off 20318 20318 0 0.00 0 5905 7 0 0
x_lock_depdocs_on_canc_invoice 11555 11555 0 0.00 0 26771 111 0 0
x_lock_depdocs_on_canc_sup_ord 438 438 0 0.00 0 14877 307 0 0
x_make_qstorno_1000_1200 5593 5593 0 0.00 2 25912 1191 0 0
x_make_qstorno_1200_2000 5571 5571 0 0.00 3 100712 3116 0 0
x_make_qstorno_2100_3300 463350 463350 0 0.00 0 88877 161 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 17-04-2022 19:39:56 to 17-04-2022 19:40:00.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
17:08:14 3 8192000000 8800818576 8812728320 6849456 0.083 2 1 2 0 6985536 61552 74528
17:09:15 33411 8192000000 9920702336 10230394880 986598480 10.748 100 83 166 2 1015158768 6251056 22309232
17:10:56 22906 8192000000 9948722176 10273075200 1034683120 11.214 100 87 173 2 1065549424 8429616 22436688
17:12:26 10873 8192000000 9990336752 10323017728 1067968528 11.533 100 83 166 0 1099425008 9861504 21594976
17:13:46 9511 8192000000 10038255744 10390179840 1132759072 12.147 100 78 156 0 1162137664 10713136 18665456
17:14:59 8975 8192000000 10084133968 10448080896 1176611280 12.559 100 79 157 2 1205259776 11335008 17313488
17:16:12 6085 8192000000 10139891920 10507628544 1227711408 13.033 100 89 178 0 1261801072 14721984 19367680
17:17:20 3785 8192000000 10166315392 10538737664 1248456448 13.224 100 84 169 0 1282212128 16134528 17621152
17:18:26 2202 8192000000 10205894816 10583740416 1284565936 13.555 100 94 188 0 1319105072 13675456 20863680
17:19:30 1639 8192000000 10222215760 10603364352 1300769824 13.702 100 95 190 2 1337183968 14794480 21619664
17:20:34 3846 8192000000 10212939472 10618839040 1316125040 13.842 100 49 97 0 1336171552 9926544 10119968
17:21:38 8717 8192000000 10205347056 10625265664 1324955376 13.922 100 24 48 2 1336538608 6206416 5376816
17:22:49 46424 8192000000 10215008448 10640302080 1330565936 13.972 100 19 38 1 1340576432 5867472 4143024
17:24:36 4996 8192000000 10231665920 10648150016 1335823168 14.020 100 29 58 1 1348798624 6350064 6625392
17:25:46 7938 8192000000 10226060160 10648342528 1327853872 13.948 99 28 56 0 1341084000 5752928 7477200
17:26:57 66488 8192000000 10241845488 10652323840 1327186656 13.942 99 36 72 3 1340073840 4715824 8171360
17:29:11 4977 8192000000 10202449280 10615250944 1297916512 13.676 99 32 64 0 1311947472 6615696 7415264
17:30:17 6824 8192000000 10164465088 10568306688 1239299216 13.140 96 32 61 1 1256524704 10093456 7132032
17:31:24 4983 8192000000 10139443056 10537021440 1233426880 13.086 99 21 41 1 1243807888 5684080 4696928
17:32:33 39261 8192000000 10121338720 10524573696 1198736320 12.765 96 25 50 0 1214131136 9897488 5497328
17:34:13 6162 8192000000 10157087072 10562129920 1266294080 13.388 100 27 54 1 1278488800 5927680 6267040
17:35:23 10321 8192000000 10185097792 10590347264 1288090448 13.587 100 24 48 2 1299745456 5781664 5873344
17:36:33 11196 8192000000 10199003840 10603225088 1303638512 13.728 100 32 64 0 1316800640 5052272 8109856
17:37:50 31870 8192000000 10217223920 10625970176 1314934768 13.831 100 28 56 2 1327090176 5504304 6651104
17:39:28 20042 8192000000 10250483440 10662404096 1341278704 14.069 100 47 94 0 1367320448 15530880 10510864
17:40:50 30902 8192000000 10271545280 10685513728 1346235136 14.114 99 40 81 1 1372897920 17324528 9338256
17:42:35 22036 8192000000 10294728848 10712702976 1375725616 14.378 100 51 98 4 1409115968 21393376 11996976
17:44:02 6949 8192000000 10312357264 10723921920 1383532496 14.448 100 66 132 0 1424790112 26233360 15024256
17:45:18 26977 8192000000 10325614272 10742419456 1392771552 14.531 100 46 92 1 1426781872 22889392 11120928
17:46:50 7043 8192000000 10339000144 10761150464 1409957296 14.684 100 57 115 0 1452267056 28536864 13772896
17:48:03 3888 8192000000 10367534736 10783690752 1419247920 14.766 100 59 114 1 1469810976 36892512 13670544
17:49:14 6006 8192000000 10378273024 10791514112 1430651536 14.867 100 77 150 1 1485810944 37019680 18139728
17:50:22 2771 8192000000 10357590976 10773073920 1407253424 14.660 100 69 136 1 1467718640 43095696 17369520
17:51:29 5127 8192000000 10377674992 10797076480 1417977696 14.755 100 68 136 1 1484396384 48630096 17788592
17:52:40 3269 8192000000 10383824656 10801045504 1410035024 14.684 99 77 154 2 1474793584 45564208 19194352
17:53:47 7265 8192000000 10348063376 10769203200 1393341168 14.536 99 53 106 2 1448148224 40316608 14490448
17:54:57 10673 8192000000 10356458560 10788298752 1409933296 14.683 100 39 77 1 1460928528 40636416 10358816
17:56:18 20821 8192000000 10334642608 10769272832 1389138864 14.498 99 31 62 0 1432156928 34165568 8852496
17:57:42 11930 8192000000 10341582368 10774097920 1385684112 14.467 99 38 74 0 1438412544 42043200 10685232
17:58:56 12793 8192000000 10301758784 10735341568 1335503168 14.017 98 35 70 1 1389457136 43475104 10478864
18:00:13 25640 8192000000 10263425824 10701451264 1308727280 13.775 96 27 52 0 1338301824 22958608 6615936
18:01:49 27788 8192000000 10273206304 10717990912 1334858288 14.011 98 23 46 0 1363950672 22974128 6118256
18:03:18 62249 8192000000 10257542160 10698203136 1296704352 13.665 95 22 44 2 1316294128 14015680 5574096
18:05:26 77796 8192000000 10264094976 10707734528 1336349456 14.024 98 10 20 2 1358733392 19786400 2597536
18:07:44 64173 8192000000 10287880848 10719105024 1338272000 14.042 97 21 40 1 1354625728 12356432 3997296
18:10:05 89949 8192000000 10286762384 10720370688 1352973280 14.174 99 36 69 2 1370291088 9226176 8091632
18:12:49 71908 8192000000 10279660496 10724552704 1354099776 14.184 98 24 49 2 1369936576 9866272 5970528
18:15:04 26014 8192000000 10297614144 10742505472 1375250112 14.374 99 23 46 0 1391700368 10894000 5556256
18:16:35 15636 8192000000 10307283456 10761330688 1395536064 14.555 100 40 80 0 1419274048 14530304 9207680
18:17:51 15769 8192000000 10292528000 10745638912 1382601024 14.440 100 17 32 0 1404197216 17589136 4007056
18:19:18 37277 8192000000 10300332416 10751111168 1382370208 14.438 99 11 22 0 1403704272 19098256 2235808
18:20:56 77806 8192000000 10305156320 10761285632 1380350912 14.420 99 20 40 3 1399487024 14383280 4752832
18:23:25 55919 8192000000 10297797504 10755903488 1383693616 14.450 99 22 44 0 1400600720 11972208 4934896
18:26:09 60789 8192000000 10285798672 10744594432 1367535024 14.305 98 17 34 0 1384242288 12818064 3889200
18:28:10 24783 8192000000 10306558656 10753327104 1380465184 14.421 99 45 90 1 1403619104 11964512 11189408
18:29:36 39315 8192000000 10292070192 10746146816 1366332032 14.294 98 17 34 1 1379847520 9613920 3901568
18:31:17 31206 8192000000 10295622128 10739130368 1369865456 14.326 99 30 60 0 1387512288 11045792 6601040
18:32:56 43629 8192000000 10298538208 10749644800 1369898560 14.326 98 31 61 1 1387633568 11151232 6583776
18:34:44 26012 8192000000 10296652800 10736082944 1368724272 14.316 99 47 94 2 1391016704 11756416 10536016
18:36:12 49866 8192000000 10278079344 10713993216 1322437152 13.899 96 29 58 0 1337265056 8454192 6373712
18:38:02 62368 8192000000 10270171824 10709991424 1317209632 13.851 96 31 58 2 1333861824 9927088 6725104
18:40:13 101309 8192000000 10287368720 10733666304 1377448512 14.394 100 18 33 1 1387319152 6452096 3418544
18:42:57 14995 8192000000 10286964880 10728857600 1368487120 14.313 100 49 98 0 1398252672 18357376 11408176
18:44:18 46219 8192000000 10277899024 10731933696 1364614016 14.279 99 6 12 1 1379882400 13956096 1312288
18:46:04 81311 8192000000 10264717488 10713088000 1303221888 13.725 95 21 41 0 1324279264 16356736 4700640
18:48:33 62401 8192000000 10270964688 10733207552 1360269152 14.240 100 10 20 0 1376829552 14395680 2164720
18:50:37 49253 8192000000 10286078832 10727120896 1361753200 14.253 99 28 56 1 1379341232 10925920 6662112
18:52:28 152562 8192000000 10245422800 10693488640 1205727488 12.829 88 5 10 0 1218516016 11922304 866224
18:56:17 28646 8192000000 10282015312 10722295808 1361924912 14.255 100 34 69 0 1378753552 9201440 7627200
18:57:49 34341 8192000000 10302684880 10744164352 1376193632 14.383 100 25 50 0 1394815968 13071456 5550880
19:00:19 30057 8192000000 10312532944 10742845440 1340201632 14.059 97 32 62 1 1355997360 9114496 6681232
19:01:54 124199 8192000000 10289922224 10735763456 1372439600 14.349 99 10 20 0 1395553792 20931200 2182992
19:05:13 24541 8192000000 10291620624 10756964352 1384705088 14.459 100 18 36 1 1400404288 11984480 3714720
19:06:41 57886 8192000000 10285756688 10745982976 1386999792 14.479 100 8 16 0 1401967760 13196368 1771600
19:08:39 64692 8192000000 10292237344 10749718528 1376740784 14.387 99 6 12 0 1387197840 9305744 1151312
19:11:06 62753 8192000000 10292343120 10748125184 1368526288 14.314 98 19 38 0 1385835440 13124976 4184176
19:13:09 52205 8192000000 10295625440 10735366144 1319841664 13.875 95 12 24 0 1331593904 9169952 2582288
19:15:04 63453 8192000000 10290425824 10739331072 1378508672 14.403 100 15 30 2 1394067152 12382352 3176128
19:17:10 72752 8192000000 10306309296 10756423680 1367543760 14.305 98 28 56 0 1386918464 12842848 6531856
19:19:23 33687 8192000000 10290004768 10728476672 1358968336 14.228 98 33 66 0 1375188912 9256304 6964272
19:21:00 68206 8192000000 10311521600 10774388736 1392194832 14.525 100 16 32 0 1412258752 16610672 3453248
19:23:12 107334 8192000000 10298706560 10741972992 1339410496 14.052 96 13 26 0 1353535760 11705552 2419712
19:26:03 26979 8192000000 10294123168 10754744320 1357557248 14.215 98 10 20 1 1380187184 20260400 2369536
19:27:39 98233 8192000000 10292094032 10744303616 1372171104 14.346 99 30 60 0 1389834256 10888064 6775088
19:30:17 32499 8192000000 10281980576 10742571008 1383813520 14.451 100 10 20 0 1396004240 9964160 2226560
19:31:55 63681 8192000000 10280986464 10741444608 1365329408 14.285 98 6 12 1 1375003088 8388752 1284928
19:34:01 63489 8192000000 10232153360 10678386688 1281569248 13.527 93 20 41 0 1293186064 6573584 5043232
19:36:06 62764 8192000000 10236813184 10684768256 1312015648 13.804 97 12 24 0 1327042912 12412656 2614608

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 17-04-2022 19:40:02 to 17-04-2022 19:40:16.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 86 v_add_invoice_to_stock 335544517
except 3 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 17-04-2022 19:40:17 to 17-04-2022 19:40:19.

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          237966
MON$OLDEST_ACTIVE               241001
MON$OLDEST_SNAPSHOT             241001
MON$NEXT_TRANSACTION            241001
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                       2023296
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.33577 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33577 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33577 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33577 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 ---
:579: 
:580: mscs-test-161.budzdorov.ru    Sun Apr 17 19:38:16 2022
:581:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:582: 
--- end of diff output ---

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

17.04.22 19:40:20. 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-04-17 19:40:23 No severe PSQL-related problems occured
17.04.22 19:40:24. End of report.