Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 21.12.2022 10: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 14837544 kB
MemAvailable 30077072 kB
Buffers 0 kB
Cached 16847796 kB
SwapCached 3288 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.3
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-12-21 10:37:23
DTS_END                         2022-12-21 10:37:23.6310
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/54084
TRN_ID                          226576
ATT_ID                          658
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: 8909.26 22424 1069111
customer order: creation 176.63 451 21196
customer order: refuse 15.49 396 1859
order to supplier: creation 46.97 1280 5636
order to supplier: removal 3.36 154914 403
invoice (draft): creation 46.97 2979 5636
invoice (draft): removal 47.33 954 5680
invoice accept: apply 42.28 28473 5073
invoice accept: cancel 42.86 1310 5143
customer reserve: creation 3962.55 272 475506
customer reserve: removal 4228.41 97 507409
realization accept: apply 86.43 272 10371
realization accept: cancel 77.75 165 9330
payment from customer: creation 60.39 180 7247
payment from customer: removal 5.48 127 658
payment to supplier: creation 49.88 197 5985
payment to supplier: removal 3.50 108 420
service: total inventory turnovers 4.48 3669 538
service: total monetary turnovers 7.63 316 916
service: aggregate perf. data 0.84 16137 101
service: refresh index statistics 0.03 236189 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 21-12-2022 10:38:37 to 21-12-2022 10:38:40.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4405
TEST_TIME 2 4527
TEST_TIME 3 4827
TEST_TIME 4 4875
TEST_TIME 5 5459
TEST_TIME 6 5740
TEST_TIME 7 5798
TEST_TIME 8 5964
TEST_TIME 9 5938
TEST_TIME 10 6082
TEST_TIME 11 6541
TEST_TIME 12 6594
TEST_TIME 13 6593
TEST_TIME 14 6889
TEST_TIME 15 6890
TEST_TIME 16 6975
TEST_TIME 17 6955
TEST_TIME 18 7033
TEST_TIME 19 7261
TEST_TIME 20 7385
TEST_TIME 21 7505
TEST_TIME 22 7659
TEST_TIME 23 7914
TEST_TIME 24 8036
TEST_TIME 25 8629
TEST_TIME 26 8971
TEST_TIME 27 9138
TEST_TIME 28 9392
TEST_TIME 29 9572
TEST_TIME 30 9997
TEST_TIME 31 10777
TEST_TIME 32 10727
TEST_TIME 33 10921
TEST_TIME 34 10876
TEST_TIME 35 10912
TEST_TIME 36 10804
TEST_TIME 37 10610
TEST_TIME 38 10446
TEST_TIME 39 10272
TEST_TIME 40 10334
TEST_TIME 41 10379
TEST_TIME 42 10230
TEST_TIME 43 10146
TEST_TIME 44 10083
TEST_TIME 45 9971
TEST_TIME 46 10031
TEST_TIME 47 9974
TEST_TIME 48 9983
TEST_TIME 49 9942
TEST_TIME 50 9872
TEST_TIME 51 9837
TEST_TIME 52 9953
TEST_TIME 53 9836
TEST_TIME 54 9818
TEST_TIME 55 9683
TEST_TIME 56 9602
TEST_TIME 57 9516
TEST_TIME 58 9554
TEST_TIME 59 9558
TEST_TIME 60 9556
TEST_TIME 61 9593
TEST_TIME 62 9606
TEST_TIME 63 9632
TEST_TIME 64 9615
TEST_TIME 65 9634
TEST_TIME 66 9771
TEST_TIME 67 9743
TEST_TIME 68 9722
TEST_TIME 69 9729
TEST_TIME 70 9719
TEST_TIME 71 9721
TEST_TIME 72 9686
TEST_TIME 73 9592
TEST_TIME 74 9553
TEST_TIME 75 9534
TEST_TIME 76 9518
TEST_TIME 77 9462
TEST_TIME 78 9394
TEST_TIME 79 9395
TEST_TIME 80 9360
TEST_TIME 81 9330
TEST_TIME 82 9299
TEST_TIME 83 9339
TEST_TIME 84 9336
TEST_TIME 85 9337
TEST_TIME 86 9320
TEST_TIME 87 9319
TEST_TIME 88 9391
TEST_TIME 89 9403
TEST_TIME 90 9373
TEST_TIME 91 9360
TEST_TIME 92 9322
TEST_TIME 93 9260
TEST_TIME 94 9276
TEST_TIME 95 9347
TEST_TIME 96 9271
TEST_TIME 97 9256
TEST_TIME 98 9222
TEST_TIME 99 9215
TEST_TIME 100 9202
TEST_TIME 101 9205
TEST_TIME 102 9193
TEST_TIME 103 9188
TEST_TIME 104 9176
TEST_TIME 105 9168
TEST_TIME 106 9132
TEST_TIME 107 9161
TEST_TIME 108 9285
TEST_TIME 109 9281
TEST_TIME 110 9260
TEST_TIME 111 9194
TEST_TIME 112 9150
TEST_TIME 113 9127
TEST_TIME 114 9128
TEST_TIME 115 9077
TEST_TIME 116 9043
TEST_TIME 117 9001
TEST_TIME 118 8989
TEST_TIME 119 8988
TEST_TIME 120 8937

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 21-12-2022 10:38:41 to 21-12-2022 10:38:44.

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 1507517 1507517 0 0.00 0 22756 15 0 0
doc_list_biud 1507509 1507509 0 0.00 0 49272 38 0 0
sp_add_invoice_to_stock 5218 5073 145 2.77 27 237822 28474 0 145
sp_cancel_adding_invoice 5143 5143 0 0.00 5 77322 1310 0 0
sp_cancel_client_order 1859 1859 0 0.00 6 20627 396 0 0
sp_cancel_customer_reserve 507409 507409 0 0.00 1 49274 98 0 0
sp_cancel_pay_from_customer 658 658 0 0.00 4 5480 127 0 0
sp_cancel_pay_to_supplier 420 420 0 0.00 3 3407 108 0 0
sp_cancel_supplier_invoice 5692 5680 12 0.21 2 36362 955 0 12
sp_cancel_supplier_order 403 403 0 0.00 7 1332432 154915 0 0
sp_cancel_write_off 9330 9330 0 0.00 2 22124 165 0 0
sp_client_order 21196 21196 0 0.00 6 34822 452 0 0
sp_customer_reserve 475506 475506 0 0.00 1 50948 273 0 0
sp_fill_shopping_cart 32464 32464 0 0.00 1 14944 76 0 0
sp_fill_shopping_cart_clo_res 475473 475473 0 0.00 0 19393 19 0 0
sp_get_clo_for_invoice 5028 5028 0 0.00 0 18643 271 0 0
sp_kill_cost_storno 15551 15551 0 0.00 0 11315 73 0 0
sp_kill_qstorno_ret_qs2qd 1030231 1030231 0 0.00 0 49268 42 0 0
sp_kill_qty_storno 545221 545221 0 0.00 0 49272 97 0 0
sp_lock_selected_doc 21363 21363 0 0.00 0 5989 7 0 0
sp_make_cost_storno 28629 28629 0 0.00 0 15387 74 0 0
sp_multiply_rows_for_pdistr 28629 28629 0 0.00 0 9356 40 0 0
sp_multiply_rows_for_qdistr 21196 21196 0 0.00 0 12036 174 0 0
sp_pay_from_customer 7247 7247 0 0.00 5 12785 181 0 0
sp_pay_to_supplier 5985 5985 0 0.00 6 12123 198 0 0
sp_payment_common 13232 13232 0 0.00 2 9235 46 0 0
sp_qd_handle_on_cancel_clo 1859 1859 0 0.00 0 20615 324 0 0
sp_qd_handle_on_invoice_upd_sts 10169 10169 0 0.00 0 33826 512 0 0
sp_qd_handle_on_reserve_upd_sts 10371 10371 0 0.00 0 17819 59 0 0
sp_reserve_write_off 10371 10371 0 0.00 8 17880 273 0 0
sp_supplier_invoice 5636 5636 0 0.00 12 110413 2979 0 0
sp_supplier_order 5636 5636 0 0.00 7 47221 1281 0 0
srv_aggregate_perf_data 102 101 1 0.98 2699 167409 16137 0 1
srv_fill_mon_cache_memory 70 70 0 0.00 707 114735 35277 0 0
srv_find_qd_qs_mism 1019222 1019222 0 0.00 0 22754 12 0 0
srv_make_invnt_saldo 538 538 0 0.00 61 96738 3670 0 0
srv_make_money_saldo 916 916 0 0.00 1 7893 317 0 0
srv_recalc_idx_stat 4 4 0 0.00 139425 360670 236189 0 0
t$perf-abend:sp_add_invoice_to_stock 145 145 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 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 5076 5076 0 0.00 1 13680 870 0 0
t$perf-norm:sp_cancel_adding_invoice 434 434 0 0.00 1 13444 560 0 0
t$perf-norm:sp_cancel_client_order 1859 1859 0 0.00 0 15827 38 0 0
t$perf-norm:sp_cancel_customer_reserve 710 710 0 0.00 0 577 24 0 0
t$perf-norm:sp_cancel_pay_from_customer 658 658 0 0.00 0 582 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 420 420 0 0.00 0 497 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 442 442 0 0.00 0 427 23 0 0
t$perf-norm:sp_cancel_supplier_order 403 403 0 0.00 0 113183 6468 0 0
t$perf-norm:sp_cancel_write_off 1041 1041 0 0.00 0 3491 21 0 0
t$perf-norm:sp_client_order 21196 21196 0 0.00 0 16445 37 0 0
t$perf-norm:sp_customer_reserve 1063 1063 0 0.00 0 2321 18 0 0
t$perf-norm:sp_pay_from_customer 7247 7247 0 0.00 0 3946 18 0 0
t$perf-norm:sp_pay_to_supplier 5985 5985 0 0.00 0 15947 28 0 0
t$perf-norm:sp_reserve_write_off 10371 10371 0 0.00 0 10634 21 0 0
t$perf-norm:sp_supplier_invoice 5636 5636 0 0.00 0 4326 30 0 0
t$perf-norm:sp_supplier_order 5636 5636 0 0.00 0 8520 32 0 0
t$perf-norm:srv_aggregate_perf_data 101 101 0 0.00 0 607 23 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 157 6 0 0
t$perf-norm:srv_make_invnt_saldo 538 538 0 0.00 0 752 15 0 0
t$perf-norm:srv_make_money_saldo 916 916 0 0.00 0 1360 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 20 49 35 0 0
v_add_invoice_to_stock 10337 10048 289 2.79 0 7026 25 0 289
v_all_customers 38630 38630 0 0.00 0 131 4 0 0
v_all_suppliers 31634 31634 0 0.00 0 86 4 0 0
v_all_wares 42392 42392 0 0.00 0 161 4 0 0
v_cancel_adding_invoice 868 868 0 0.00 0 2758 22 0 0
v_cancel_client_order 3718 3718 0 0.00 0 2752 12 0 0
v_cancel_customer_prepayment 1316 1316 0 0.00 0 4321 15 0 0
v_cancel_customer_reserve 1420 1420 0 0.00 0 1717 12 0 0
v_cancel_payment_to_supplier 840 840 0 0.00 0 2739 15 0 0
v_cancel_supplier_invoice 908 884 24 2.64 0 377 21 0 24
v_cancel_supplier_order 806 806 0 0.00 0 3000 14 0 0
v_cancel_write_off 2082 2082 0 0.00 0 4713 28 0 0
v_max_id_clo_ord 5635 5635 0 0.00 0 1175 5 0 0
v_max_id_clo_res 1063 1063 0 0.00 0 1324 12 0 0
v_max_id_ord_sup 5633 5633 0 0.00 0 2844 6 0 0
v_max_non_paid_invoice 1436 1436 0 0.00 0 1936 9 0 0
v_max_non_paid_realizn 3773 3773 0 0.00 0 7232 9 0 0
v_min_id_clo_ord 5635 5635 0 0.00 0 2852 8 0 0
v_min_id_clo_res 1063 1063 0 0.00 0 11020 26 0 0
v_min_id_ord_sup 5633 5633 0 0.00 0 4003 9 0 0
v_min_non_paid_invoice 5985 5985 0 0.00 0 4815 16 0 0
v_min_non_paid_realizn 7247 7247 0 0.00 0 9229 19 0 0
v_our_firm 10710 10710 0 0.00 0 73 5 0 0
v_reserve_write_off 20742 20742 0 0.00 0 5917 13 0 0
x_lock_depdocs_on_canc_invoice 10381 10381 0 0.00 0 10765 121 0 0
x_lock_depdocs_on_canc_sup_ord 403 403 0 0.00 0 10659 359 0 0
x_make_qstorno_1000_1200 5636 5636 0 0.00 3 47212 1124 0 0
x_make_qstorno_1200_2000 5636 5636 0 0.00 5 110402 2780 0 0
x_make_qstorno_2100_3300 475505 475505 0 0.00 0 50937 241 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 21-12-2022 10:38:45 to 21-12-2022 10:38:50.

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
08:07:27 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
08:08:28 37056 8192000000 9726205952 10027716608 800151312 8.898 100 90 180 0 829695648 7951344 21592992
08:10:21 17198 8192000000 9763699808 10076315648 838720896 9.287 100 83 164 0 871605136 13291984 19592256
08:11:45 13089 8192000000 9825212688 10150952960 887242880 9.772 100 84 168 0 933068976 27651296 18174800
08:13:03 7046 8192000000 9890089856 10225274880 932461776 10.219 100 79 154 0 1003054048 53774928 16817344
08:14:13 3847 8192000000 9935778848 10285682688 971404544 10.600 100 81 160 0 1054650448 66361216 16884688
08:15:18 1821 8192000000 9957122992 10313170944 1006241296 10.939 100 90 168 1 1067133072 44613792 16277984
08:16:22 1310 8192000000 10011493184 10375991296 1029762816 11.166 100 90 178 0 1133434880 85522544 18149520
08:17:25 811 8192000000 10015948992 10390441984 1049403520 11.355 100 98 194 0 1137211808 69075344 18732944
08:18:27 1166 8192000000 10043010800 10420191232 1064314432 11.498 100 97 186 1 1166911632 83246304 19350896
08:19:29 4006 8192000000 10045408176 10426171392 1067132000 11.525 100 89 172 0 1166673248 82611424 16929824
08:20:34 1084 8192000000 10069616784 10454446080 1079084448 11.639 100 99 187 3 1190727184 92899984 18742752
08:21:36 1455 8192000000 10075694992 10466672640 1086496880 11.709 100 96 183 2 1203954576 97916944 19540752
08:22:39 1140 8192000000 10055191728 10438766592 1087316256 11.717 100 98 182 2 1170420336 64876336 18227744
08:23:41 1031 8192000000 10071777552 10464260096 1094635472 11.787 100 89 175 1 1195694688 83466704 17592512
08:24:43 975 8192000000 10080711056 10475782144 1096816336 11.807 100 95 180 1 1202839520 87790256 18232928
08:25:45 1144 8192000000 10073611504 10469793792 1100513984 11.843 100 91 174 0 1196524720 79824416 16186320
08:26:47 732 8192000000 10046905280 10437877760 1104706096 11.882 100 87 170 0 1167721296 45741712 17273488
08:27:48 2905 8192000000 10047223152 10439053312 1097172464 11.811 100 76 145 5 1167875744 54460576 16242704
08:28:52 1122 8192000000 10035352784 10422181888 1087534512 11.719 100 92 172 2 1158945760 52938416 18472832
08:29:54 1228 8192000000 10021664384 10402566144 1070868128 11.560 100 92 172 1 1139825552 51532384 17425040
08:30:57 1315 8192000000 10023838400 10403561472 1056326768 11.421 99 94 177 1 1144236672 69455584 18454320
08:31:59 967 8192000000 10033753680 10418540544 1073536688 11.586 100 84 162 0 1155963584 65514448 16912448
08:33:01 979 8192000000 9983273648 10348703744 1017344128 11.046 99 95 180 3 1102860464 65773360 19742976
08:34:04 918 8192000000 9984134608 10354716672 1024570464 11.116 100 84 153 2 1110544624 70105552 15868608
08:35:06 1181 8192000000 9979381216 10350370816 1030584352 11.174 99 98 187 1 1103887200 54381440 18921408
08:36:11 1461 8192000000 9985928112 10361020416 1044330976 11.306 100 78 150 3 1104447808 44514032 15602800
08:37:13 1650 8192000000 10001131824 10381942784 1045151184 11.314 100 78 149 1 1125289440 66239808 13898448
08:38:18 18944 8192000000 10027520160 10409988096 1062053888 11.476 100 66 132 0 1140834320 65733408 13047024
08:39:49 27384 8192000000 10054359328 10451529728 1076207536 11.611 100 66 124 0 1193113552 103983360 12922656
08:41:19 2175 8192000000 10089089472 10476941312 1095481584 11.795 100 72 142 0 1205494192 94878144 15134464
08:42:24 10466 8192000000 10108270576 10508546048 1106003376 11.895 100 62 117 0 1229666704 110827968 12835360
08:43:43 21113 8192000000 10123265504 10521698304 1114585680 11.976 100 67 131 0 1234033888 105944352 13503856
08:45:13 2842 8192000000 10146751712 10545975296 1128200768 12.104 100 71 136 2 1258953280 115792960 14959552
08:46:19 1878 8192000000 10200189424 10597683200 1145022720 12.263 100 89 170 1 1314039872 150697600 18319552
08:47:24 1796 8192000000 10234512400 10631462912 1158896368 12.393 100 84 159 1 1338720816 162088480 17735968
08:48:29 1206 8192000000 10265507280 10676449280 1163611552 12.437 100 89 177 1 1370350736 186730784 20008400
08:49:33 815 8192000000 10293171952 10689036288 1166224080 12.462 100 88 165 2 1405534016 221174832 18135104
08:50:38 1455 8192000000 10311757792 10717663232 1166729216 12.466 100 76 145 0 1421943712 239032624 16181872
08:51:43 952 8192000000 10352395424 10747953152 1167608272 12.474 100 97 186 0 1458982432 270143392 21230768
08:52:47 1070 8192000000 10383981184 10786631680 1184573744 12.633 100 96 188 2 1488562672 281864560 22124368
08:53:51 2287 8192000000 10427984848 10836209664 1197171504 12.750 100 83 165 0 1531174432 315056800 18946128
08:55:00 2108 8192000000 10460563216 10882187264 1189270848 12.677 100 90 169 1 1571238576 360638288 21329440
08:56:07 1677 8192000000 10484890752 10912636928 1200261008 12.779 100 94 173 0 1593894320 372181152 21452160
08:57:12 701 8192000000 10516961696 10940223488 1199039104 12.767 100 97 186 1 1627944656 407251664 21653888
08:58:14 895 8192000000 10488683808 10925461504 1173429280 12.529 99 94 187 0 1595527760 399308976 22789504
08:59:16 838 8192000000 10530195120 10964197376 1182100208 12.610 100 95 178 0 1627568272 423586448 21881616
09:00:20 1451 8192000000 10545240576 10977652736 1185902192 12.645 100 90 176 1 1649126368 442187840 21036336
09:01:24 1465 8192000000 10534120128 11030106112 1186246608 12.648 100 85 159 2 1638421536 431682560 20492368
09:02:31 6345 8192000000 10514147440 10980708352 1192016224 12.702 100 50 100 0 1632998240 427880272 13101744
09:03:47 15865 8192000000 10470692112 10935017472 1174564112 12.539 100 38 76 0 1590138752 404844064 10730576
09:05:16 17538 8192000000 10457777488 10903572480 1164570656 12.446 100 36 73 0 1560284416 386383168 9330592
09:06:34 13849 8192000000 10369327552 10894000128 1157999200 12.385 100 22 44 0 1464697040 301077840 5620000
09:07:49 38966 8192000000 10203512096 10715938816 1125588144 12.080 99 16 32 0 1339196832 209402880 4205808
09:09:48 7029 8192000000 10104063456 10539986944 1130351232 12.125 99 65 124 3 1204718560 60000736 14366592
09:11:07 70863 8192000000 10094650912 10570477568 1140262192 12.218 99 9 18 0 1202350112 60215248 1872672
09:13:36 34867 8192000000 10086284208 10513903616 1128230976 12.105 98 45 85 1 1148113392 10671344 9211072
09:15:29 42592 8192000000 10100757280 10541772800 1123618176 12.061 98 46 87 0 1194302160 60841104 9842880
09:17:23 64971 8192000000 10098197984 10574135296 1126836880 12.092 99 25 47 1 1237682016 105700832 5144304
09:19:40 14022 8192000000 10065329952 10512584704 1113255712 11.963 98 23 40 1 1143739808 25337344 5146752
09:21:20 56437 8192000000 10140507840 10578935808 1142599360 12.240 100 54 108 0 1227250800 71813920 12837520
09:23:23 26174 8192000000 10158415696 10641100800 1151576224 12.324 100 15 30 0 1259660560 104613280 3471056
09:25:39 37306 8192000000 10096273536 10564272128 1127320880 12.096 99 15 28 1 1199249072 68312336 3615856
09:27:22 70734 8192000000 10120300832 10615513088 1120534800 12.032 98 14 28 0 1232996880 109468448 2993632
09:29:38 89696 8192000000 10065336144 10526109696 1124820208 12.073 99 19 38 0 1166992624 38111472 4060944
09:32:08 70636 8192000000 10035034784 10511880192 1075304672 11.603 95 3 6 0 1109094224 33530816 258736
09:34:21 56424 8192000000 10094592848 10566721536 1123030896 12.056 99 38 76 1 1213338800 81655568 8652336
09:36:26 28303 8192000000 10107460096 10565521408 1132818624 12.148 100 49 97 1 1184686064 42664336 9203104
09:37:55 14073 8192000000 10091259728 10531827712 1121222144 12.039 99 45 90 1 1185767040 55153600 9391296
09:39:16 28745 8192000000 10084160528 10537246720 1125289504 12.077 99 41 81 2 1194827296 61531776 8006016
09:40:49 38739 8192000000 10086383024 10533543936 1076266432 11.612 95 47 94 1 1152787520 66586736 9934352
09:42:30 27443 8192000000 10114100640 10545188864 1110176160 11.934 99 35 68 1 1193065824 76214976 6674688
09:44:00 31171 8192000000 10088808016 10524446720 1126475728 12.088 100 40 80 0 1193898352 58819328 8603296
09:45:32 16171 8192000000 10144918736 10581704704 1132624896 12.146 100 62 124 1 1246055408 101174432 12256080
09:48:35 108420 8192000000 10055948400 10534903808 1119119072 12.019 100 24 48 1 1168472960 44792384 4561504
09:51:25 16935 8192000000 10094366352 10526425088 1124060832 12.065 100 59 117 2 1186168464 50472336 11635296
09:52:43 68082 8192000000 10053851008 10566328320 1120846368 12.035 100 6 12 0 1200609056 77855408 1907280
09:54:52 101278 8192000000 10094710256 10564796416 1120951776 12.036 99 25 51 1 1169894848 43511136 5431936
09:57:34 51363 8192000000 10078668928 10542731264 1135678704 12.175 100 20 38 0 1161116992 20762864 4675424
09:59:26 73180 8192000000 10097361248 10559787008 1101421216 11.851 97 26 52 0 1167171760 60144736 5605808
10:01:55 66720 8192000000 10083850528 10516094976 1111506368 11.947 98 29 56 0 1158935232 41150560 6278304
10:04:12 53899 8192000000 10129098208 10586161152 1112181824 11.953 99 32 64 1 1195752480 77422144 6148512
10:06:10 35034 8192000000 10063932832 10516197376 1097079648 11.810 98 33 62 0 1156625824 53363968 6182208
10:07:47 24779 8192000000 10122033488 10551623680 1114178064 11.972 99 41 80 0 1196472432 74369200 7925168
10:09:20 96299 8192000000 10207025056 10667069440 1137854880 12.195 100 24 48 0 1298282912 155095584 5332448
10:12:14 76237 8192000000 10070043616 10535067648 1124474384 12.069 99 15 30 0 1205267264 77512224 3280656
10:14:30 114695 8192000000 10080509872 10530271232 1079827328 11.646 95 11 22 0 1115699632 33759920 2112384
10:17:29 79087 8192000000 10067339632 10532978688 1078996368 11.638 95 23 46 0 1132006608 47988736 5021504
10:19:50 9048 8192000000 10072287744 10502782976 1125947472 12.083 100 54 106 0 1171244368 34623840 10673056
10:20:59 24760 8192000000 10095176512 10526306304 1118308208 12.011 99 37 74 0 1173447328 47565344 7573776
10:22:28 47852 8192000000 10115391520 10556620800 1122169856 12.047 99 29 58 0 1200508304 71728880 6609568
10:24:16 42706 8192000000 10179793984 10651975680 1137826832 12.195 99 54 107 1 1283055392 134073168 11155392
10:26:03 78571 8192000000 10057085360 10501238784 1076486400 11.614 95 27 52 0 1104202736 22784352 4931984
10:28:33 78826 8192000000 10035697216 10498867200 1118348544 12.011 99 12 24 0 1162325184 41717744 2258896
10:30:56 12525 8192000000 10078731184 10517045248 1121922112 12.045 99 41 80 0 1173029344 42657664 8449568
10:32:13 72954 8192000000 10072729040 10535550976 1112526224 11.956 98 11 22 0 1154387504 39490336 2370944
10:34:36 101888 8192000000 10065046704 10541879296 1086100320 11.706 97 15 30 0 1155287184 65524624 3662240
10:37:18 11471 8192000000 9204004704 9506709504 356699296 4.172 31 14 28 0 392116336 32455536 2961504

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 21-12-2022 10:38:52 to 21-12-2022 10:39:08.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 145 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 21-12-2022 10:39:09 to 21-12-2022 10:39:11.

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          228430
MON$OLDEST_ACTIVE               228431
MON$OLDEST_SNAPSHOT             228431
MON$NEXT_TRANSACTION            228431
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                       2050976
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             791
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2885 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2885 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2885 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2885 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 ---
:295: 
:296: mscs-test-161.budzdorov.ru    Wed Dec 21 10:37:33 2022
:297:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:298: 
--- end of diff output ---

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

21.12.22 10:39:12. 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-12-21 10:39:14 No severe PSQL-related problems occured
21.12.22 10:39:15. End of report.