Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 25.09.2022 16:43
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 15618728 kB
MemAvailable 30594088 kB
Buffers 0 kB
Cached 16092152 kB
SwapCached 2120 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-09-25 16:42:31
DTS_END                         2022-09-25 16:42:31.7040
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/58898
TRN_ID                          239795
ATT_ID                          631
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: 9316.07 18546 1117928
customer order: creation 173.13 377 20775
customer order: refuse 14.56 382 1747
order to supplier: creation 44.99 1307 5399
order to supplier: removal 3.53 99515 424
invoice (draft): creation 45.99 3244 5519
invoice (draft): removal 54.35 772 6522
invoice accept: apply 40.59 18769 4871
invoice accept: cancel 48.94 775 5873
customer reserve: creation 3814.78 183 457774
customer reserve: removal 4781.88 57 573826
realization accept: apply 83.23 174 9987
realization accept: cancel 82.99 120 9959
payment from customer: creation 58.33 111 6999
payment from customer: removal 5.42 88 650
payment to supplier: creation 48.66 130 5839
payment to supplier: removal 3.25 50 390
service: total inventory turnovers 4.60 1215 552
service: total monetary turnovers 6.08 22 730
service: aggregate perf. data 0.73 25905 88
service: refresh index statistics 0.03 217732 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 25-09-2022 16:43:22 to 25-09-2022 16:43:24.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3155
TEST_TIME 2 5419
TEST_TIME 3 5710
TEST_TIME 4 5366
TEST_TIME 5 4839
TEST_TIME 6 4884
TEST_TIME 7 5118
TEST_TIME 8 5075
TEST_TIME 9 5126
TEST_TIME 10 5465
TEST_TIME 11 5527
TEST_TIME 12 5621
TEST_TIME 13 6087
TEST_TIME 14 6163
TEST_TIME 15 6350
TEST_TIME 16 6355
TEST_TIME 17 6408
TEST_TIME 18 7082
TEST_TIME 19 7536
TEST_TIME 20 8021
TEST_TIME 21 8123
TEST_TIME 22 8382
TEST_TIME 23 8623
TEST_TIME 24 8910
TEST_TIME 25 9283
TEST_TIME 26 9373
TEST_TIME 27 10236
TEST_TIME 28 10648
TEST_TIME 29 10419
TEST_TIME 30 10129
TEST_TIME 31 10243
TEST_TIME 32 10357
TEST_TIME 33 10158
TEST_TIME 34 10109
TEST_TIME 35 10002
TEST_TIME 36 9961
TEST_TIME 37 9987
TEST_TIME 38 9815
TEST_TIME 39 9668
TEST_TIME 40 9663
TEST_TIME 41 9586
TEST_TIME 42 9495
TEST_TIME 43 9473
TEST_TIME 44 9547
TEST_TIME 45 9560
TEST_TIME 46 9568
TEST_TIME 47 9542
TEST_TIME 48 9657
TEST_TIME 49 9602
TEST_TIME 50 9481
TEST_TIME 51 9615
TEST_TIME 52 9806
TEST_TIME 53 9780
TEST_TIME 54 9755
TEST_TIME 55 9797
TEST_TIME 56 9761
TEST_TIME 57 9703
TEST_TIME 58 9601
TEST_TIME 59 9522
TEST_TIME 60 9483
TEST_TIME 61 9527
TEST_TIME 62 9492
TEST_TIME 63 9379
TEST_TIME 64 9366
TEST_TIME 65 9329
TEST_TIME 66 9373
TEST_TIME 67 9360
TEST_TIME 68 9358
TEST_TIME 69 9465
TEST_TIME 70 9459
TEST_TIME 71 9456
TEST_TIME 72 9498
TEST_TIME 73 9425
TEST_TIME 74 9524
TEST_TIME 75 9642
TEST_TIME 76 9619
TEST_TIME 77 9640
TEST_TIME 78 9692
TEST_TIME 79 9590
TEST_TIME 80 9558
TEST_TIME 81 9501
TEST_TIME 82 9538
TEST_TIME 83 9517
TEST_TIME 84 9473
TEST_TIME 85 9449
TEST_TIME 86 9442
TEST_TIME 87 9393
TEST_TIME 88 9355
TEST_TIME 89 9340
TEST_TIME 90 9333
TEST_TIME 91 9293
TEST_TIME 92 9267
TEST_TIME 93 9336
TEST_TIME 94 9386
TEST_TIME 95 9391
TEST_TIME 96 9360
TEST_TIME 97 9389
TEST_TIME 98 9452
TEST_TIME 99 9451
TEST_TIME 100 9429
TEST_TIME 101 9510
TEST_TIME 102 9500
TEST_TIME 103 9536
TEST_TIME 104 9531
TEST_TIME 105 9464
TEST_TIME 106 9453
TEST_TIME 107 9413
TEST_TIME 108 9409
TEST_TIME 109 9374
TEST_TIME 110 9347
TEST_TIME 111 9365
TEST_TIME 112 9338
TEST_TIME 113 9308
TEST_TIME 114 9305
TEST_TIME 115 9323
TEST_TIME 116 9295
TEST_TIME 117 9316
TEST_TIME 118 9318
TEST_TIME 119 9355
TEST_TIME 120 9319

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 25-09-2022 16:43:26 to 25-09-2022 16:43:29.

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 1544136 1544136 0 0.00 0 23738 6 0 0
doc_list_biud 1544135 1544135 0 0.00 0 64383 27 0 0
sp_add_invoice_to_stock 5007 4871 136 2.71 37 196849 18770 0 136
sp_cancel_adding_invoice 5873 5873 0 0.00 4 78436 775 0 0
sp_cancel_client_order 1747 1747 0 0.00 4 25558 383 0 0
sp_cancel_customer_reserve 573826 573826 0 0.00 0 64385 58 0 0
sp_cancel_pay_from_customer 650 650 0 0.00 3 6056 89 0 0
sp_cancel_pay_to_supplier 390 390 0 0.00 3 1449 51 0 0
sp_cancel_supplier_invoice 6540 6522 18 0.27 1 27728 773 0 18
sp_cancel_supplier_order 424 424 0 0.00 7 1066507 99515 0 0
sp_cancel_write_off 9959 9959 0 0.00 1 21947 121 0 0
sp_client_order 20775 20775 0 0.00 3 28914 377 0 0
sp_customer_reserve 457774 457774 0 0.00 0 53370 184 0 0
sp_fill_shopping_cart 31678 31678 0 0.00 1 12839 38 0 0
sp_fill_shopping_cart_clo_res 457770 457770 0 0.00 0 15744 15 0 0
sp_get_clo_for_invoice 4863 4863 0 0.00 0 22393 339 0 0
sp_kill_cost_storno 16872 16872 0 0.00 0 7863 49 0 0
sp_kill_qstorno_ret_qs2qd 1164557 1164557 0 0.00 0 64381 31 0 0
sp_kill_qty_storno 613200 613200 0 0.00 0 64383 68 0 0
sp_lock_selected_doc 20579 20579 0 0.00 0 8441 8 0 0
sp_make_cost_storno 27687 27687 0 0.00 0 23735 51 0 0
sp_multiply_rows_for_pdistr 27687 27687 0 0.00 0 11549 24 0 0
sp_multiply_rows_for_qdistr 20775 20775 0 0.00 0 14661 167 0 0
sp_pay_from_customer 6999 6999 0 0.00 4 19936 111 0 0
sp_pay_to_supplier 5839 5839 0 0.00 4 22510 131 0 0
sp_payment_common 12838 12838 0 0.00 1 19930 27 0 0
sp_qd_handle_on_cancel_clo 1747 1747 0 0.00 0 25551 337 0 0
sp_qd_handle_on_invoice_upd_sts 10735 10735 0 0.00 0 24933 314 0 0
sp_qd_handle_on_reserve_upd_sts 9987 9987 0 0.00 0 13754 63 0 0
sp_reserve_write_off 9987 9987 0 0.00 6 23756 174 0 0
sp_supplier_invoice 5519 5519 0 0.00 5 78572 3245 0 0
sp_supplier_order 5399 5399 0 0.00 4 46876 1307 0 0
srv_aggregate_perf_data 89 88 1 1.12 2664 273345 25905 0 1
srv_fill_mon_cache_memory 69 69 0 0.00 2470 141815 39602 0 0
srv_find_qd_qs_mism 1071738 1071738 0 0.00 0 16656 5 0 0
srv_make_invnt_saldo 552 552 0 0.00 47 64606 1216 0 0
srv_make_money_saldo 730 730 0 0.00 1 332 23 0 0
srv_recalc_idx_stat 4 4 0 0.00 117748 289578 217733 0 0
t$perf-abend:sp_add_invoice_to_stock 136 136 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 18 18 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 4871 4871 0 0.00 0 9357 184 0 0
t$perf-norm:sp_cancel_adding_invoice 406 406 0 0.00 0 4801 114 0 0
t$perf-norm:sp_cancel_client_order 1747 1747 0 0.00 0 3230 13 0 0
t$perf-norm:sp_cancel_customer_reserve 712 712 0 0.00 0 2163 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 650 650 0 0.00 0 1546 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 390 390 0 0.00 0 4570 25 0 0
t$perf-norm:sp_cancel_supplier_invoice 395 395 0 0.00 0 1801 14 0 0
t$perf-norm:sp_cancel_supplier_order 424 424 0 0.00 0 26646 1741 0 0
t$perf-norm:sp_cancel_write_off 1007 1007 0 0.00 0 899 6 0 0
t$perf-norm:sp_client_order 20775 20775 0 0.00 0 9418 16 0 0
t$perf-norm:sp_customer_reserve 1019 1019 0 0.00 0 2644 9 0 0
t$perf-norm:sp_pay_from_customer 6999 6999 0 0.00 0 9346 13 0 0
t$perf-norm:sp_pay_to_supplier 5839 5839 0 0.00 0 7356 13 0 0
t$perf-norm:sp_reserve_write_off 9987 9987 0 0.00 0 9919 14 0 0
t$perf-norm:sp_supplier_invoice 5519 5519 0 0.00 0 9785 21 0 0
t$perf-norm:sp_supplier_order 5399 5399 0 0.00 0 5657 15 0 0
t$perf-norm:srv_aggregate_perf_data 88 88 0 0.00 0 394 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 61 3 0 0
t$perf-norm:srv_make_invnt_saldo 552 552 0 0.00 0 1381 22 0 0
t$perf-norm:srv_make_money_saldo 730 730 0 0.00 0 545 12 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 17 6 0 0
v_add_invoice_to_stock 9994 9722 272 2.72 0 9226 26 0 272
v_all_customers 37948 37948 0 0.00 0 180 1 0 0
v_all_suppliers 30626 30626 0 0.00 0 167 1 0 0
v_all_wares 41546 41546 0 0.00 0 25 1 0 0
v_cancel_adding_invoice 812 812 0 0.00 0 4189 12 0 0
v_cancel_client_order 3494 3494 0 0.00 0 3666 10 0 0
v_cancel_customer_prepayment 1300 1300 0 0.00 0 6023 13 0 0
v_cancel_customer_reserve 1424 1424 0 0.00 0 6884 14 0 0
v_cancel_payment_to_supplier 780 780 0 0.00 0 573 5 0 0
v_cancel_supplier_invoice 826 790 36 4.35 0 3331 22 0 36
v_cancel_supplier_order 848 848 0 0.00 0 1442 5 0 0
v_cancel_write_off 2014 2014 0 0.00 0 9015 20 0 0
v_max_id_clo_ord 5396 5396 0 0.00 0 6601 3 0 0
v_max_id_clo_res 1019 1019 0 0.00 0 2578 15 0 0
v_max_id_ord_sup 5509 5509 0 0.00 0 1894 2 0 0
v_max_non_paid_invoice 1431 1431 0 0.00 0 1082 3 0 0
v_max_non_paid_realizn 3643 3643 0 0.00 0 2489 5 0 0
v_min_id_clo_ord 5396 5396 0 0.00 0 5073 8 0 0
v_min_id_clo_res 1019 1019 0 0.00 0 1815 5 0 0
v_min_id_ord_sup 5509 5509 0 0.00 0 6267 9 0 0
v_min_non_paid_invoice 5839 5839 0 0.00 0 8814 15 0 0
v_min_non_paid_realizn 6999 6999 0 0.00 0 19925 22 0 0
v_our_firm 10310 10310 0 0.00 0 81 1 0 0
v_reserve_write_off 19974 19974 0 0.00 0 6945 7 0 0
x_lock_depdocs_on_canc_invoice 12000 12000 0 0.00 0 15263 115 0 0
x_lock_depdocs_on_canc_sup_ord 424 424 0 0.00 0 8285 339 0 0
x_make_qstorno_1000_1200 5399 5399 0 0.00 1 46848 1235 0 0
x_make_qstorno_1200_2000 5519 5519 0 0.00 2 78121 3107 0 0
x_make_qstorno_2100_3300 457774 457774 0 0.00 0 53368 166 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 25-09-2022 16:43:30 to 25-09-2022 16:43:34.

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
14:12:35 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:13:37 29487 8192000000 9924120528 10242330624 998719936 10.866 100 82 162 1 1026702432 6441744 21540752
14:15:11 16459 8192000000 9959884176 10284957696 1044247888 11.305 100 88 176 0 1075831664 8763072 22820704
14:16:38 19150 8192000000 9988907472 10325557248 1073471680 11.585 100 75 150 0 1101813536 9510992 18830864
14:18:14 13299 8192000000 10047290048 10399596544 1133522992 12.155 100 75 148 0 1161943616 10537712 17882912
14:19:29 4617 8192000000 10095412928 10459435008 1188211584 12.667 100 79 158 1 1218365264 12328432 17825248
14:20:37 2854 8192000000 10135823440 10504912896 1226269568 13.020 100 88 175 1 1259097760 13690992 19137200
14:21:42 1416 8192000000 10171445920 10544820224 1253654624 13.272 100 89 179 1 1286895360 13844864 19395872
14:22:45 1643 8192000000 10206734912 10593382400 1293620432 13.637 100 91 180 1 1327524208 12997264 20906512
14:23:48 839 8192000000 10226540256 10613112832 1312595632 13.810 100 88 174 1 1344002064 12517984 18888448
14:24:49 32925 8192000000 10215917744 10615422976 1310552512 13.791 100 54 105 2 1328759680 6132368 12074800
14:26:22 9772 8192000000 10201300688 10617982976 1314400256 13.826 100 22 44 0 1325474912 6163280 4911376
14:27:35 9827 8192000000 10214534144 10635730944 1327459472 13.944 100 20 41 0 1336959072 5601696 3897904
14:28:48 6524 8192000000 10220962928 10641694720 1333429168 13.998 100 25 50 0 1345306224 6225056 5652000
14:29:57 9604 8192000000 10218610816 10635177984 1330503088 13.972 99 25 48 0 1342694464 6708080 5483296
14:31:13 17312 8192000000 10217766432 10636288000 1309338688 13.780 98 45 91 0 1326686032 6268592 11078752
14:32:31 7980 8192000000 10203005680 10615918592 1294505088 13.645 97 15 30 0 1302590864 4449840 3635936
14:33:43 5407 8192000000 10173265504 10579410944 1251186544 13.249 97 27 52 2 1263071456 5724352 6160560
14:34:54 12523 8192000000 10130871136 10525020160 1156041040 12.366 91 17 36 0 1164681104 4762704 3877360
14:36:12 22560 8192000000 10127368032 10526453760 1193897744 12.720 95 21 40 0 1203665056 4996816 4770496
14:37:35 8507 8192000000 10160805776 10565861376 1259739232 13.328 99 34 68 2 1273732608 5757488 8235888
14:38:47 9642 8192000000 10184903952 10595934208 1292575024 13.628 100 20 40 0 1302479536 5122416 4782096
14:39:58 23219 8192000000 10190608256 10610917376 1307789584 13.766 100 27 52 0 1319977440 5380992 6806864
14:41:21 12636 8192000000 10205064816 10613489664 1306093200 13.751 100 27 52 0 1317780064 6374416 5312448
14:42:36 4756 8192000000 10236692048 10647146496 1330331760 13.970 100 42 76 3 1347610032 9418320 7859952
14:43:57 6976 8192000000 10256779552 10668679168 1345684784 14.109 100 51 102 0 1370677840 13744864 11248192
14:45:09 21590 8192000000 10272864736 10694217728 1362413408 14.259 100 41 81 0 1386879488 15201216 9264864
14:46:33 21293 8192000000 10276490128 10702073856 1372297328 14.348 100 38 76 1 1395303104 13806784 9198992
14:48:23 20335 8192000000 10297887696 10718179328 1375222832 14.374 99 42 82 1 1401878512 17251600 9404080
14:49:52 6579 8192000000 10314080400 10737549312 1396412448 14.563 100 48 96 0 1426463440 18289632 11761360
14:51:02 11962 8192000000 10325896896 10741903360 1384779888 14.459 99 60 119 1 1421688800 22126480 14782432
14:52:18 8096 8192000000 10309281920 10730528768 1377234416 14.392 99 51 100 1 1412472304 23367232 11870656
14:53:32 8483 8192000000 10332602896 10753777664 1400173296 14.597 100 54 107 0 1440530544 26715184 13642064
14:54:47 2468 8192000000 10361664832 10784755712 1420926784 14.781 100 67 130 0 1470450416 33583840 15939792
14:55:54 3725 8192000000 10359933792 10774327296 1413283040 14.713 100 73 144 1 1466498816 36639312 16576464
14:57:01 2576 8192000000 10359337472 10776973312 1410703600 14.690 100 68 134 0 1464220080 36922736 16593744
14:58:07 3018 8192000000 10365566976 10781278208 1406453984 14.652 100 77 149 0 1467306736 41268800 19583952
14:59:15 2657 8192000000 10361889504 10775142400 1395698512 14.557 99 70 139 1 1459066496 44569824 18798160
15:00:22 7058 8192000000 10354482848 10768134144 1392204064 14.526 100 69 136 0 1458200000 48005376 17990560
15:01:35 2615 8192000000 10361134832 10769121280 1393257696 14.535 100 85 169 0 1465271968 50380784 21633488
15:02:44 5396 8192000000 10348178224 10768625664 1383237296 14.445 99 57 114 0 1445629040 47162784 15228960
15:04:06 9002 8192000000 10360748000 10790617088 1404992096 14.639 100 45 90 0 1468327600 50693264 12642240
15:05:26 16380 8192000000 10314362656 10746482688 1368359376 14.312 100 31 62 0 1416786912 40139456 8288080
15:06:47 22004 8192000000 10315500032 10761728000 1372903392 14.353 100 25 50 0 1435473008 55520848 7048768
15:08:10 16431 8192000000 10290146512 10723250176 1352002704 14.165 100 20 40 1 1393365232 35319824 6042704
15:09:28 25699 8192000000 10243003440 10676203520 1285648912 13.565 95 18 37 0 1324104640 32907664 5548064
15:10:56 34247 8192000000 10239289216 10681683968 1304273840 13.734 98 12 24 1 1315885664 8816384 2795440
15:13:15 67981 8192000000 10268819360 10717536256 1350079664 14.148 100 17 34 0 1379997264 26139712 3777888
15:15:23 16027 8192000000 10270285808 10721869824 1359069520 14.229 100 24 47 0 1381436544 17193856 5173168
15:16:41 22178 8192000000 10269109312 10707230720 1352027632 14.166 99 31 59 1 1368905184 10585728 6291824
15:18:03 51539 8192000000 10262249776 10712240128 1331926160 13.985 97 26 50 0 1350706576 13074480 5705936
15:20:00 27580 8192000000 10272188448 10723622912 1371668576 14.342 100 12 24 0 1387026512 12001552 3356384
15:21:28 55752 8192000000 10277962944 10732404736 1372759952 14.352 99 19 38 0 1389383872 11468208 5155712
15:23:26 83225 8192000000 10301087984 10766696448 1392140224 14.525 100 14 29 0 1414234736 18405424 3689088
15:26:04 78746 8192000000 10299352768 10760409088 1401173056 14.605 100 9 19 0 1420173648 17031648 1968944
15:28:25 49084 8192000000 10319619520 10775433216 1404115008 14.632 100 38 76 0 1427886112 14953168 8817936
15:30:18 54695 8192000000 10301500848 10763161600 1390397280 14.509 99 7 14 0 1402656160 10489440 1769440
15:32:14 31959 8192000000 10325463648 10771734528 1409330400 14.678 100 31 60 0 1427416784 11289408 6796976
15:33:50 90754 8192000000 10310456688 10784522240 1376726784 14.387 98 8 16 0 1398934016 20145456 2061776
15:36:29 74740 8192000000 10312314256 10765045760 1353804128 14.182 97 17 33 0 1367588720 10316048 3468544
15:38:45 114674 8192000000 10312266416 10756493312 1388015920 14.488 99 35 71 0 1410722512 15110096 7596496
15:41:44 35874 8192000000 10312667312 10758316032 1387786368 14.486 99 49 97 0 1412574160 13681760 11106032
15:43:21 58230 8192000000 10291296784 10758782976 1368186512 14.311 97 15 30 0 1379478320 7731504 3560304
15:45:21 35660 8192000000 10287311600 10751934464 1358813152 14.227 97 4 8 0 1369384528 9921344 650032
15:46:58 44220 8192000000 10267394720 10718162944 1305536304 13.746 94 17 34 0 1317726208 8576256 3613648
15:48:42 33822 8192000000 10285674064 10734829568 1358857136 14.227 98 26 48 2 1376551856 11707616 5987104
15:50:16 48269 8192000000 10279579152 10730774528 1349489408 14.143 98 10 20 0 1368671728 16653840 2528480
15:52:36 18182 8192000000 10277694912 10718818304 1358766960 14.226 99 33 66 0 1374998896 9344448 6887488
15:53:56 12841 8192000000 10303403104 10738429952 1377475632 14.394 100 40 80 0 1406007472 19794368 8737472
15:55:10 56492 8192000000 10307176784 10746077184 1356027072 14.202 98 35 69 0 1384063696 20306624 7730000
15:57:07 65609 8192000000 10270895648 10736750592 1322557232 13.900 96 4 8 0 1333547808 10399280 591296
15:59:15 25555 8192000000 10281804592 10721509376 1360260208 14.240 100 29 58 1 1374904080 8320848 6323024
16:00:41 141814 8192000000 10250581168 10696347648 1309305200 13.780 96 21 42 2 1325048400 11131184 4612016
16:04:03 12631 8192000000 10273781744 10715549696 1361324544 14.249 100 30 60 0 1383337872 14587760 7425568
16:05:16 49532 8192000000 10252252432 10715185152 1354487920 14.188 100 14 29 0 1370431664 12203600 3740144
16:07:06 74410 8192000000 10260455248 10714632192 1352345376 14.169 99 12 24 0 1363608976 8094000 3169600
16:09:21 58711 8192000000 10256609936 10714914816 1350360784 14.151 99 15 28 0 1363046128 9198448 3486896
16:11:22 67729 8192000000 10266410400 10730037248 1360965696 14.246 99 16 30 0 1376006976 11526080 3515200
16:13:32 44326 8192000000 10289019888 10739023872 1386284288 14.473 100 18 34 0 1399858400 9924080 3650032
16:15:23 80077 8192000000 10277588400 10738081792 1355992000 14.201 98 18 34 0 1374579504 14670944 3916560
16:17:43 29669 8192000000 10297504288 10742263808 1365194032 14.284 98 28 56 1 1380590176 9479120 5917024
16:19:13 21627 8192000000 10318872512 10760663040 1388744464 14.495 100 56 111 0 1420166192 19331872 12089856
16:20:39 60138 8192000000 10304068320 10768601088 1387608496 14.485 100 13 26 0 1412709536 22207056 2893984
16:22:42 58696 8192000000 10310873072 10769068032 1391059760 14.515 100 21 42 0 1412845488 16956608 4829120
16:24:47 49428 8192000000 10323555728 10772938752 1340209424 14.059 96 19 38 0 1363288336 18854896 4224016
16:26:41 86943 8192000000 10297804160 10755309568 1398006000 14.577 100 27 54 0 1414415232 9949968 6459264
16:29:30 34885 8192000000 10298471488 10750234624 1389986880 14.506 100 36 70 0 1406664000 9033568 7643552
16:31:06 58287 8192000000 10273540784 10731266048 1368607072 14.315 98 20 40 0 1386972144 14019104 4345968
16:33:04 57891 8192000000 10275388304 10740211712 1355570192 14.198 98 13 26 0 1374481632 15825344 3086096
16:35:03 59068 8192000000 10267580000 10726539264 1374223616 14.365 100 13 26 0 1383637536 6590736 2823184
16:37:02 65424 8192000000 10274615456 10743558144 1365378320 14.286 99 21 42 0 1378668480 8581696 4708464
16:39:08 39906 8192000000 10271039360 10729156608 1361057232 14.247 99 25 48 0 1375818192 9390080 5370880
16:40:48 64225 8192000000 10262311904 10722115584 1342716896 14.082 98 17 32 0 1358229056 11167136 4345024

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 25-09-2022 16:43:36 to 25-09-2022 16:43:52.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 136 v_add_invoice_to_stock 335544517
except 18 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 25-09-2022 16:43:53 to 25-09-2022 16:43:55.

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          239164
MON$OLDEST_ACTIVE               239962
MON$OLDEST_SNAPSHOT             239962
MON$NEXT_TRANSACTION            239962
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                       2023488
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.33631 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33631 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33631 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33631 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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Sun Sep 25 16:42:32 2022
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Sun Sep 25 16:42:33 2022
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:13: 
:14: 
:15: mscs-test-161.budzdorov.ru    Sun Sep 25 16:42:34 2022
:16:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:17: 
:18: 
:19: mscs-test-161.budzdorov.ru    Sun Sep 25 16:42:34 2022
:20:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:21: 
:22: 
:23: mscs-test-161.budzdorov.ru    Sun Sep 25 16:42:34 2022
:24:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:25: 
:26: 
:27: mscs-test-161.budzdorov.ru    Sun Sep 25 16:42:34 2022
:28:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:29: 
--- end of diff output ---

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

25.09.22 16:43:56. 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-09-25 16:43:59 No severe PSQL-related problems occured
25.09.22 16:44:00. End of report.