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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15179812 kB
MemAvailable 30397020 kB
Buffers 0 kB
Cached 16514832 kB
SwapCached 4400 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 2023-01-09 22:38:38
DTS_END                         2023-01-09 22:38:38.1080
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/56784
TRN_ID                          243962
ATT_ID                          715
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: 9309.63 23472 1117156
customer order: creation 176.77 322 21212
customer order: refuse 14.80 359 1776
order to supplier: creation 46.41 1152 5569
order to supplier: removal 3.28 109500 394
invoice (draft): creation 46.40 3335 5568
invoice (draft): removal 51.76 764 6211
invoice accept: apply 43.14 17188 5177
invoice accept: cancel 46.83 832 5620
customer reserve: creation 3967.81 168 476137
customer reserve: removal 4611.32 61 553358
realization accept: apply 84.59 228 10151
realization accept: cancel 85.39 114 10247
payment from customer: creation 59.63 166 7155
payment from customer: removal 5.93 93 711
payment to supplier: creation 50.07 194 6008
payment to supplier: removal 3.13 87 376
service: total inventory turnovers 4.72 1340 566
service: total monetary turnovers 6.82 51 818
service: aggregate perf. data 0.82 22354 98
service: refresh index statistics 0.03 311122 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 09-01-2023 22:39:56 to 09-01-2023 22:39:59.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1543
TEST_TIME 2 1862
TEST_TIME 3 2436
TEST_TIME 4 3392
TEST_TIME 5 3991
TEST_TIME 6 4552
TEST_TIME 7 5203
TEST_TIME 8 5175
TEST_TIME 9 5757
TEST_TIME 10 6067
TEST_TIME 11 6132
TEST_TIME 12 6316
TEST_TIME 13 6165
TEST_TIME 14 6332
TEST_TIME 15 6746
TEST_TIME 16 6805
TEST_TIME 17 7160
TEST_TIME 18 7090
TEST_TIME 19 7103
TEST_TIME 20 7430
TEST_TIME 21 7678
TEST_TIME 22 7832
TEST_TIME 23 8046
TEST_TIME 24 8233
TEST_TIME 25 8328
TEST_TIME 26 8769
TEST_TIME 27 8705
TEST_TIME 28 9105
TEST_TIME 29 9168
TEST_TIME 30 9370
TEST_TIME 31 10606
TEST_TIME 32 10457
TEST_TIME 33 10460
TEST_TIME 34 10424
TEST_TIME 35 10330
TEST_TIME 36 10156
TEST_TIME 37 10077
TEST_TIME 38 9961
TEST_TIME 39 9847
TEST_TIME 40 9832
TEST_TIME 41 9792
TEST_TIME 42 9722
TEST_TIME 43 9636
TEST_TIME 44 9631
TEST_TIME 45 9660
TEST_TIME 46 9764
TEST_TIME 47 9775
TEST_TIME 48 9733
TEST_TIME 49 9810
TEST_TIME 50 9871
TEST_TIME 51 9815
TEST_TIME 52 9864
TEST_TIME 53 9755
TEST_TIME 54 9640
TEST_TIME 55 9556
TEST_TIME 56 9473
TEST_TIME 57 9426
TEST_TIME 58 9474
TEST_TIME 59 9435
TEST_TIME 60 9425
TEST_TIME 61 9451
TEST_TIME 62 9472
TEST_TIME 63 9383
TEST_TIME 64 9295
TEST_TIME 65 9376
TEST_TIME 66 9351
TEST_TIME 67 9360
TEST_TIME 68 9251
TEST_TIME 69 9220
TEST_TIME 70 9174
TEST_TIME 71 9196
TEST_TIME 72 9230
TEST_TIME 73 9203
TEST_TIME 74 9181
TEST_TIME 75 9299
TEST_TIME 76 9315
TEST_TIME 77 9346
TEST_TIME 78 9374
TEST_TIME 79 9386
TEST_TIME 80 9373
TEST_TIME 81 9374
TEST_TIME 82 9388
TEST_TIME 83 9532
TEST_TIME 84 9529
TEST_TIME 85 9493
TEST_TIME 86 9422
TEST_TIME 87 9451
TEST_TIME 88 9395
TEST_TIME 89 9356
TEST_TIME 90 9319
TEST_TIME 91 9380
TEST_TIME 92 9303
TEST_TIME 93 9319
TEST_TIME 94 9281
TEST_TIME 95 9293
TEST_TIME 96 9268
TEST_TIME 97 9372
TEST_TIME 98 9329
TEST_TIME 99 9360
TEST_TIME 100 9373
TEST_TIME 101 9337
TEST_TIME 102 9467
TEST_TIME 103 9479
TEST_TIME 104 9500
TEST_TIME 105 9489
TEST_TIME 106 9517
TEST_TIME 107 9570
TEST_TIME 108 9553
TEST_TIME 109 9501
TEST_TIME 110 9480
TEST_TIME 111 9448
TEST_TIME 112 9458
TEST_TIME 113 9430
TEST_TIME 114 9388
TEST_TIME 115 9369
TEST_TIME 116 9326
TEST_TIME 117 9351
TEST_TIME 118 9321
TEST_TIME 119 9328
TEST_TIME 120 9310

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 09-01-2023 22:40:00 to 09-01-2023 22:40:03.

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 1557534 1557534 0 0.00 0 68259 7 0 0
doc_list_biud 1557531 1557531 0 0.00 0 21213 27 0 0
sp_add_invoice_to_stock 5306 5177 129 2.43 26 182960 17188 0 129
sp_cancel_adding_invoice 5620 5620 0 0.00 4 49160 833 0 0
sp_cancel_client_order 1776 1776 0 0.00 4 15538 360 0 0
sp_cancel_customer_reserve 553358 553358 0 0.00 0 21214 62 0 0
sp_cancel_pay_from_customer 711 711 0 0.00 2 6340 94 0 0
sp_cancel_pay_to_supplier 376 376 0 0.00 3 6469 88 0 0
sp_cancel_supplier_invoice 6231 6211 20 0.32 1 19439 765 0 20
sp_cancel_supplier_order 394 394 0 0.00 8 1098332 109500 0 0
sp_cancel_write_off 10247 10247 0 0.00 1 17597 114 0 0
sp_client_order 21212 21212 0 0.00 3 15893 323 0 0
sp_customer_reserve 476137 476137 0 0.00 0 147459 168 0 0
sp_fill_shopping_cart 32345 32345 0 0.00 1 16427 42 0 0
sp_fill_shopping_cart_clo_res 476136 476136 0 0.00 0 17517 13 0 0
sp_get_clo_for_invoice 5176 5176 0 0.00 0 26711 291 0 0
sp_kill_cost_storno 16954 16954 0 0.00 0 7396 56 0 0
sp_kill_qstorno_ret_qs2qd 1123568 1123568 0 0.00 0 21210 33 0 0
sp_kill_qty_storno 592933 592933 0 0.00 0 21212 71 0 0
sp_lock_selected_doc 21208 21208 0 0.00 0 7193 6 0 0
sp_make_cost_storno 28490 28490 0 0.00 0 66488 102 0 0
sp_multiply_rows_for_pdistr 28490 28490 0 0.00 0 34505 38 0 0
sp_multiply_rows_for_qdistr 21212 21212 0 0.00 0 14070 144 0 0
sp_pay_from_customer 7155 7155 0 0.00 4 43340 167 0 0
sp_pay_to_supplier 6008 6008 0 0.00 4 66341 194 0 0
sp_payment_common 13160 13160 0 0.00 1 40329 63 0 0
sp_qd_handle_on_cancel_clo 1776 1776 0 0.00 0 15526 331 0 0
sp_qd_handle_on_invoice_upd_sts 10796 10796 0 0.00 0 17299 338 0 0
sp_qd_handle_on_reserve_upd_sts 10151 10151 0 0.00 0 14010 58 0 0
sp_reserve_write_off 10151 10151 0 0.00 6 43110 229 0 0
sp_supplier_invoice 5568 5568 0 0.00 6 100775 3335 0 0
sp_supplier_order 5569 5569 0 0.00 5 28378 1152 0 0
srv_aggregate_perf_data 99 98 1 1.01 2426 181523 22355 0 1
srv_fill_mon_cache_memory 63 63 0 0.00 864 156719 48738 0 0
srv_find_qd_qs_mism 1068111 1068111 0 0.00 0 17519 5 0 0
srv_make_invnt_saldo 566 566 0 0.00 43 159392 1341 0 0
srv_make_money_saldo 818 818 0 0.00 0 14765 51 0 0
srv_recalc_idx_stat 4 4 0 0.00 192601 483295 311122 0 0
t$perf-abend:sp_add_invoice_to_stock 129 129 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 20 20 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5177 5177 0 0.00 0 15243 169 0 0
t$perf-norm:sp_cancel_adding_invoice 424 424 0 0.00 0 2272 86 0 0
t$perf-norm:sp_cancel_client_order 1776 1776 0 0.00 0 5021 14 0 0
t$perf-norm:sp_cancel_customer_reserve 728 728 0 0.00 0 6541 20 0 0
t$perf-norm:sp_cancel_pay_from_customer 711 711 0 0.00 0 1798 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 376 376 0 0.00 0 408 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 426 426 0 0.00 0 3590 21 0 0
t$perf-norm:sp_cancel_supplier_order 394 394 0 0.00 0 30583 1902 0 0
t$perf-norm:sp_cancel_write_off 1046 1046 0 0.00 0 1751 7 0 0
t$perf-norm:sp_client_order 21212 21212 0 0.00 0 6362 19 0 0
t$perf-norm:sp_customer_reserve 1014 1014 0 0.00 0 1418 6 0 0
t$perf-norm:sp_pay_from_customer 7155 7155 0 0.00 0 9540 14 0 0
t$perf-norm:sp_pay_to_supplier 6008 6008 0 0.00 0 6434 12 0 0
t$perf-norm:sp_reserve_write_off 10151 10151 0 0.00 0 7988 13 0 0
t$perf-norm:sp_supplier_invoice 5568 5568 0 0.00 0 14064 28 0 0
t$perf-norm:sp_supplier_order 5569 5569 0 0.00 0 5197 18 0 0
t$perf-norm:srv_aggregate_perf_data 98 98 0 0.00 0 331 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 332 9 0 0
t$perf-norm:srv_make_invnt_saldo 566 566 0 0.00 0 1073 30 0 0
t$perf-norm:srv_make_money_saldo 818 818 0 0.00 0 2020 22 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 10610 10352 258 2.43 0 6950 20 0 258
v_all_customers 38472 38472 0 0.00 0 23 1 0 0
v_all_suppliers 30750 30750 0 0.00 0 21 1 0 0
v_all_wares 42418 42418 0 0.00 0 26 1 0 0
v_cancel_adding_invoice 848 848 0 0.00 0 2579 11 0 0
v_cancel_client_order 3552 3552 0 0.00 0 5904 6 0 0
v_cancel_customer_prepayment 1422 1422 0 0.00 0 4110 8 0 0
v_cancel_customer_reserve 1456 1456 0 0.00 0 2255 7 0 0
v_cancel_payment_to_supplier 752 752 0 0.00 0 6452 15 0 0
v_cancel_supplier_invoice 892 852 40 4.48 0 330 14 0 40
v_cancel_supplier_order 788 788 0 0.00 0 2606 8 0 0
v_cancel_write_off 2092 2092 0 0.00 0 3060 20 0 0
v_max_id_clo_ord 5567 5567 0 0.00 0 1193 2 0 0
v_max_id_clo_res 1014 1014 0 0.00 0 2016 16 0 0
v_max_id_ord_sup 5566 5566 0 0.00 0 3922 5 0 0
v_max_non_paid_invoice 1765 1765 0 0.00 0 1264 5 0 0
v_max_non_paid_realizn 3827 3827 0 0.00 0 30563 16 0 0
v_min_id_clo_ord 5567 5567 0 0.00 0 4235 7 0 0
v_min_id_clo_res 1014 1014 0 0.00 0 2957 8 0 0
v_min_id_ord_sup 5566 5566 0 0.00 0 5431 6 0 0
v_min_non_paid_invoice 6007 6007 0 0.00 0 38797 50 0 0
v_min_non_paid_realizn 7153 7153 0 0.00 0 39203 51 0 0
v_our_firm 10598 10598 0 0.00 0 25 1 0 0
v_reserve_write_off 20302 20302 0 0.00 0 6489 7 0 0
x_lock_depdocs_on_canc_invoice 11405 11405 0 0.00 0 11137 122 0 0
x_lock_depdocs_on_canc_sup_ord 394 394 0 0.00 0 15003 482 0 0
x_make_qstorno_1000_1200 5569 5569 0 0.00 1 28335 1056 0 0
x_make_qstorno_1200_2000 5568 5568 0 0.00 2 100757 3190 0 0
x_make_qstorno_2100_3300 476137 476137 0 0.00 0 147458 152 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 09-01-2023 22:40:04 to 09-01-2023 22:40:09.

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
20:08:42 10 8192000000 8810232976 8825638912 13861024 0.168 6 2 3 0 14007456 71488 74944
20:09:43 16412 8192000000 9893009040 10199605248 973398544 10.620 100 91 182 0 1003190336 5709648 24082144
20:11:08 26941 8192000000 9945947376 10267820032 1024214704 11.113 100 85 170 0 1054064256 7851568 21997984
20:12:39 18323 8192000000 9976822432 10310443008 1061382048 11.470 100 83 166 0 1091509216 9747232 20379936
20:14:02 9405 8192000000 10017652848 10365882368 1107701616 11.911 100 77 154 1 1136198864 10634992 17862256
20:15:18 5053 8192000000 10073905216 10431033344 1161420608 12.417 100 77 152 1 1191106416 12548912 17136896
20:16:25 5864 8192000000 10112212016 10479218688 1198992576 12.767 100 76 152 1 1229196608 13201712 17002320
20:17:32 3304 8192000000 10126349872 10507988992 1228707568 13.042 100 59 118 0 1253536832 11639888 13189376
20:18:38 2746 8192000000 10175072032 10559561728 1270232032 13.424 100 71 142 2 1297305440 11136224 15937184
20:19:43 1350 8192000000 10214872352 10599124992 1302960144 13.722 100 89 179 1 1336671280 14048624 19662512
20:20:45 12049 8192000000 10194328432 10602524672 1304427104 13.735 100 40 81 0 1322361824 9087216 8847504
20:21:58 8800 8192000000 10199433456 10616131584 1318620928 13.864 100 18 36 0 1328663440 5861920 4180592
20:23:07 9336 8192000000 10212993760 10630307840 1328193984 13.951 100 18 35 1 1337119520 5168576 3756960
20:24:20 66878 8192000000 10239443184 10650357760 1334979456 14.012 100 38 76 1 1348838656 4855648 9003552
20:26:27 7748 8192000000 10233189984 10658963456 1343971488 14.093 100 30 60 0 1357995920 7550784 6473648
20:27:35 6159 8192000000 10236188640 10657599488 1333853360 14.002 99 28 55 1 1346349552 5446592 7049600
20:28:46 19810 8192000000 10217908256 10626072576 1292504464 13.627 97 43 82 0 1307392016 4869536 10018016
20:30:15 12507 8192000000 10169775408 10575433728 1231902352 13.072 95 19 38 1 1241329776 5401168 4026256
20:31:28 7026 8192000000 10153952448 10558152704 1261717872 13.346 100 14 29 0 1270313440 5357392 3238176
20:32:35 6941 8192000000 10129991136 10524336128 1209205536 12.862 97 20 40 1 1218824144 5099488 4519120
20:33:44 10618 8192000000 10152316320 10555961344 1256976320 13.302 100 14 28 0 1265824976 5541344 3307312
20:34:56 19444 8192000000 10157923360 10568638464 1270097376 13.423 100 21 42 0 1280997408 6119616 4780416
20:36:19 6001 8192000000 10178334656 10589282304 1286796656 13.575 100 26 53 0 1299051024 5889392 6364976
20:37:25 12730 8192000000 10190909168 10600468480 1294427472 13.645 100 23 46 0 1304795152 4833392 5534288
20:38:41 131347 8192000000 10239346752 10653040640 1327294528 13.943 100 38 76 1 1344039840 7841680 8903632
20:41:56 30813 8192000000 10261584976 10678669312 1350382800 14.151 100 44 86 1 1373346544 12968064 9995680
20:43:57 8967 8192000000 10273166592 10689765376 1359097248 14.229 100 39 76 1 1381590528 13172928 9320352
20:45:10 23098 8192000000 10287736544 10711351296 1378329392 14.402 100 49 93 1 1402444752 13256992 10858368
20:46:38 5030 8192000000 10311393808 10732392448 1390489536 14.510 100 52 104 0 1423870992 20974592 12406864
20:47:49 7547 8192000000 10327170672 10743021568 1393151072 14.534 100 64 126 0 1429283904 20960000 15172832
20:48:58 5983 8192000000 10341710112 10766381056 1412906080 14.710 100 53 106 1 1449005488 23685664 12413744
20:50:10 11498 8192000000 10340111152 10759331840 1402858672 14.620 100 63 127 0 1447234816 29859472 14516672
20:51:23 5931 8192000000 10327529744 10747146240 1382070304 14.435 99 62 124 0 1429619440 32984384 14564752
20:52:34 1844 8192000000 10343749584 10753273856 1390853776 14.513 100 76 150 2 1449754032 40152752 18747504
20:53:41 863 8192000000 10341712000 10750459904 1382179504 14.436 100 84 168 0 1447375024 44955984 20239536
20:54:47 2383 8192000000 10326838960 10740408320 1376763120 14.388 100 62 121 0 1429339904 37357392 15219392
20:55:54 3247 8192000000 10313400016 10723811328 1366104048 14.292 100 59 117 0 1420317808 39842336 14371424
20:57:04 5343 8192000000 10340360608 10753761280 1383872768 14.451 100 58 115 1 1447020000 48803680 14343552
20:58:17 1153 8192000000 10339946816 10743152640 1364124000 14.274 100 74 146 1 1435682736 51851520 19707216
20:59:26 1360 8192000000 10370385408 10774687744 1390423600 14.510 100 90 178 1 1469631616 57019408 22188608
21:00:32 5334 8192000000 10356920688 10771152896 1393034176 14.533 100 58 116 1 1459579056 52204368 14340512
21:01:47 3779 8192000000 10361249344 10779725824 1392373104 14.527 100 61 121 1 1467347056 59034544 15939408
21:02:57 9825 8192000000 10351812528 10780454912 1390318304 14.509 100 37 74 0 1456207200 55622464 10266432
21:04:17 6330 8192000000 10348356832 10771415040 1383320464 14.446 100 49 95 1 1454510080 58861616 12328000
21:05:56 4486 8192000000 10365389280 10795003904 1398292352 14.580 100 46 89 0 1466634400 56608208 11733840
21:07:09 26266 8192000000 10340365584 10772402176 1370844096 14.335 99 32 64 0 1439416800 59784752 8787952
21:08:38 23592 8192000000 10334556400 10775404544 1366575744 14.296 99 19 38 0 1431165152 58807728 5781680
21:10:09 48387 8192000000 10302214336 10738204672 1384198624 14.454 100 32 66 1 1403211264 11510832 7501808
21:12:16 49369 8192000000 10289267648 10744188928 1382816864 14.442 100 29 57 0 1404167904 15380416 5970624
21:14:09 43666 8192000000 10276309392 10732929024 1374653328 14.369 100 7 14 0 1387968096 12075440 1239328
21:15:54 32096 8192000000 10305200880 10753441792 1394170912 14.543 100 26 53 0 1412894960 13166624 5557424
21:17:31 91172 8192000000 10308310064 10754768896 1386704800 14.476 99 17 34 0 1399708720 9499216 3504704
21:20:02 92176 8192000000 10305418016 10759090176 1384211344 14.454 99 16 32 0 1396684752 9026416 3446992
21:22:41 150117 8192000000 10309060752 10769977344 1381382368 14.429 98 13 27 0 1404839072 20611824 2844880
21:26:27 84834 8192000000 10312955472 10780631040 1382720528 14.441 99 8 17 0 1403876576 19272720 1883328
21:28:55 110561 8192000000 10286300672 10741006336 1311456368 13.799 94 11 22 0 1327088320 13152016 2479936
21:32:16 87814 8192000000 10292313696 10741522432 1384035136 14.453 100 44 86 0 1403943200 9627328 10280736
21:34:46 58049 8192000000 10308183552 10751057920 1391774896 14.522 100 36 72 0 1416599808 16823472 8001440
21:36:48 57088 8192000000 10294443584 10741157888 1373861808 14.362 99 19 38 2 1389189472 11168160 4159504
21:38:46 30879 8192000000 10284362384 10739843072 1381340704 14.429 100 25 50 0 1401226704 14225648 5660352
21:40:24 156719 8192000000 10288917904 10742743040 1326130240 13.932 95 14 28 0 1343664384 14181808 3352336
21:44:01 57478 8192000000 10275880752 10735353856 1356698560 14.208 98 25 50 0 1379818816 17761728 5358528
21:46:04 67533 8192000000 10284639792 10736734208 1355687088 14.199 98 25 49 0 1371190272 9775888 5727296
21:48:12 101252 8192000000 10259480464 10721144832 1331090368 13.977 97 4 8 0 1341452880 9664896 697616
21:50:54 63477 8192000000 10242905248 10678231040 1274340384 13.461 94 22 44 0 1291678848 12599504 4738960
21:53:03 51023 8192000000 10268363296 10722148352 1359023056 14.229 100 20 40 0 1380688592 16721376 4944160
21:55:06 93948 8192000000 10267177328 10714198016 1333897520 14.002 98 15 30 0 1350951616 13960512 3093584
21:57:40 53488 8192000000 10277885536 10722168832 1356468544 14.206 99 36 72 0 1373870032 8913056 8488432
21:59:35 48250 8192000000 10295690080 10746007552 1362915824 14.264 99 27 54 0 1384437088 15357632 6163632
22:01:24 103091 8192000000 10291065120 10733293568 1326889696 13.939 96 11 23 0 1349005808 20026096 2090016
22:04:11 92685 8192000000 10283775760 10736988160 1372429664 14.349 99 22 44 0 1386278176 8635728 5212784
22:06:47 20319 8192000000 10285333584 10737537024 1355133184 14.194 98 23 44 0 1370228080 10492800 4602096
22:08:10 45364 8192000000 10285552368 10744786944 1389334608 14.500 100 13 26 0 1402602560 10392592 2875360
22:10:22 71206 8192000000 10298070592 10762829824 1402556400 14.618 100 20 39 0 1420941184 14184592 4200192
22:12:34 35877 8192000000 10309564064 10766245888 1406756832 14.655 100 17 34 1 1417542512 7078256 3707424
22:14:21 52635 8192000000 10313533104 10775584768 1401337968 14.607 99 16 32 0 1415156848 10135520 3683360
22:16:26 15314 8192000000 10318144832 10759880704 1401253536 14.606 100 41 82 2 1418481296 7821344 9406416
22:17:42 69348 8192000000 10305678192 10761641984 1354828224 14.191 97 19 38 0 1371397968 12531056 4038688
22:20:15 61151 8192000000 10287039200 10763620352 1368404864 14.313 98 14 29 0 1389095136 17306704 3383568
22:22:16 93939 8192000000 10289746624 10737410048 1298672000 13.683 93 14 28 0 1314295392 12707760 2915632
22:24:50 89014 8192000000 10282096256 10741608448 1313792384 13.820 95 10 20 0 1333463360 17585184 2085792
22:27:25 103284 8192000000 10270693248 10721062912 1334616128 14.009 97 15 30 0 1344775424 6979760 3179536
22:30:12 37820 8192000000 10246287792 10697125888 1332137936 13.986 98 8 16 0 1346107840 12224384 1745520
22:31:52 58128 8192000000 10250254368 10712895488 1349053728 14.139 99 14 28 0 1362989680 10628336 3307616
22:33:50 53615 8192000000 10241685696 10703253504 1309035184 13.777 96 9 19 0 1320481488 9295952 2150352
22:35:52 82306 8192000000 10254288720 10703818752 1295602336 13.655 95 11 22 0 1310746928 12738144 2406448
22:38:15 29939 8192000000 9076444864 9307815936 225314480 2.676 17 7 14 0 240333024 13790432 1228112

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 09-01-2023 22:40:10 to 09-01-2023 22:40:26.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 09-01-2023 22:40:27 to 09-01-2023 22:40:29.

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          243555
MON$OLDEST_ACTIVE               244799
MON$OLDEST_SNAPSHOT             244799
MON$NEXT_TRANSACTION            244799
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                       2050464
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.33652 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33652 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33652 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33652 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 ---
:236: 
:237: mscs-test-161.budzdorov.ru    Mon Jan  9 22:38:47 2023
:238:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:239: 
:240: 
:241: mscs-test-161.budzdorov.ru    Mon Jan  9 22:38:48 2023
:242:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:243: 
:244: 
:245: mscs-test-161.budzdorov.ru    Mon Jan  9 22:38:48 2023
:246:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:247: 
:248: 
:249: mscs-test-161.budzdorov.ru    Mon Jan  9 22:38:52 2023
:250:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:251: 
:252: 
:253: mscs-test-161.budzdorov.ru    Mon Jan  9 22:38:55 2023
:254:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:255: 
:256: 
:257: mscs-test-161.budzdorov.ru    Mon Jan  9 22:38:55 2023
:258:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:259: 
--- end of diff output ---

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

09.01.23 22:40:30. 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
2023-01-09 22:40:32 No severe PSQL-related problems occured
09.01.23 22:40:34. End of report.