Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 19.02.2023 01: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 15293004 kB
MemAvailable 30467644 kB
Buffers 0 kB
Cached 16442976 kB
SwapCached 3176 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-02-19 01:38:41
DTS_END                         2023-02-19 01:38:41.9330
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/50834
TRN_ID                          245120
ATT_ID                          675
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: 9181.03 21323 1101723
customer order: creation 177.28 350 21274
customer order: refuse 15.68 365 1881
order to supplier: creation 47.28 1245 5674
order to supplier: removal 3.47 91786 416
invoice (draft): creation 46.73 3402 5608
invoice (draft): removal 52.10 751 6252
invoice accept: apply 42.82 17138 5138
invoice accept: cancel 47.39 763 5687
customer reserve: creation 3892.50 172 467100
customer reserve: removal 4552.14 55 546257
realization accept: apply 85.78 181 10294
realization accept: cancel 83.94 95 10073
payment from customer: creation 61.68 108 7402
payment from customer: removal 5.96 76 715
payment to supplier: creation 49.89 130 5987
payment to supplier: removal 3.60 90 432
service: total inventory turnovers 4.80 1012 576
service: total monetary turnovers 7.10 25 852
service: aggregate perf. data 0.84 20888 101
service: refresh index statistics 0.03 287828 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 19-02-2023 01:40:15 to 19-02-2023 01:40:17.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2708
TEST_TIME 2 3598
TEST_TIME 3 3385
TEST_TIME 4 3517
TEST_TIME 5 3817
TEST_TIME 6 4022
TEST_TIME 7 4189
TEST_TIME 8 4781
TEST_TIME 9 4887
TEST_TIME 10 4756
TEST_TIME 11 4995
TEST_TIME 12 5266
TEST_TIME 13 5505
TEST_TIME 14 5891
TEST_TIME 15 6042
TEST_TIME 16 6481
TEST_TIME 17 6617
TEST_TIME 18 6858
TEST_TIME 19 7684
TEST_TIME 20 8067
TEST_TIME 21 8244
TEST_TIME 22 8499
TEST_TIME 23 8655
TEST_TIME 24 9005
TEST_TIME 25 9043
TEST_TIME 26 9156
TEST_TIME 27 9344
TEST_TIME 28 10561
TEST_TIME 29 10485
TEST_TIME 30 10301
TEST_TIME 31 10227
TEST_TIME 32 10015
TEST_TIME 33 9934
TEST_TIME 34 9796
TEST_TIME 35 9946
TEST_TIME 36 9736
TEST_TIME 37 9782
TEST_TIME 38 9654
TEST_TIME 39 9669
TEST_TIME 40 9833
TEST_TIME 41 9733
TEST_TIME 42 9641
TEST_TIME 43 9554
TEST_TIME 44 9574
TEST_TIME 45 9469
TEST_TIME 46 9382
TEST_TIME 47 9481
TEST_TIME 48 9676
TEST_TIME 49 9644
TEST_TIME 50 9685
TEST_TIME 51 9659
TEST_TIME 52 9833
TEST_TIME 53 9767
TEST_TIME 54 9764
TEST_TIME 55 9795
TEST_TIME 56 9838
TEST_TIME 57 9922
TEST_TIME 58 9891
TEST_TIME 59 9974
TEST_TIME 60 9928
TEST_TIME 61 9794
TEST_TIME 62 9795
TEST_TIME 63 9705
TEST_TIME 64 9640
TEST_TIME 65 9669
TEST_TIME 66 9602
TEST_TIME 67 9615
TEST_TIME 68 9538
TEST_TIME 69 9526
TEST_TIME 70 9488
TEST_TIME 71 9458
TEST_TIME 72 9394
TEST_TIME 73 9346
TEST_TIME 74 9377
TEST_TIME 75 9392
TEST_TIME 76 9386
TEST_TIME 77 9399
TEST_TIME 78 9399
TEST_TIME 79 9404
TEST_TIME 80 9350
TEST_TIME 81 9510
TEST_TIME 82 9472
TEST_TIME 83 9459
TEST_TIME 84 9455
TEST_TIME 85 9466
TEST_TIME 86 9530
TEST_TIME 87 9476
TEST_TIME 88 9532
TEST_TIME 89 9449
TEST_TIME 90 9373
TEST_TIME 91 9365
TEST_TIME 92 9304
TEST_TIME 93 9275
TEST_TIME 94 9320
TEST_TIME 95 9309
TEST_TIME 96 9283
TEST_TIME 97 9293
TEST_TIME 98 9285
TEST_TIME 99 9418
TEST_TIME 100 9388
TEST_TIME 101 9359
TEST_TIME 102 9396
TEST_TIME 103 9415
TEST_TIME 104 9457
TEST_TIME 105 9468
TEST_TIME 106 9454
TEST_TIME 107 9508
TEST_TIME 108 9439
TEST_TIME 109 9422
TEST_TIME 110 9378
TEST_TIME 111 9371
TEST_TIME 112 9352
TEST_TIME 113 9323
TEST_TIME 114 9310
TEST_TIME 115 9273
TEST_TIME 116 9204
TEST_TIME 117 9193
TEST_TIME 118 9212
TEST_TIME 119 9202
TEST_TIME 120 9182

Performance per minute, chart


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

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 1534229 1534229 0 0.00 0 28804 6 0 0
doc_list_biud 1534219 1534219 0 0.00 0 46601 25 0 0
sp_add_invoice_to_stock 5272 5138 134 2.54 19 162514 17138 0 134
sp_cancel_adding_invoice 5687 5687 0 0.00 4 97322 763 0 0
sp_cancel_client_order 1881 1881 0 0.00 4 9074 366 0 0
sp_cancel_customer_reserve 546257 546257 0 0.00 0 23231 55 0 0
sp_cancel_pay_from_customer 715 715 0 0.00 3 5776 76 0 0
sp_cancel_pay_to_supplier 432 432 0 0.00 3 6959 91 0 0
sp_cancel_supplier_invoice 6259 6252 7 0.11 1 46612 751 0 7
sp_cancel_supplier_order 416 416 0 0.00 8 1091906 91786 0 0
sp_cancel_write_off 10073 10073 0 0.00 1 8255 96 0 0
sp_client_order 21274 21274 0 0.00 3 72438 350 0 0
sp_customer_reserve 467100 467100 0 0.00 0 61153 172 0 0
sp_fill_shopping_cart 32555 32555 0 0.00 1 24871 40 0 0
sp_fill_shopping_cart_clo_res 467096 467096 0 0.00 0 21948 14 0 0
sp_get_clo_for_invoice 5134 5134 0 0.00 0 35404 296 0 0
sp_kill_cost_storno 16907 16907 0 0.00 0 28800 49 0 0
sp_kill_qstorno_ret_qs2qd 1109255 1109255 0 0.00 0 46599 30 0 0
sp_kill_qty_storno 585986 585986 0 0.00 0 46601 65 0 0
sp_lock_selected_doc 21401 21401 0 0.00 0 8178 6 0 0
sp_make_cost_storno 28817 28817 0 0.00 0 27654 49 0 0
sp_multiply_rows_for_pdistr 28807 28807 0 0.00 0 13037 26 0 0
sp_multiply_rows_for_qdistr 21274 21274 0 0.00 0 72430 172 0 0
sp_pay_from_customer 7402 7402 0 0.00 4 12738 108 0 0
sp_pay_to_supplier 5987 5987 0 0.00 4 27666 131 0 0
sp_payment_common 13387 13387 0 0.00 1 8317 27 0 0
sp_qd_handle_on_cancel_clo 1881 1881 0 0.00 0 9037 327 0 0
sp_qd_handle_on_invoice_upd_sts 10816 10816 0 0.00 0 18925 315 0 0
sp_qd_handle_on_reserve_upd_sts 10291 10291 0 0.00 0 21832 61 0 0
sp_reserve_write_off 10294 10294 0 0.00 6 21878 181 0 0
sp_supplier_invoice 5608 5608 0 0.00 6 77218 3403 0 0
sp_supplier_order 5674 5674 0 0.00 5 41100 1246 0 0
srv_aggregate_perf_data 102 101 1 0.98 2345 209429 20889 0 1
srv_fill_mon_cache_memory 65 65 0 0.00 1381 128384 45059 0 0
srv_find_qd_qs_mism 1052838 1052838 0 0.00 0 16326 5 0 0
srv_make_invnt_saldo 576 576 0 0.00 27 25259 1012 0 0
srv_make_money_saldo 852 852 0 0.00 1 1392 25 0 0
srv_recalc_idx_stat 4 4 0 0.00 128819 431635 287828 0 0
t$perf-abend:sp_add_invoice_to_stock 134 134 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 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 5138 5138 0 0.00 0 18285 186 0 0
t$perf-norm:sp_cancel_adding_invoice 437 437 0 0.00 0 5709 106 0 0
t$perf-norm:sp_cancel_client_order 1881 1881 0 0.00 0 8352 23 0 0
t$perf-norm:sp_cancel_customer_reserve 677 677 0 0.00 0 3730 17 0 0
t$perf-norm:sp_cancel_pay_from_customer 715 715 0 0.00 0 2055 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 432 432 0 0.00 0 4380 23 0 0
t$perf-norm:sp_cancel_supplier_invoice 434 434 0 0.00 0 2430 22 0 0
t$perf-norm:sp_cancel_supplier_order 416 416 0 0.00 0 20351 1419 0 0
t$perf-norm:sp_cancel_write_off 989 989 0 0.00 0 1703 9 0 0
t$perf-norm:sp_client_order 21274 21274 0 0.00 0 23923 23 0 0
t$perf-norm:sp_customer_reserve 1021 1021 0 0.00 0 4359 23 0 0
t$perf-norm:sp_pay_from_customer 7402 7402 0 0.00 0 10158 14 0 0
t$perf-norm:sp_pay_to_supplier 5987 5987 0 0.00 0 11456 17 0 0
t$perf-norm:sp_reserve_write_off 10294 10294 0 0.00 0 8193 12 0 0
t$perf-norm:sp_supplier_invoice 5608 5608 0 0.00 0 7153 25 0 0
t$perf-norm:sp_supplier_order 5674 5674 0 0.00 0 10576 20 0 0
t$perf-norm:srv_aggregate_perf_data 101 101 0 0.00 0 903 24 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 1277 21 0 0
t$perf-norm:srv_make_invnt_saldo 576 576 0 0.00 0 4945 34 0 0
t$perf-norm:srv_make_money_saldo 852 852 0 0.00 0 3141 27 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 5 4 0 0
v_add_invoice_to_stock 10524 10256 268 2.54 0 5619 21 0 268
v_all_customers 38540 38540 0 0.00 0 18 1 0 0
v_all_suppliers 30864 30864 0 0.00 0 34 1 0 0
v_all_wares 42548 42548 0 0.00 0 24 1 0 0
v_cancel_adding_invoice 874 874 0 0.00 0 1985 11 0 0
v_cancel_client_order 3762 3762 0 0.00 0 3232 7 0 0
v_cancel_customer_prepayment 1430 1430 0 0.00 0 5630 9 0 0
v_cancel_customer_reserve 1354 1354 0 0.00 0 470 4 0 0
v_cancel_payment_to_supplier 864 864 0 0.00 0 2394 9 0 0
v_cancel_supplier_invoice 882 868 14 1.58 0 1572 16 0 14
v_cancel_supplier_order 832 832 0 0.00 0 5620 13 0 0
v_cancel_write_off 1978 1978 0 0.00 0 4067 16 0 0
v_max_id_clo_ord 5674 5674 0 0.00 0 594 2 0 0
v_max_id_clo_res 1021 1021 0 0.00 0 2965 17 0 0
v_max_id_ord_sup 5607 5607 0 0.00 0 3661 4 0 0
v_max_non_paid_invoice 1835 1835 0 0.00 0 946 3 0 0
v_max_non_paid_realizn 4110 4110 0 0.00 0 2058 4 0 0
v_min_id_clo_ord 5674 5674 0 0.00 0 5814 10 0 0
v_min_id_clo_res 1021 1021 0 0.00 0 7660 26 0 0
v_min_id_ord_sup 5607 5607 0 0.00 0 8392 9 0 0
v_min_non_paid_invoice 5986 5986 0 0.00 0 6629 19 0 0
v_min_non_paid_realizn 7401 7401 0 0.00 0 8311 18 0 0
v_our_firm 10590 10590 0 0.00 0 19 1 0 0
v_reserve_write_off 20582 20582 0 0.00 0 5633 8 0 0
x_lock_depdocs_on_canc_invoice 11505 11505 0 0.00 0 21640 115 0 0
x_lock_depdocs_on_canc_sup_ord 416 416 0 0.00 0 11623 358 0 0
x_make_qstorno_1000_1200 5674 5674 0 0.00 1 41088 1161 0 0
x_make_qstorno_1200_2000 5608 5608 0 0.00 2 77211 3262 0 0
x_make_qstorno_2100_3300 467100 467100 0 0.00 0 57635 155 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 19-02-2023 01:40:23 to 19-02-2023 01:40:28.

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
23:08:45 11 8192000000 8811168176 8826556416 15342400 0.186 5 4 3 0 15506720 84016 80304
23:09:47 22572 8192000000 9903751168 10212720640 984856528 10.731 100 91 182 0 1015256368 6412192 23987648
23:11:13 16736 8192000000 9936318800 10255908864 1019649184 11.069 100 81 162 0 1048828560 8481376 20698000
23:12:34 15948 8192000000 9971597888 10301149184 1055249440 11.411 100 82 164 0 1085298544 9991968 20057136
23:13:55 10800 8192000000 10009604768 10352193536 1097997680 11.819 100 79 158 0 1128980704 11475184 19507840
23:15:11 6370 8192000000 10058882288 10411810816 1151142864 12.320 100 79 156 0 1181097424 12320560 17634000
23:16:19 5428 8192000000 10097989664 10463006720 1187631200 12.661 100 76 152 0 1218279392 13099600 17548592
23:17:26 2144 8192000000 10135414912 10509496320 1223616224 12.995 100 81 162 0 1254771168 13047776 18107168
23:18:30 1400 8192000000 10166507664 10549501952 1259989216 13.330 100 78 155 0 1290121904 12435120 17697568
23:19:32 1769 8192000000 10203257248 10587291648 1288051024 13.586 100 79 157 1 1317800128 12958192 16790912
23:20:36 25713 8192000000 10209753856 10603257856 1301358624 13.708 100 60 119 0 1323909056 9068480 13481952
23:22:02 4758 8192000000 10208536848 10614231040 1308924896 13.776 100 34 66 2 1327655392 11005424 7725072
23:23:10 9294 8192000000 10199416096 10617204736 1318132080 13.860 100 16 32 0 1327465280 5781904 3551296
23:24:23 12355 8192000000 10215703344 10631602176 1327048560 13.940 100 17 32 1 1336331456 5672992 3609904
23:25:42 39978 8192000000 10221740368 10648535040 1334494144 14.008 100 17 34 0 1343615168 5600832 3520192
23:27:23 4478 8192000000 10235940544 10657771520 1342495808 14.080 100 28 56 0 1355325136 7092128 5737200
23:28:30 4797 8192000000 10213187168 10634883072 1326175280 13.933 100 16 32 0 1335210208 5589664 3445264
23:29:35 56150 8192000000 10169090160 10569297920 1229421568 13.049 94 37 70 0 1242703376 4542864 8738944
23:31:35 6507 8192000000 10121685184 10513645568 1178367488 12.575 94 23 46 0 1188982128 4652000 5962640
23:32:43 8496 8192000000 10145152608 10548764672 1245212864 13.194 99 23 44 1 1255986880 5962784 4811232
23:33:53 7722 8192000000 10156880672 10557358080 1263366272 13.361 100 19 37 0 1272621472 5236320 4018880
23:35:02 22006 8192000000 10151167888 10560286720 1255137136 13.285 99 18 36 0 1269338992 10262784 3939072
23:36:26 6752 8192000000 10180141520 10584641536 1280763808 13.520 100 29 58 0 1293578480 6730016 6084656
23:37:33 10437 8192000000 10185841856 10597183488 1294466208 13.645 100 17 34 2 1303247856 4861936 3919712
23:38:45 64607 8192000000 10230192320 10642907136 1326491456 13.935 100 32 64 0 1339638272 5236000 7910816
23:40:52 11696 8192000000 10243062464 10659135488 1343244608 14.087 100 46 89 1 1361686464 8582464 9859392
23:42:12 28518 8192000000 10273761568 10692182016 1362070768 14.256 100 41 82 1 1385169424 13962704 9135952
23:43:50 24905 8192000000 10293143632 10712940544 1377903440 14.398 100 35 70 0 1405373264 19103312 8366512
23:45:21 22613 8192000000 10301762944 10727964672 1390289792 14.508 100 39 78 1 1420010768 20896000 8824976
23:46:54 28102 8192000000 10315724304 10742910976 1396123504 14.560 100 40 79 1 1428834704 23298656 9412544
23:48:41 10859 8192000000 10322469488 10752507904 1400785888 14.602 100 33 65 1 1435250656 26382672 8082096
23:50:01 8374 8192000000 10342474656 10764623872 1404138496 14.632 100 59 120 2 1446769728 28459856 14171376
23:51:21 5326 8192000000 10365458752 10784038912 1417453232 14.750 100 72 144 1 1469649664 33898800 18297632
23:52:33 4249 8192000000 10343096160 10759581696 1397887536 14.576 100 64 127 0 1446896848 33638736 15370576
23:53:43 4228 8192000000 10339715008 10748624896 1387755824 14.486 100 76 152 0 1446158000 40103168 18299008
23:54:53 2374 8192000000 10328429120 10730962944 1360260560 14.240 98 75 148 0 1418839120 40840720 17737840
23:56:05 1381 8192000000 10335035504 10737627136 1373445136 14.358 100 82 164 0 1436230176 43183296 19601744
23:57:21 1793 8192000000 10340323808 10741080064 1364872592 14.281 100 83 167 1 1439924512 54811840 20240080
23:58:27 13385 8192000000 10279427936 10691870720 1327709024 13.946 99 40 80 4 1381327248 42745648 10872576
23:59:44 8958 8192000000 10302938992 10721071104 1358669232 14.225 100 41 83 1 1411344512 41890352 10784928
00:00:56 9999 8192000000 10287508832 10720735232 1349371792 14.142 99 29 58 1 1396200240 38855760 7972688
00:02:10 17663 8192000000 10297673872 10720047104 1359483872 14.233 100 32 64 1 1402372528 34358752 8529904
00:03:29 17478 8192000000 10300746432 10735636480 1361330752 14.249 100 21 42 0 1408559856 40981184 6247920
00:04:50 14841 8192000000 10304209168 10736390144 1350294912 14.150 99 29 58 0 1398814096 40394416 8124768
00:06:11 59080 8192000000 10262334272 10706788352 1334368704 14.007 98 13 26 0 1375267984 37392096 3507184
00:08:14 24266 8192000000 10273696800 10710884352 1349234720 14.141 99 8 17 0 1357081504 6078448 1768336
00:09:43 44434 8192000000 10286630400 10712510464 1338078800 14.040 97 29 55 0 1357255088 13106336 6069952
00:12:41 109015 8192000000 10296575040 10748145664 1370119248 14.328 99 22 44 0 1387088784 11670624 5298912
00:15:32 64825 8192000000 10312396848 10764447744 1396805408 14.567 100 18 35 0 1413073168 12555904 3711856
00:17:40 39397 8192000000 10311644016 10773565440 1402631232 14.618 100 26 51 1 1422926448 13887632 6407584
00:19:33 45763 8192000000 10309002592 10764242944 1395285840 14.553 99 23 46 0 1409783792 9170848 5327104
00:21:19 39985 8192000000 10308019792 10766356480 1398067648 14.578 99 18 35 0 1413682352 11637920 3976784
00:23:20 71760 8192000000 10312653968 10780078080 1405349104 14.643 100 15 30 0 1424441952 15607648 3485200
00:25:57 71515 8192000000 10327649328 10809274368 1414412688 14.723 100 4 8 0 1433567008 18325600 828720
00:28:12 56913 8192000000 10338327392 10797072384 1405508048 14.644 99 24 43 0 1427528512 16922928 5097536
00:30:12 74486 8192000000 10316668144 10774880256 1373201584 14.356 97 15 30 1 1391871296 14461664 4208048
00:32:30 33896 8192000000 10332234080 10784092160 1419326656 14.767 100 34 66 0 1435722032 8536880 7858496
00:34:05 91271 8192000000 10318943232 10788712448 1373666592 14.360 98 9 18 0 1396400064 20926800 1806672
00:36:37 65349 8192000000 10267851408 10728378368 1279559152 13.509 92 14 28 0 1293119472 10604480 2955840
00:38:47 112757 8192000000 10272595248 10718396416 1313576272 13.819 95 31 62 2 1335975536 15081120 7318144
00:41:48 18341 8192000000 10261180080 10718126080 1348462496 14.134 99 14 28 0 1366127488 15070240 2594752
00:43:11 65098 8192000000 10238690528 10688761856 1302255664 13.716 96 24 42 1 1317035344 10789568 3990112
00:45:17 46783 8192000000 10247672352 10702376960 1325462944 13.926 98 13 26 0 1338650480 10254288 2933248
00:47:04 46649 8192000000 10253891568 10702467072 1331224848 13.978 98 6 12 0 1344457024 11454752 1777424
00:48:53 64847 8192000000 10255754224 10712305664 1361910544 14.255 100 17 34 0 1372402160 6786672 3704944
00:50:58 19088 8192000000 10282160560 10730086400 1367314432 14.303 99 26 52 0 1384421872 11090144 6017296
00:52:18 79575 8192000000 10266685040 10740441088 1361905696 14.254 99 14 28 0 1377721392 12327824 3487872
00:54:40 52805 8192000000 10272333680 10736496640 1352096688 14.166 98 20 40 1 1366966352 10383872 4485792
00:56:33 55965 8192000000 10282306368 10738487296 1361827856 14.254 99 21 42 0 1379604112 12865040 4911216
00:58:29 16363 8192000000 10302096448 10741968896 1380186784 14.418 100 44 86 1 1403232320 13807216 9238320
00:59:50 45532 8192000000 10275587392 10716323840 1338664224 14.045 98 28 56 0 1355782768 11119024 5999520
01:01:37 9350 8192000000 10301514416 10738520064 1373450848 14.358 100 42 84 0 1398806656 15604560 9751248
01:02:50 13208 8192000000 10292373920 10733809664 1374271792 14.365 100 48 96 1 1396938464 12228096 10438576
01:04:04 63975 8192000000 10288078528 10735120384 1358051584 14.220 98 20 40 0 1373562336 11371904 4138848
01:06:10 107945 8192000000 10282993616 10746552320 1374895808 14.371 99 28 56 0 1395006688 13707888 6402992
01:09:03 34402 8192000000 10278770432 10744250368 1366417168 14.295 98 20 40 0 1380571680 9311536 4842976
01:10:47 36091 8192000000 10293196576 10761867264 1394213024 14.543 100 7 14 0 1402420976 6966160 1241792
01:12:27 83405 8192000000 10306828848 10763079680 1384127232 14.453 99 18 37 0 1399775792 11048864 4599696
01:14:53 38870 8192000000 10311544192 10766331904 1387126912 14.480 99 30 60 0 1410112528 16502192 6483424
01:16:33 79705 8192000000 10297698576 10768044032 1359875568 14.236 97 19 38 0 1379096608 14763056 4457984
01:19:43 48578 8192000000 10294391920 10753654784 1336992096 14.030 96 40 80 0 1357195776 11342720 8860960
01:21:41 83566 8192000000 10296932432 10764349440 1330845616 13.975 96 7 14 0 1349882720 17869936 1167168
01:24:05 110229 8192000000 10287812592 10749702144 1342736896 14.082 97 7 14 0 1357498256 13465184 1296176
01:26:55 128383 8192000000 10288046112 10737680384 1368168304 14.311 99 33 61 2 1384022320 8390912 7463104
01:30:06 66362 8192000000 10264946144 10730139648 1352101264 14.166 98 12 24 0 1363727648 8742384 2884000
01:32:15 47055 8192000000 10274300672 10725761024 1351702160 14.163 98 14 29 0 1361831232 6716672 3412400
01:34:04 63523 8192000000 10275245040 10731020288 1378568448 14.404 100 21 42 0 1394237200 10577760 5090992
01:36:13 63764 8192000000 10232630320 10686558208 1295011920 13.650 96 18 34 1 1312690640 13988128 3690592
01:38:17 33267 8192000000 9457683168 9782247424 599979440 6.824 46 2 4 0 604815616 4436544 399632

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 19-02-2023 01:40:29 to 19-02-2023 01:40:45.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 134 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 19-02-2023 01:40:46 to 19-02-2023 01:40:48.

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          245407
MON$OLDEST_ACTIVE               245408
MON$OLDEST_SNAPSHOT             245408
MON$NEXT_TRANSACTION            245408
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                       2032032
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.33665 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33665 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33665 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33665 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 ---
:225: 
:226: mscs-test-161.budzdorov.ru    Sun Feb 19 01:38:53 2023
:227:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:228: 
--- end of diff output ---

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

19.02.23 01:40:49. 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-02-19 01:40:52 No severe PSQL-related problems occured
19.02.23 01:40:53. End of report.