Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 16.04.2022 04:37
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 16015616 kB
MemAvailable 31443220 kB
Buffers 20 kB
Cached 15587916 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 4.0.2
DB_NAME                         /home/bases/oltp40-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/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/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=3400
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/oltp40-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-oltp40
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-16 04:37:16
DTS_END                         2022-04-16 04:37:16.4880
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(68:17)
IP                              ::1/34514
TRN_ID                          224208
ATT_ID                          665
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: 9385.82 24677 1126298
customer order: creation 173.33 518 20800
customer order: refuse 14.25 375 1710
order to supplier: creation 45.38 1479 5445
order to supplier: removal 3.58 161645 429
invoice (draft): creation 45.97 3145 5516
invoice (draft): removal 53.73 891 6447
invoice accept: apply 41.58 25226 4989
invoice accept: cancel 48.93 1201 5872
customer reserve: creation 3895.38 237 467446
customer reserve: removal 4764.25 96 571710
realization accept: apply 85.08 296 10210
realization accept: cancel 85.01 167 10201
payment from customer: creation 58.91 196 7069
payment from customer: removal 5.78 107 694
payment to supplier: creation 48.98 209 5877
payment to supplier: removal 3.30 122 396
service: total inventory turnovers 3.82 4566 458
service: total monetary turnovers 7.70 383 924
service: aggregate perf. data 0.84 19392 101
service: refresh index statistics 0.03 273296 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 16-04-2022 04:38:01 to 16-04-2022 04:38:03.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8449
TEST_TIME 2 6735
TEST_TIME 3 5876
TEST_TIME 4 6040
TEST_TIME 5 6263
TEST_TIME 6 6340
TEST_TIME 7 6095
TEST_TIME 8 6123
TEST_TIME 9 6208
TEST_TIME 10 6511
TEST_TIME 11 6731
TEST_TIME 12 6780
TEST_TIME 13 6972
TEST_TIME 14 7047
TEST_TIME 15 7352
TEST_TIME 16 7325
TEST_TIME 17 7639
TEST_TIME 18 7866
TEST_TIME 19 8052
TEST_TIME 20 8036
TEST_TIME 21 8263
TEST_TIME 22 8344
TEST_TIME 23 8403
TEST_TIME 24 8577
TEST_TIME 25 8932
TEST_TIME 26 8977
TEST_TIME 27 8999
TEST_TIME 28 8790
TEST_TIME 29 9169
TEST_TIME 30 9458
TEST_TIME 31 9861
TEST_TIME 32 10020
TEST_TIME 33 10450
TEST_TIME 34 10904
TEST_TIME 35 11521
TEST_TIME 36 11338
TEST_TIME 37 11346
TEST_TIME 38 11250
TEST_TIME 39 11153
TEST_TIME 40 11051
TEST_TIME 41 10996
TEST_TIME 42 11088
TEST_TIME 43 10994
TEST_TIME 44 10863
TEST_TIME 45 10934
TEST_TIME 46 10833
TEST_TIME 47 10822
TEST_TIME 48 10766
TEST_TIME 49 10582
TEST_TIME 50 10390
TEST_TIME 51 10307
TEST_TIME 52 10345
TEST_TIME 53 10240
TEST_TIME 54 10172
TEST_TIME 55 10192
TEST_TIME 56 10132
TEST_TIME 57 10046
TEST_TIME 58 10072
TEST_TIME 59 10121
TEST_TIME 60 10065
TEST_TIME 61 10117
TEST_TIME 62 10030
TEST_TIME 63 10090
TEST_TIME 64 10091
TEST_TIME 65 10056
TEST_TIME 66 10002
TEST_TIME 67 10070
TEST_TIME 68 10042
TEST_TIME 69 9943
TEST_TIME 70 9937
TEST_TIME 71 9916
TEST_TIME 72 9881
TEST_TIME 73 9840
TEST_TIME 74 9909
TEST_TIME 75 9948
TEST_TIME 76 9908
TEST_TIME 77 9784
TEST_TIME 78 9762
TEST_TIME 79 9783
TEST_TIME 80 9708
TEST_TIME 81 9686
TEST_TIME 82 9676
TEST_TIME 83 9610
TEST_TIME 84 9579
TEST_TIME 85 9518
TEST_TIME 86 9542
TEST_TIME 87 9529
TEST_TIME 88 9556
TEST_TIME 89 9536
TEST_TIME 90 9490
TEST_TIME 91 9543
TEST_TIME 92 9587
TEST_TIME 93 9589
TEST_TIME 94 9567
TEST_TIME 95 9612
TEST_TIME 96 9610
TEST_TIME 97 9700
TEST_TIME 98 9669
TEST_TIME 99 9660
TEST_TIME 100 9717
TEST_TIME 101 9687
TEST_TIME 102 9741
TEST_TIME 103 9683
TEST_TIME 104 9618
TEST_TIME 105 9570
TEST_TIME 106 9548
TEST_TIME 107 9506
TEST_TIME 108 9481
TEST_TIME 109 9435
TEST_TIME 110 9381
TEST_TIME 111 9348
TEST_TIME 112 9430
TEST_TIME 113 9369
TEST_TIME 114 9416
TEST_TIME 115 9410
TEST_TIME 116 9439
TEST_TIME 117 9441
TEST_TIME 118 9410
TEST_TIME 119 9436
TEST_TIME 120 9415

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 16-04-2022 04:38:05 to 16-04-2022 04:38:08.

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 1557988 1557988 0 0.00 0 17014 13 0 0
doc_list_biud 1557974 1557974 0 0.00 0 26379 40 0 0
sp_add_invoice_to_stock 5135 4989 146 2.84 40 189412 25226 0 146
sp_cancel_adding_invoice 5872 5872 0 0.00 8 70003 1202 0 0
sp_cancel_client_order 1710 1710 0 0.00 6 14984 376 0 0
sp_cancel_customer_reserve 571710 571710 0 0.00 1 23341 97 0 0
sp_cancel_pay_from_customer 694 694 0 0.00 3 1970 108 0 0
sp_cancel_pay_to_supplier 396 396 0 0.00 4 3905 123 0 0
sp_cancel_supplier_invoice 6460 6447 13 0.20 3 26437 891 0 13
sp_cancel_supplier_order 429 429 0 0.00 12 1530697 161646 0 0
sp_cancel_write_off 10201 10201 0 0.00 2 10514 167 0 0
sp_client_order 20800 20800 0 0.00 6 18757 518 0 0
sp_customer_reserve 467446 467446 0 0.00 1 94885 238 0 0
sp_fill_shopping_cart 31759 31759 0 0.00 2 10512 81 0 0
sp_fill_shopping_cart_clo_res 467412 467412 0 0.00 0 10968 17 0 0
sp_get_clo_for_invoice 4943 4943 0 0.00 0 17327 272 0 0
sp_kill_cost_storno 17163 17163 0 0.00 0 13556 68 0 0
sp_kill_qstorno_ret_qs2qd 1160497 1160497 0 0.00 0 26376 41 0 0
sp_kill_qty_storno 611522 611522 0 0.00 0 26377 95 0 0
sp_lock_selected_doc 20952 20952 0 0.00 0 9636 8 0 0
sp_make_cost_storno 28099 28099 0 0.00 0 16901 86 0 0
sp_multiply_rows_for_pdistr 28099 28099 0 0.00 0 6537 40 0 0
sp_multiply_rows_for_qdistr 20799 20799 0 0.00 0 14631 199 0 0
sp_pay_from_customer 7069 7069 0 0.00 6 12896 197 0 0
sp_pay_to_supplier 5877 5877 0 0.00 6 16170 209 0 0
sp_payment_common 12946 12946 0 0.00 2 5778 46 0 0
sp_qd_handle_on_cancel_clo 1710 1710 0 0.00 1 14973 305 0 0
sp_qd_handle_on_invoice_upd_sts 10815 10815 0 0.00 0 13866 498 0 0
sp_qd_handle_on_reserve_upd_sts 10210 10210 0 0.00 0 18961 68 0 0
sp_reserve_write_off 10210 10210 0 0.00 8 19013 296 0 0
sp_supplier_invoice 5516 5516 0 0.00 9 93155 3145 0 0
sp_supplier_order 5445 5445 0 0.00 7 40979 1480 0 0
srv_aggregate_perf_data 102 101 1 0.98 2959 184286 19392 0 1
srv_fill_mon_cache_memory 70 70 0 0.00 808 164742 37578 0 0
srv_find_qd_qs_mism 1077750 1077750 0 0.00 0 12689 9 0 0
srv_make_invnt_saldo 458 458 0 0.00 74 71780 4567 0 0
srv_make_money_saldo 924 924 0 0.00 1 15231 383 0 0
srv_recalc_idx_stat 4 4 0 0.00 72354 443210 273296 0 0
t$perf-abend:sp_add_invoice_to_stock 146 146 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 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 4991 4991 0 0.00 1 13524 964 0 0
t$perf-norm:sp_cancel_adding_invoice 447 447 0 0.00 0 9789 566 0 0
t$perf-norm:sp_cancel_client_order 1710 1710 0 0.00 0 10605 35 0 0
t$perf-norm:sp_cancel_customer_reserve 704 704 0 0.00 0 680 28 0 0
t$perf-norm:sp_cancel_pay_from_customer 694 694 0 0.00 0 880 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 396 396 0 0.00 0 2823 27 0 0
t$perf-norm:sp_cancel_supplier_invoice 420 420 0 0.00 0 2204 43 0 0
t$perf-norm:sp_cancel_supplier_order 429 429 0 0.00 0 100531 6793 0 0
t$perf-norm:sp_cancel_write_off 1000 1000 0 0.00 0 1773 21 0 0
t$perf-norm:sp_client_order 20800 20800 0 0.00 0 13200 39 0 0
t$perf-norm:sp_customer_reserve 1082 1082 0 0.00 0 941 15 0 0
t$perf-norm:sp_pay_from_customer 7069 7069 0 0.00 0 5063 24 0 0
t$perf-norm:sp_pay_to_supplier 5877 5877 0 0.00 0 13233 25 0 0
t$perf-norm:sp_reserve_write_off 10210 10210 0 0.00 0 13728 29 0 0
t$perf-norm:sp_supplier_invoice 5516 5516 0 0.00 0 12944 32 0 0
t$perf-norm:sp_supplier_order 5445 5445 0 0.00 0 4222 36 0 0
t$perf-norm:srv_aggregate_perf_data 101 101 0 0.00 0 1694 38 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 2881 50 0 0
t$perf-norm:srv_make_invnt_saldo 458 458 0 0.00 0 1525 16 0 0
t$perf-norm:srv_make_money_saldo 924 924 0 0.00 0 1440 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 18 190 90 0 0
v_add_invoice_to_stock 10176 9884 292 2.86 0 7334 29 0 292
v_all_customers 38012 38012 0 0.00 0 64 4 0 0
v_all_suppliers 31004 31004 0 0.00 0 78 4 0 0
v_all_wares 41598 41598 0 0.00 0 61 4 0 0
v_cancel_adding_invoice 894 894 0 0.00 0 5484 33 0 0
v_cancel_client_order 3420 3420 0 0.00 0 4595 13 0 0
v_cancel_customer_prepayment 1388 1388 0 0.00 0 1936 14 0 0
v_cancel_customer_reserve 1408 1408 0 0.00 1 7437 22 0 0
v_cancel_payment_to_supplier 792 792 0 0.00 0 3813 15 0 0
v_cancel_supplier_invoice 866 840 26 3.00 0 2530 27 0 26
v_cancel_supplier_order 858 858 0 0.00 0 2735 20 0 0
v_cancel_write_off 2000 2000 0 0.00 0 9780 40 0 0
v_max_id_clo_ord 5445 5445 0 0.00 0 1991 5 0 0
v_max_id_clo_res 1082 1082 0 0.00 0 1331 12 0 0
v_max_id_ord_sup 5515 5515 0 0.00 0 4012 6 0 0
v_max_non_paid_invoice 1335 1335 0 0.00 0 828 9 0 0
v_max_non_paid_realizn 3608 3608 0 0.00 0 2917 9 0 0
v_min_id_clo_ord 5445 5445 0 0.00 0 2466 9 0 0
v_min_id_clo_res 1082 1082 0 0.00 0 7346 15 0 0
v_min_id_ord_sup 5515 5515 0 0.00 0 5974 9 0 0
v_min_non_paid_invoice 5877 5877 0 0.00 0 5756 14 0 0
v_min_non_paid_realizn 7069 7069 0 0.00 0 5387 19 0 0
v_our_firm 10508 10508 0 0.00 0 55 5 0 0
v_reserve_write_off 20420 20420 0 0.00 0 7346 14 0 0
x_lock_depdocs_on_canc_invoice 11899 11899 0 0.00 0 20643 130 0 0
x_lock_depdocs_on_canc_sup_ord 429 429 0 0.00 0 22674 402 0 0
x_make_qstorno_1000_1200 5445 5445 0 0.00 3 40935 1303 0 0
x_make_qstorno_1200_2000 5516 5516 0 0.00 3 89093 2941 0 0
x_make_qstorno_2100_3300 467445 467445 0 0.00 0 94880 209 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 16-04-2022 04:38:09 to 16-04-2022 04:38:13.

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
02:07:20 1 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:21 30580 8192000000 9733773360 10036056064 806265104 8.960 100 92 183 1 835986864 7861536 21860224
02:09:57 23678 8192000000 9777778640 10090065920 848574272 9.386 100 92 184 1 883710256 13015696 22120288
02:11:30 15117 8192000000 9808500368 10129756160 875903824 9.659 100 84 168 2 918558544 23313104 19341616
02:12:47 15126 8192000000 9878255568 10215321600 930842192 10.203 100 79 158 0 987009712 39027072 17140448
02:14:09 5600 8192000000 9933641408 10280460288 971848560 10.605 100 88 171 1 1049502400 59914848 17738992
02:15:17 3008 8192000000 9970498464 10325708800 1002592944 10.904 100 90 178 0 1089618192 68571760 18453488
02:16:24 903 8192000000 10019811760 10384175104 1022031152 11.092 100 91 181 0 1138814080 99031344 17751584
02:17:27 872 8192000000 10024505488 10395602944 1043720304 11.300 100 97 186 1 1146213536 83781056 18712176
02:18:29 1128 8192000000 10038510848 10419429376 1057757456 11.435 100 96 190 1 1161881728 84585216 19539056
02:19:31 1037 8192000000 10049022192 10426023936 1066135024 11.515 100 98 192 0 1169159216 83186000 19838192
02:20:33 915 8192000000 10065685712 10444820480 1075123664 11.601 100 93 179 2 1186287024 92760896 18402464
02:21:36 1463 8192000000 10055906576 10440499200 1084409184 11.689 100 94 178 1 1176881264 74843696 17628384
02:22:38 3010 8192000000 10020493440 10408595456 1080123568 11.649 100 74 147 0 1134997664 40158096 14716000
02:23:42 1579 8192000000 10050684576 10439950336 1086513344 11.709 100 86 160 6 1172506720 69915632 16077744
02:24:44 1146 8192000000 10060806880 10448228352 1093475376 11.776 100 97 186 4 1181840496 68649680 19715440
02:25:46 1470 8192000000 10054443360 10439598080 1097810352 11.817 100 99 197 1 1178824448 61752688 19261408
02:26:48 1219 8192000000 10074872768 10469724160 1103954272 11.875 100 96 176 0 1201371888 79389184 18028432
02:27:51 951 8192000000 10051569024 10444890112 1106742064 11.902 100 92 178 0 1174677968 48421552 19514352
02:28:53 1091 8192000000 10062564944 10450681856 1088813232 11.731 100 94 181 0 1184671008 78559936 17297840
02:29:55 1157 8192000000 10068483248 10459910144 1106857296 11.903 100 96 183 1 1187440592 61657952 18925344
02:30:57 1441 8192000000 10054064608 10440785920 1080592464 11.653 100 96 190 0 1175611040 75692880 19325696
02:31:59 1131 8192000000 10045915488 10426961920 1078715536 11.635 100 97 184 0 1166502064 68517632 19268896
02:33:01 1899 8192000000 9992629504 10369814528 1042992960 11.293 100 71 133 0 1112478512 55424208 14061344
02:34:04 1015 8192000000 9953615664 10321661952 1018671392 11.059 100 88 167 0 1076279568 39667056 17941120
02:35:06 1449 8192000000 10003864224 10389196800 1057531584 11.433 100 79 151 2 1128799072 55162800 16104688
02:36:09 2086 8192000000 9997955696 10382360576 1054752416 11.406 100 72 140 1 1114544736 45796336 13995984
02:37:12 10515 8192000000 10004324176 10394234880 1062546640 11.481 100 72 135 4 1140832160 64597888 13687632
02:38:33 14559 8192000000 10018602224 10405715968 1069365280 11.546 100 61 116 0 1141114144 60052480 11696384
02:39:52 2683 8192000000 10051901168 10444075008 1084072240 11.686 100 84 161 3 1171589552 70589312 16928000
02:40:59 3098 8192000000 10095334432 10478305280 1101511584 11.852 100 76 142 4 1209494832 94006320 13976928
02:42:13 11441 8192000000 10117889792 10527207424 1116581456 11.995 100 80 153 0 1235863616 103122912 16159248
02:43:30 7899 8192000000 10134201424 10530680832 1133804256 12.157 100 75 140 0 1256760320 108018048 14938016
02:44:42 1447 8192000000 10173571520 10569056256 1142291936 12.237 100 85 163 1 1283794416 123100464 18402016
02:45:49 1239 8192000000 10224018144 10622664704 1158758384 12.392 100 90 167 0 1337733792 160378560 18596848
02:46:56 1856 8192000000 10256232208 10660159488 1159965056 12.403 100 84 162 0 1368682896 190332464 18385376
02:48:01 1202 8192000000 10274926544 10673598464 1166585392 12.465 100 92 172 1 1386332592 200830944 18916256
02:49:06 1604 8192000000 10282395776 10686713856 1169035360 12.488 100 89 168 1 1396657696 209055168 18567168
02:50:11 981 8192000000 10321288160 10728517632 1174134000 12.535 100 91 174 1 1429464288 236641600 18688688
02:51:16 1628 8192000000 10348726640 10755432448 1178090544 12.572 100 92 181 0 1467981072 268538960 21351568
02:52:20 918 8192000000 10375539696 10798604288 1181025056 12.600 100 95 186 1 1486935424 284000480 21909888
02:53:24 941 8192000000 10389856432 10807480320 1185668736 12.643 100 96 183 1 1498218576 291441664 21108176
02:54:27 946 8192000000 10413176128 10828488704 1178991776 12.581 100 93 183 1 1525961872 325831248 21138848
02:55:31 802 8192000000 10423007616 10832310272 1187365440 12.659 100 98 192 0 1527751776 317946320 22440016
02:56:35 1631 8192000000 10423706240 10843197440 1171736912 12.513 99 97 185 0 1525288288 331424976 22126400
02:57:40 2595 8192000000 10486524560 10894516224 1189809712 12.682 100 90 179 0 1593400960 382239536 21351712
02:58:45 940 8192000000 10476825104 10913820672 1194017904 12.721 100 97 190 2 1580898400 361708016 25172480
02:59:48 842 8192000000 10523632688 10958737408 1209783552 12.867 100 87 170 2 1630565568 400367232 20414784
03:00:51 2697 8192000000 10524928784 10979561472 1211592464 12.884 100 84 162 1 1628239856 395965696 20681696
03:01:55 1184 8192000000 10530057008 11001737216 1202425584 12.799 100 98 187 4 1637302256 412406480 22470192
03:03:01 4930 8192000000 10538769392 10979418112 1205711952 12.829 100 58 113 0 1640096512 419667232 14717328
03:04:12 8881 8192000000 10543155312 10979119104 1193638464 12.717 100 49 99 0 1651051632 444309520 13103648
03:05:31 15296 8192000000 10620826448 11057278976 1188565696 12.670 100 41 82 1 1715857648 515834656 11457296
03:06:54 22848 8192000000 10529627136 11047403520 1176983216 12.562 99 45 89 1 1655928752 467590496 11355040
03:08:26 28125 8192000000 10495787648 11020460032 1164845360 12.449 99 26 52 0 1574705440 402900400 6959680
03:10:02 35208 8192000000 10358544224 10914082816 1157806224 12.383 100 22 44 1 1525715968 362231024 5678720
03:12:50 46004 8192000000 10082517792 10511429632 1104732176 11.883 96 29 55 0 1141518576 31492720 5293680
03:14:39 18733 8192000000 10094663328 10557009920 1146029664 12.272 100 19 38 1 1208807904 58557328 4220912
03:16:14 91171 8192000000 10124546800 10602647552 1133150240 12.151 99 26 50 0 1176938448 38769232 5018976
03:18:46 139915 8192000000 10064340800 10518884352 1108941936 11.922 96 11 22 0 1143829840 32828608 2059296
03:22:29 156710 8192000000 10068736048 10562232320 1054214224 11.401 92 7 14 1 1085691936 29756640 1721072
03:26:37 66326 8192000000 10071748944 10498564096 1107491456 11.909 98 36 67 0 1141306368 26449376 7365536
03:28:47 45889 8192000000 10064499824 10515005440 1095029696 11.790 97 50 91 2 1164651712 59192784 10429232
03:30:36 39821 8192000000 10094698160 10536988672 1138711824 12.203 100 36 66 2 1196167776 50795440 6660512
03:32:18 52115 8192000000 10109183280 10571948032 1105340944 11.888 97 28 52 3 1185550576 74568816 5640816
03:34:17 18071 8192000000 10172683008 10591936512 1128958656 12.112 99 69 133 2 1256507808 112531584 15017568
03:35:36 74074 8192000000 10149767760 10645528576 1122604976 12.052 99 26 52 1 1269076496 139628160 6843360
03:37:50 33129 8192000000 10082837296 10579931136 1095951216 11.799 98 13 26 0 1187446880 88157200 3338464
03:39:38 45144 8192000000 10076786448 10556788736 1102835072 11.865 97 13 27 0 1168587296 62970416 2781808
03:41:24 56900 8192000000 10076049632 10516725760 1100384096 11.841 97 19 37 1 1138724400 34341968 3998336
03:43:27 36798 8192000000 10132224992 10582806528 1108977648 11.923 97 56 108 4 1209363424 89283632 11102144
03:45:04 72654 8192000000 10069551040 10500771840 1094990320 11.790 96 23 45 0 1125590800 25610768 4989712
03:47:28 24391 8192000000 10082597200 10517966848 1128497232 12.107 99 32 62 1 1176743504 41876624 6369648
03:49:01 19437 8192000000 10101505296 10529796096 1129021312 12.112 99 54 105 0 1189380800 49261120 11098368
03:50:22 25329 8192000000 10110042320 10547003392 1124743424 12.072 99 36 71 1 1181416992 49299104 7374464
03:51:49 77456 8192000000 10097983536 10622218240 1106133600 11.896 97 15 30 0 1178187104 68868128 3185376
03:54:11 80391 8192000000 10113763152 10579881984 1126671792 12.090 99 37 70 2 1199518464 64562112 8284560
03:56:34 46018 8192000000 10053810576 10498912256 1123844656 12.063 99 34 67 1 1157218224 26404352 6969216
03:58:21 29359 8192000000 10112886560 10569007104 1120238768 12.029 99 44 88 3 1210284080 80979728 9065584
03:59:58 65472 8192000000 10101149648 10543423488 1113329280 11.964 98 17 34 0 1184584464 67010800 4244384
04:02:06 78547 8192000000 10099337568 10606170112 1119358176 12.021 98 19 38 0 1236106352 112342848 4405328
04:04:24 40943 8192000000 10113472480 10583535616 1137729344 12.194 100 14 28 2 1212495632 71656784 3109504
04:06:07 47801 8192000000 10114257456 10565722112 1131620624 12.137 99 22 44 0 1195774048 59136928 5016496
04:07:55 58256 8192000000 10165628528 10636124160 1161075664 12.413 100 22 44 0 1285107248 119382352 4649232
04:10:01 53295 8192000000 10097843152 10544566272 1154904064 12.356 100 19 38 1 1199245040 40472032 3868944
04:12:05 63712 8192000000 10136749760 10617544704 1109763696 11.930 96 21 42 1 1216961184 102684864 4512624
04:14:17 68382 8192000000 10077779712 10517504000 1122504768 12.051 98 23 46 0 1168055328 40973472 4577088
04:16:30 33852 8192000000 10118025888 10564374528 1127748736 12.100 99 44 84 2 1200806656 64754928 8302992
04:18:04 56537 8192000000 10111145072 10552320000 1080684992 11.654 95 22 44 0 1132538320 47363760 4489568
04:20:04 164741 8192000000 10058561872 10507694080 1092670656 11.768 97 22 44 1 1128685504 31270064 4744784
04:23:51 68538 8192000000 10082226736 10539474944 1127677360 12.099 100 15 30 0 1183524944 52317680 3529904
04:26:02 89793 8192000000 10125957728 10576269312 1093845888 11.779 96 11 22 0 1167744256 71384176 2514192
04:28:32 54449 8192000000 10146941808 10596970496 1130436448 12.125 99 19 37 2 1244402976 109591648 4374880
04:30:42 78244 8192000000 10086653776 10542018560 1098967472 11.828 97 33 62 2 1198186368 92207872 7011024
04:33:12 37155 8192000000 10100450800 10534604800 1102391120 11.860 98 35 70 1 1200977728 90533680 8052928
04:34:51 50111 8192000000 10115033152 10565296128 1103991120 11.875 98 19 38 0 1183860464 76012656 3856688
04:36:44 35594 8192000000 9354386496 9690034176 467609600 5.399 42 22 42 2 507027280 35389344 4028336

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 16-04-2022 04:38:15 to 16-04-2022 04:38:31.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 146 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 16-04-2022 04:38:32 to 16-04-2022 04:38:34.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          224106
MON$OLDEST_ACTIVE               224728
MON$OLDEST_SNAPSHOT             224728
MON$NEXT_TRANSACTION            224728
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-30 07:30:08.6030 GMT
MON$PAGES                       2091008
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             785
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2761 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2761 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2761 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2761 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.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 ---
:141: 
:142: mscs-test-161.budzdorov.ru    Sat Apr 16 04:37:22 2022
:143:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:144: 
:145: 
:146: mscs-test-161.budzdorov.ru    Sat Apr 16 04:37:25 2022
:147:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:148: 
:149: 
:150: mscs-test-161.budzdorov.ru    Sat Apr 16 04:37:26 2022
:151:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:152: 
--- end of diff output ---

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

16.04.22 04:38:35. There are 3 logs in /var/tmp/logs-oltp40 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-16 04:38:37 No severe PSQL-related problems occured
16.04.22 04:38:39. End of report.