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

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-12-15 19:38:32
DTS_END                         2022-12-15 19:38:32.7050
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/40318
TRN_ID                          240717
ATT_ID                          640
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: 9245.33 21926 1109439
customer order: creation 172.30 352 20676
customer order: refuse 15.05 316 1806
order to supplier: creation 47.02 1119 5642
order to supplier: removal 3.55 88884 426
invoice (draft): creation 46.26 2969 5551
invoice (draft): removal 54.09 713 6491
invoice accept: apply 41.33 16443 4959
invoice accept: cancel 48.16 673 5779
customer reserve: creation 3798.79 165 455855
customer reserve: removal 4723.33 52 566800
realization accept: apply 84.53 149 10143
realization accept: cancel 79.99 116 9599
payment from customer: creation 59.49 113 7139
payment from customer: removal 5.92 83 710
payment to supplier: creation 49.63 125 5955
payment to supplier: removal 3.43 55 412
service: total inventory turnovers 5.01 1052 601
service: total monetary turnovers 6.54 53 785
service: aggregate perf. data 0.88 20439 106
service: refresh index statistics 0.03 304658 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 15-12-2022 19:39:19 to 15-12-2022 19:39:22.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2165
TEST_TIME 2 3632
TEST_TIME 3 3577
TEST_TIME 4 3677
TEST_TIME 5 3954
TEST_TIME 6 4479
TEST_TIME 7 4218
TEST_TIME 8 4349
TEST_TIME 9 4453
TEST_TIME 10 5658
TEST_TIME 11 5629
TEST_TIME 12 5767
TEST_TIME 13 6674
TEST_TIME 14 6861
TEST_TIME 15 6850
TEST_TIME 16 7464
TEST_TIME 17 7723
TEST_TIME 18 7697
TEST_TIME 19 7705
TEST_TIME 20 7978
TEST_TIME 21 8665
TEST_TIME 22 9136
TEST_TIME 23 9274
TEST_TIME 24 9573
TEST_TIME 25 10493
TEST_TIME 26 10536
TEST_TIME 27 10468
TEST_TIME 28 10374
TEST_TIME 29 10362
TEST_TIME 30 10398
TEST_TIME 31 10292
TEST_TIME 32 10039
TEST_TIME 33 10132
TEST_TIME 34 9953
TEST_TIME 35 9920
TEST_TIME 36 9825
TEST_TIME 37 9780
TEST_TIME 38 9865
TEST_TIME 39 9818
TEST_TIME 40 9665
TEST_TIME 41 9649
TEST_TIME 42 9633
TEST_TIME 43 9681
TEST_TIME 44 9644
TEST_TIME 45 9549
TEST_TIME 46 9458
TEST_TIME 47 9381
TEST_TIME 48 9373
TEST_TIME 49 9289
TEST_TIME 50 9310
TEST_TIME 51 9257
TEST_TIME 52 9262
TEST_TIME 53 9259
TEST_TIME 54 9378
TEST_TIME 55 9567
TEST_TIME 56 9552
TEST_TIME 57 9615
TEST_TIME 58 9616
TEST_TIME 59 9652
TEST_TIME 60 9718
TEST_TIME 61 9651
TEST_TIME 62 9580
TEST_TIME 63 9514
TEST_TIME 64 9416
TEST_TIME 65 9415
TEST_TIME 66 9349
TEST_TIME 67 9289
TEST_TIME 68 9239
TEST_TIME 69 9206
TEST_TIME 70 9398
TEST_TIME 71 9542
TEST_TIME 72 9512
TEST_TIME 73 9499
TEST_TIME 74 9597
TEST_TIME 75 9611
TEST_TIME 76 9582
TEST_TIME 77 9560
TEST_TIME 78 9576
TEST_TIME 79 9646
TEST_TIME 80 9557
TEST_TIME 81 9478
TEST_TIME 82 9370
TEST_TIME 83 9515
TEST_TIME 84 9457
TEST_TIME 85 9387
TEST_TIME 86 9381
TEST_TIME 87 9303
TEST_TIME 88 9268
TEST_TIME 89 9222
TEST_TIME 90 9246
TEST_TIME 91 9189
TEST_TIME 92 9234
TEST_TIME 93 9275
TEST_TIME 94 9256
TEST_TIME 95 9244
TEST_TIME 96 9253
TEST_TIME 97 9376
TEST_TIME 98 9360
TEST_TIME 99 9343
TEST_TIME 100 9376
TEST_TIME 101 9350
TEST_TIME 102 9364
TEST_TIME 103 9402
TEST_TIME 104 9447
TEST_TIME 105 9426
TEST_TIME 106 9344
TEST_TIME 107 9337
TEST_TIME 108 9299
TEST_TIME 109 9242
TEST_TIME 110 9199
TEST_TIME 111 9200
TEST_TIME 112 9237
TEST_TIME 113 9222
TEST_TIME 114 9227
TEST_TIME 115 9264
TEST_TIME 116 9244
TEST_TIME 117 9257
TEST_TIME 118 9246
TEST_TIME 119 9265
TEST_TIME 120 9249

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 15-12-2022 19:39:23 to 15-12-2022 19:39:27.

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 1532921 1532921 0 0.00 0 24247 6 0 0
doc_list_biud 1532919 1532919 0 0.00 0 28888 25 0 0
sp_add_invoice_to_stock 5105 4959 146 2.85 35 181435 16443 0 146
sp_cancel_adding_invoice 5779 5779 0 0.00 3 50620 673 0 0
sp_cancel_client_order 1806 1806 0 0.00 4 14665 317 0 0
sp_cancel_customer_reserve 566800 566800 0 0.00 0 28889 52 0 0
sp_cancel_pay_from_customer 710 710 0 0.00 3 10376 83 0 0
sp_cancel_pay_to_supplier 412 412 0 0.00 3 1303 56 0 0
sp_cancel_supplier_invoice 6501 6491 10 0.15 1 12810 714 0 10
sp_cancel_supplier_order 426 426 0 0.00 6 1012266 88884 0 0
sp_cancel_write_off 9599 9599 0 0.00 1 14665 116 0 0
sp_client_order 20676 20676 0 0.00 3 27211 353 0 0
sp_customer_reserve 455855 455855 0 0.00 0 82012 165 0 0
sp_fill_shopping_cart 31859 31859 0 0.00 1 15043 38 0 0
sp_fill_shopping_cart_clo_res 455852 455852 0 0.00 0 12782 13 0 0
sp_get_clo_for_invoice 4954 4954 0 0.00 0 18099 293 0 0
sp_kill_cost_storno 16500 16500 0 0.00 0 13438 52 0 0
sp_kill_qstorno_ret_qs2qd 1150116 1150116 0 0.00 0 28882 29 0 0
sp_kill_qty_storno 605996 605996 0 0.00 0 28888 62 0 0
sp_lock_selected_doc 20980 20980 0 0.00 0 9632 6 0 0
sp_make_cost_storno 28191 28191 0 0.00 0 24238 43 0 0
sp_multiply_rows_for_pdistr 28191 28191 0 0.00 0 13761 28 0 0
sp_multiply_rows_for_qdistr 20676 20676 0 0.00 0 17300 195 0 0
sp_pay_from_customer 7139 7139 0 0.00 4 23961 113 0 0
sp_pay_to_supplier 5955 5955 0 0.00 4 21100 126 0 0
sp_payment_common 13094 13094 0 0.00 1 23952 31 0 0
sp_qd_handle_on_cancel_clo 1806 1806 0 0.00 0 14657 291 0 0
sp_qd_handle_on_invoice_upd_sts 10731 10731 0 0.00 0 23526 291 0 0
sp_qd_handle_on_reserve_upd_sts 10143 10143 0 0.00 0 15824 46 0 0
sp_reserve_write_off 10143 10143 0 0.00 6 15844 149 0 0
sp_supplier_invoice 5551 5551 0 0.00 6 120903 2970 0 0
sp_supplier_order 5642 5642 0 0.00 4 31231 1120 0 0
srv_aggregate_perf_data 107 106 1 0.93 2571 300461 20440 0 1
srv_fill_mon_cache_memory 65 65 0 0.00 2684 142354 44509 0 0
srv_find_qd_qs_mism 1062358 1062358 0 0.00 0 13578 5 0 0
srv_make_invnt_saldo 601 601 0 0.00 31 94654 1052 0 0
srv_make_money_saldo 785 785 0 0.00 1 23815 54 0 0
srv_recalc_idx_stat 4 4 0 0.00 155882 430893 304659 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 10 10 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 4959 4959 0 0.00 0 12144 163 0 0
t$perf-norm:sp_cancel_adding_invoice 383 383 0 0.00 0 9923 156 0 0
t$perf-norm:sp_cancel_client_order 1806 1806 0 0.00 0 2174 12 0 0
t$perf-norm:sp_cancel_customer_reserve 662 662 0 0.00 0 396 5 0 0
t$perf-norm:sp_cancel_pay_from_customer 710 710 0 0.00 0 502 2 0 0
t$perf-norm:sp_cancel_pay_to_supplier 412 412 0 0.00 0 8457 24 0 0
t$perf-norm:sp_cancel_supplier_invoice 436 436 0 0.00 0 509 10 0 0
t$perf-norm:sp_cancel_supplier_order 426 426 0 0.00 0 21201 1334 0 0
t$perf-norm:sp_cancel_write_off 1053 1053 0 0.00 0 1980 7 0 0
t$perf-norm:sp_client_order 20676 20676 0 0.00 0 15388 19 0 0
t$perf-norm:sp_customer_reserve 1036 1036 0 0.00 0 2628 8 0 0
t$perf-norm:sp_pay_from_customer 7139 7139 0 0.00 0 5503 11 0 0
t$perf-norm:sp_pay_to_supplier 5955 5955 0 0.00 0 6245 16 0 0
t$perf-norm:sp_reserve_write_off 10143 10143 0 0.00 0 11004 15 0 0
t$perf-norm:sp_supplier_invoice 5551 5551 0 0.00 0 5190 16 0 0
t$perf-norm:sp_supplier_order 5642 5642 0 0.00 0 7967 14 0 0
t$perf-norm:srv_aggregate_perf_data 106 106 0 0.00 0 141 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 194 5 0 0
t$perf-norm:srv_make_invnt_saldo 601 601 0 0.00 0 8912 31 0 0
t$perf-norm:srv_make_money_saldo 785 785 0 0.00 0 2183 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 10190 9898 292 2.86 0 5270 16 0 292
v_all_customers 37521 37521 0 0.00 0 147 0 0 0
v_all_suppliers 31194 31194 0 0.00 0 69 1 0 0
v_all_wares 41350 41350 0 0.00 0 49 1 0 0
v_cancel_adding_invoice 766 766 0 0.00 0 68 5 0 0
v_cancel_client_order 3612 3612 0 0.00 0 4138 6 0 0
v_cancel_customer_prepayment 1420 1420 0 0.00 0 3370 10 0 0
v_cancel_customer_reserve 1324 1324 0 0.00 0 706 3 0 0
v_cancel_payment_to_supplier 824 824 0 0.00 0 977 5 0 0
v_cancel_supplier_invoice 892 872 20 2.24 0 187 12 0 20
v_cancel_supplier_order 852 852 0 0.00 0 2640 9 0 0
v_cancel_write_off 2106 2106 0 0.00 0 4026 20 0 0
v_max_id_clo_ord 5642 5642 0 0.00 0 510 1 0 0
v_max_id_clo_res 1036 1036 0 0.00 0 2070 9 0 0
v_max_id_ord_sup 5542 5542 0 0.00 0 6018 8 0 0
v_max_non_paid_invoice 1542 1542 0 0.00 0 2778 5 0 0
v_max_non_paid_realizn 3949 3949 0 0.00 0 8563 6 0 0
v_min_id_clo_ord 5642 5642 0 0.00 0 3061 5 0 0
v_min_id_clo_res 1036 1036 0 0.00 0 7507 10 0 0
v_min_id_ord_sup 5542 5542 0 0.00 0 7428 9 0 0
v_min_non_paid_invoice 5955 5955 0 0.00 0 8638 20 0 0
v_min_non_paid_realizn 7139 7139 0 0.00 0 23946 26 0 0
v_our_firm 10208 10208 0 0.00 0 146 1 0 0
v_reserve_write_off 20286 20286 0 0.00 0 4305 6 0 0
x_lock_depdocs_on_canc_invoice 11834 11834 0 0.00 0 19465 110 0 0
x_lock_depdocs_on_canc_sup_ord 426 426 0 0.00 0 29080 443 0 0
x_make_qstorno_1000_1200 5642 5642 0 0.00 1 31223 1051 0 0
x_make_qstorno_1200_2000 5551 5551 0 0.00 3 120773 2825 0 0
x_make_qstorno_2100_3300 455855 455855 0 0.00 0 81977 150 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 15-12-2022 19:39:28 to 15-12-2022 19:39:32.

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:36 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:37 28781 8192000000 9910323728 10219683840 989404640 10.776 100 87 175 0 1017841904 5575584 22861680
17:11:13 23266 8192000000 9960940144 10284216320 1042765392 11.291 100 88 176 1 1072991040 7792112 22433536
17:12:49 15234 8192000000 10012671584 10348544000 1092431808 11.766 100 81 163 0 1121648016 9920336 19295872
17:14:14 5531 8192000000 10044964704 10396295168 1137014400 12.187 100 69 138 0 1163127664 10226016 15887248
17:15:27 4908 8192000000 10097193248 10457571328 1184786800 12.635 100 82 164 0 1215820208 12501936 18531472
17:16:34 3276 8192000000 10119485376 10487361536 1211056752 12.879 100 73 146 0 1239792176 12386176 16349248
17:17:40 7792 8192000000 10149009536 10527121408 1243892720 13.182 100 75 148 3 1273847872 13704208 16250944
17:18:52 1842 8192000000 10192569952 10570432512 1280106608 13.514 100 85 169 0 1312443536 14120448 18216480
17:19:55 846 8192000000 10212330336 10592817152 1294238448 13.643 100 87 176 0 1325196864 12065216 18893200
17:20:57 1335 8192000000 10203330416 10599636992 1300452768 13.699 100 70 140 2 1326772192 10566528 15752896
17:22:02 8284 8192000000 10213571568 10618671104 1313949312 13.822 100 27 54 0 1330847680 11064096 5834272
17:23:11 19825 8192000000 10225712592 10626498560 1320554112 13.882 100 53 105 1 1338642480 5969632 12118736
17:24:32 5479 8192000000 10215652256 10632876032 1327494976 13.945 100 28 52 1 1340303440 6381248 6427216
17:25:42 8738 8192000000 10225617248 10641997824 1335207936 14.014 100 24 48 0 1346705040 6137904 5359200
17:26:51 58794 8192000000 10211547296 10634485760 1308716768 13.774 98 27 52 1 1320178544 5423216 6038560
17:28:59 5801 8192000000 10207581152 10620649472 1306341696 13.753 99 28 56 2 1318590032 4941632 7306704
17:30:07 9456 8192000000 10181038160 10590265344 1269930464 13.421 98 15 30 0 1278790448 5109168 3750816
17:31:22 53266 8192000000 10142387152 10549817344 1179595072 12.586 92 18 36 0 1188712752 5048144 4069536
17:33:17 10379 8192000000 10142622144 10538676224 1213205216 12.899 97 32 63 3 1225960880 5318944 7436720
17:34:28 8286 8192000000 10167541520 10570997760 1269986128 13.421 100 24 48 0 1281741360 5763728 5991504
17:35:37 19473 8192000000 10178967536 10594807808 1294279072 13.643 100 27 52 0 1305327360 5396880 5651408
17:37:24 8625 8192000000 10202446016 10611773440 1302648640 13.719 100 42 84 0 1318079712 6813072 8618000
17:38:33 45508 8192000000 10231086000 10645172224 1332395792 13.989 100 38 76 3 1347044624 5698576 8950256
17:40:25 60471 8192000000 10277067088 10691395584 1357312448 14.213 100 36 72 0 1377166480 10954048 8899984
17:42:31 31784 8192000000 10290009056 10705326080 1374997840 14.372 100 56 112 1 1408729584 20318720 13413024
17:44:12 46666 8192000000 10288342928 10716270592 1368671792 14.315 99 24 48 0 1396059984 21530080 5858112
17:46:25 2986 8192000000 10319140048 10735603712 1391028000 14.515 100 58 115 0 1432576592 28233536 13315056
17:47:34 9814 8192000000 10308301360 10726457344 1374028400 14.363 99 50 98 0 1416306112 29601040 12676672
17:48:45 5082 8192000000 10326814656 10747920384 1387497696 14.484 100 53 105 2 1433053648 33025408 12530544
17:49:57 5165 8192000000 10337321344 10758950912 1398981120 14.586 100 59 117 0 1446015248 32309872 14724256
17:51:08 4532 8192000000 10356269280 10774339584 1412337440 14.705 100 66 132 0 1461732256 32747264 16647552
17:52:17 6030 8192000000 10382590432 10796896256 1424702576 14.814 100 72 144 3 1484045664 40770576 18572512
17:53:28 2684 8192000000 10367572800 10786344960 1412889312 14.710 100 67 134 0 1472844256 43215104 16739840
17:54:36 2889 8192000000 10363165536 10781847552 1409339168 14.678 100 62 124 2 1469750112 44575024 15835920
17:55:40 13008 8192000000 10316790560 10748293120 1365728288 14.289 98 31 61 0 1412430304 38447440 8254576
17:56:55 3248 8192000000 10347263488 10774007808 1402688272 14.619 100 44 87 0 1456755504 42828928 11238304
17:58:05 5274 8192000000 10328231056 10747285504 1368996992 14.318 100 49 96 1 1433357280 51218400 13141888
17:59:13 8565 8192000000 10303276736 10726002688 1337352512 14.034 98 37 74 1 1397204480 49319200 10532768
18:00:27 17391 8192000000 10286160864 10722631680 1352096176 14.166 100 25 49 0 1397844800 39246816 6501808
18:02:09 23341 8192000000 10278983152 10712264704 1339812656 14.056 99 18 36 1 1382522176 37508384 5201136
18:03:37 30411 8192000000 10244166784 10665762816 1306285344 13.752 97 31 60 0 1320529984 6874896 7369744
18:05:22 54715 8192000000 10250916384 10679463936 1302879648 13.721 97 12 24 0 1315359024 8837840 3641536
18:07:18 98789 8192000000 10269176544 10706202624 1321428992 13.890 97 20 40 0 1340693136 14636384 4627760
18:09:58 67081 8192000000 10289644256 10718138368 1340322960 14.060 99 47 93 0 1363784960 12415984 11046016
18:12:11 78495 8192000000 10282048880 10719490048 1348499696 14.134 98 29 54 1 1362031968 8152144 5380128
18:14:31 33574 8192000000 10284559536 10725654528 1366581600 14.296 99 32 64 0 1384082912 10058048 7443264
18:16:11 45632 8192000000 10298165104 10757033984 1393371616 14.536 100 17 34 0 1415363232 17266960 4724656
18:17:56 44658 8192000000 10311959248 10765615104 1400061632 14.596 100 14 28 0 1415121392 11985840 3073920
18:20:18 41949 8192000000 10306601200 10760929280 1395307520 14.553 100 16 30 0 1415519264 16815536 3396208
18:22:01 23383 8192000000 10288081792 10746826752 1389488192 14.501 100 35 71 0 1406534928 9146096 7900640
18:23:35 54277 8192000000 10288180672 10752581632 1388413968 14.492 99 19 38 0 1401934256 9190560 4329728
18:25:34 46862 8192000000 10287838224 10759630848 1387555952 14.484 99 8 16 0 1397968448 8545840 1866656
18:27:21 48602 8192000000 10300878192 10763735040 1399521520 14.591 100 12 24 0 1414544672 11735744 3287408
18:29:21 19516 8192000000 10318649952 10766290944 1403749008 14.628 100 33 66 0 1427080688 15408560 7923120
18:30:46 43249 8192000000 10311558048 10777980928 1404542224 14.635 100 15 30 1 1425728176 17822256 3363696
18:32:49 53456 8192000000 10306885696 10767245312 1392425360 14.528 99 11 22 0 1409792544 14754384 2612800
18:34:57 17920 8192000000 10318696288 10760691712 1391497632 14.519 99 49 96 1 1411635952 8515920 11622400
18:36:28 32632 8192000000 10299288912 10747080704 1373881568 14.362 99 29 58 0 1391587008 11463552 6241888
18:38:05 60762 8192000000 10292140640 10752311296 1361190880 14.248 98 15 30 0 1378450848 14249344 3010624
18:40:20 91645 8192000000 10276723504 10732593152 1365359824 14.285 99 39 78 0 1386203328 12296656 8546848
18:43:03 55673 8192000000 10293786304 10756222976 1379514448 14.412 99 14 24 1 1394688048 12651776 2521824
18:45:02 77793 8192000000 10290530336 10752032768 1337959920 14.039 96 19 38 0 1360985648 18302272 4723456
18:47:26 58271 8192000000 10262296560 10724343808 1292232960 13.625 94 24 48 0 1321695216 23229184 6233072
18:49:32 48371 8192000000 10254347872 10702655488 1320881184 13.885 98 23 47 2 1334682384 8860016 4941184
18:51:21 21249 8192000000 10263306912 10703441920 1333505296 13.999 99 38 75 1 1353386416 11644352 8236768
18:52:42 46082 8192000000 10248242288 10694926336 1283450800 13.545 95 15 31 0 1303985248 17135856 3398592
18:54:33 10480 8192000000 10262600480 10693353472 1339605232 14.054 100 55 110 1 1361542224 9338576 12598416
18:55:46 12522 8192000000 10274507072 10705510400 1351413664 14.160 100 41 81 0 1370608960 10145296 9050000
18:57:02 50577 8192000000 10271675136 10714394624 1325328000 13.925 98 19 38 0 1345239136 15970528 3940608
18:58:58 87489 8192000000 10273354208 10721837056 1352871696 14.173 99 19 36 0 1373105616 15802624 4431296
19:01:27 30528 8192000000 10253726688 10711384064 1355302768 14.195 100 9 18 0 1366135664 8924720 1908176
19:03:00 68179 8192000000 10254355216 10711412736 1327349616 13.943 97 6 12 0 1336910384 8393856 1166912
19:05:12 57630 8192000000 10262675840 10714562560 1343805200 14.092 98 20 40 0 1353845760 5806160 4234400
19:07:13 95600 8192000000 10268181328 10735235072 1357621280 14.216 99 15 30 1 1376412928 15579904 3211744
19:09:49 76336 8192000000 10270631824 10726383616 1349152448 14.140 97 16 32 0 1361617520 8838144 3626928
19:12:11 72862 8192000000 10294141104 10748485632 1362204112 14.257 98 14 28 0 1375835344 10284128 3347104
19:14:30 122811 8192000000 10279840144 10741776384 1314036000 13.823 95 8 16 0 1337695616 21591376 2068240
19:17:33 70169 8192000000 10285563568 10746478592 1364953968 14.282 99 26 52 0 1383023008 12712048 5356992
19:19:49 26970 8192000000 10298946080 10738978816 1356865344 14.209 98 36 72 0 1374559152 9819920 7873888
19:21:20 36837 8192000000 10307444928 10761949184 1373420288 14.358 99 42 84 1 1404943024 22374128 9148608
19:22:57 142354 8192000000 10287591168 10741686272 1372938528 14.353 99 21 42 0 1391463552 14293136 4231888
19:26:43 36819 8192000000 10272785600 10735312896 1344652464 14.099 97 9 18 0 1357411552 10729184 2029904
19:28:20 32512 8192000000 10288710624 10749673472 1387523840 14.484 100 15 30 0 1401443104 10449936 3469328
19:29:57 53814 8192000000 10286885520 10747371520 1365231728 14.284 98 19 36 0 1380743760 11166208 4345824
19:32:27 73287 8192000000 10277144032 10734088192 1323055920 13.904 95 10 20 0 1338291856 12747872 2488064
19:34:48 86126 8192000000 10261542832 10721107968 1249795616 13.236 90 20 40 0 1264927264 10552128 4579520
19:37:43 59601 8192000000 9185179472 9443725312 330615472 3.879 25 7 14 0 335631440 3598736 1417232

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 15-12-2022 19:39:34 to 15-12-2022 19:39:49.

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 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 15-12-2022 19:39:50 to 15-12-2022 19:39:52.

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          241457
MON$OLDEST_ACTIVE               241458
MON$OLDEST_SNAPSHOT             241458
MON$NEXT_TRANSACTION            241458
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                       2013376
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33648 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33648 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33648 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33648 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 ---
:83: 
:84: mscs-test-161.budzdorov.ru    Thu Dec 15 19:38:44 2022
:85:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:86: 
--- end of diff output ---

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

15.12.22 19:39:53. 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-12-15 19:39:56 No severe PSQL-related problems occured
15.12.22 19:39:57. End of report.