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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16531908 kB
MemAvailable 31471000 kB
Buffers 0 kB
Cached 15160900 kB
SwapCached 2568 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.10
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-05-08 22:38:15
DTS_END                         2022-05-08 22:38:15.1140
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/53500
TRN_ID                          239084
ATT_ID                          641
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: 9298.03 22522 1115763
customer order: creation 171.08 358 20530
customer order: refuse 14.10 338 1692
order to supplier: creation 45.75 1353 5490
order to supplier: removal 3.53 106929 423
invoice (draft): creation 45.95 3421 5514
invoice (draft): removal 53.73 774 6448
invoice accept: apply 41.66 18550 4999
invoice accept: cancel 48.83 817 5860
customer reserve: creation 3835.38 183 460245
customer reserve: removal 4744.58 63 569349
realization accept: apply 82.72 180 9926
realization accept: cancel 80.73 111 9688
payment from customer: creation 59.50 108 7140
payment from customer: removal 5.52 55 662
payment to supplier: creation 48.78 131 5853
payment to supplier: removal 3.45 125 414
service: total inventory turnovers 4.95 1133 594
service: total monetary turnovers 6.93 45 832
service: aggregate perf. data 0.83 18887 100
service: refresh index statistics 0.03 296872 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 08-05-2022 22:38:58 to 08-05-2022 22:39:00.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4871
TEST_TIME 2 4406
TEST_TIME 3 6260
TEST_TIME 4 6214
TEST_TIME 5 6209
TEST_TIME 6 6324
TEST_TIME 7 6073
TEST_TIME 8 6408
TEST_TIME 9 6378
TEST_TIME 10 6930
TEST_TIME 11 6660
TEST_TIME 12 6698
TEST_TIME 13 6942
TEST_TIME 14 7214
TEST_TIME 15 7293
TEST_TIME 16 7620
TEST_TIME 17 8093
TEST_TIME 18 7984
TEST_TIME 19 8306
TEST_TIME 20 8556
TEST_TIME 21 8403
TEST_TIME 22 8681
TEST_TIME 23 9029
TEST_TIME 24 9106
TEST_TIME 25 9070
TEST_TIME 26 9116
TEST_TIME 27 9293
TEST_TIME 28 9331
TEST_TIME 29 9782
TEST_TIME 30 10341
TEST_TIME 31 11079
TEST_TIME 32 11089
TEST_TIME 33 10934
TEST_TIME 34 10644
TEST_TIME 35 10544
TEST_TIME 36 10420
TEST_TIME 37 10480
TEST_TIME 38 10388
TEST_TIME 39 10377
TEST_TIME 40 10269
TEST_TIME 41 10141
TEST_TIME 42 10103
TEST_TIME 43 9980
TEST_TIME 44 10012
TEST_TIME 45 9935
TEST_TIME 46 9865
TEST_TIME 47 9710
TEST_TIME 48 9650
TEST_TIME 49 9605
TEST_TIME 50 9563
TEST_TIME 51 9449
TEST_TIME 52 9378
TEST_TIME 53 9319
TEST_TIME 54 9366
TEST_TIME 55 9310
TEST_TIME 56 9291
TEST_TIME 57 9273
TEST_TIME 58 9429
TEST_TIME 59 9348
TEST_TIME 60 9433
TEST_TIME 61 9438
TEST_TIME 62 9448
TEST_TIME 63 9474
TEST_TIME 64 9489
TEST_TIME 65 9511
TEST_TIME 66 9574
TEST_TIME 67 9531
TEST_TIME 68 9545
TEST_TIME 69 9605
TEST_TIME 70 9616
TEST_TIME 71 9606
TEST_TIME 72 9598
TEST_TIME 73 9528
TEST_TIME 74 9467
TEST_TIME 75 9427
TEST_TIME 76 9397
TEST_TIME 77 9365
TEST_TIME 78 9346
TEST_TIME 79 9338
TEST_TIME 80 9313
TEST_TIME 81 9295
TEST_TIME 82 9266
TEST_TIME 83 9283
TEST_TIME 84 9250
TEST_TIME 85 9238
TEST_TIME 86 9214
TEST_TIME 87 9293
TEST_TIME 88 9350
TEST_TIME 89 9404
TEST_TIME 90 9389
TEST_TIME 91 9391
TEST_TIME 92 9437
TEST_TIME 93 9424
TEST_TIME 94 9390
TEST_TIME 95 9486
TEST_TIME 96 9510
TEST_TIME 97 9494
TEST_TIME 98 9486
TEST_TIME 99 9437
TEST_TIME 100 9409
TEST_TIME 101 9414
TEST_TIME 102 9381
TEST_TIME 103 9365
TEST_TIME 104 9345
TEST_TIME 105 9315
TEST_TIME 106 9280
TEST_TIME 107 9254
TEST_TIME 108 9240
TEST_TIME 109 9261
TEST_TIME 110 9306
TEST_TIME 111 9284
TEST_TIME 112 9305
TEST_TIME 113 9283
TEST_TIME 114 9318
TEST_TIME 115 9337
TEST_TIME 116 9326
TEST_TIME 117 9322
TEST_TIME 118 9349
TEST_TIME 119 9367
TEST_TIME 120 9301

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 08-05-2022 22:39:01 to 08-05-2022 22:39:05.

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 1541793 1541793 0 0.00 0 28414 6 0 0
doc_list_biud 1541789 1541789 0 0.00 0 31438 29 0 0
sp_add_invoice_to_stock 5097 4999 98 1.92 22 162442 18550 0 98
sp_cancel_adding_invoice 5860 5860 0 0.00 4 75216 817 0 0
sp_cancel_client_order 1692 1692 0 0.00 4 8877 339 0 0
sp_cancel_customer_reserve 569349 569349 0 0.00 1 26897 63 0 0
sp_cancel_pay_from_customer 662 662 0 0.00 2 1616 55 0 0
sp_cancel_pay_to_supplier 414 414 0 0.00 2 9162 126 0 0
sp_cancel_supplier_invoice 6458 6448 10 0.15 1 17724 775 0 10
sp_cancel_supplier_order 423 423 0 0.00 6 946721 106930 0 0
sp_cancel_write_off 9688 9688 0 0.00 1 20897 111 0 0
sp_client_order 20530 20530 0 0.00 3 53569 358 0 0
sp_customer_reserve 460245 460245 0 0.00 0 81835 184 0 0
sp_fill_shopping_cart 31532 31532 0 0.00 1 11523 39 0 0
sp_fill_shopping_cart_clo_res 460237 460237 0 0.00 0 26014 13 0 0
sp_get_clo_for_invoice 4990 4990 0 0.00 0 39896 321 0 0
sp_kill_cost_storno 16624 16624 0 0.00 0 9680 59 0 0
sp_kill_qstorno_ret_qs2qd 1155257 1155257 0 0.00 0 26893 34 0 0
sp_kill_qty_storno 608376 608376 0 0.00 0 31433 73 0 0
sp_lock_selected_doc 20626 20626 0 0.00 0 9020 5 0 0
sp_make_cost_storno 27909 27909 0 0.00 0 18412 57 0 0
sp_multiply_rows_for_pdistr 27909 27909 0 0.00 0 12790 32 0 0
sp_multiply_rows_for_qdistr 20530 20530 0 0.00 0 53543 157 0 0
sp_pay_from_customer 7140 7140 0 0.00 4 17266 108 0 0
sp_pay_to_supplier 5853 5853 0 0.00 5 18870 131 0 0
sp_payment_common 12993 12993 0 0.00 1 9920 25 0 0
sp_qd_handle_on_cancel_clo 1692 1692 0 0.00 0 8195 310 0 0
sp_qd_handle_on_invoice_upd_sts 10850 10850 0 0.00 0 31426 342 0 0
sp_qd_handle_on_reserve_upd_sts 9926 9926 0 0.00 0 18630 61 0 0
sp_reserve_write_off 9926 9926 0 0.00 6 20275 181 0 0
sp_supplier_invoice 5514 5514 0 0.00 6 83254 3421 0 0
sp_supplier_order 5490 5490 0 0.00 5 62554 1353 0 0
srv_aggregate_perf_data 101 100 1 0.99 3023 249745 18887 0 1
srv_fill_mon_cache_memory 65 65 0 0.00 1429 162708 44243 0 0
srv_find_qd_qs_mism 1068052 1068052 0 0.00 0 28413 5 0 0
srv_make_invnt_saldo 594 594 0 0.00 30 32664 1134 0 0
srv_make_money_saldo 832 832 0 0.00 1 11123 45 0 0
srv_recalc_idx_stat 4 4 0 0.00 202314 434889 296873 0 0
t$perf-abend:sp_add_invoice_to_stock 98 98 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4999 4999 0 0.00 0 8203 161 0 0
t$perf-norm:sp_cancel_adding_invoice 430 430 0 0.00 0 12437 114 0 0
t$perf-norm:sp_cancel_client_order 1692 1692 0 0.00 0 2143 14 0 0
t$perf-norm:sp_cancel_customer_reserve 703 703 0 0.00 0 691 8 0 0
t$perf-norm:sp_cancel_pay_from_customer 662 662 0 0.00 0 5198 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 414 414 0 0.00 0 634 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 411 411 0 0.00 0 1589 20 0 0
t$perf-norm:sp_cancel_supplier_order 423 423 0 0.00 0 24965 1591 0 0
t$perf-norm:sp_cancel_write_off 976 976 0 0.00 0 2208 8 0 0
t$perf-norm:sp_client_order 20530 20530 0 0.00 0 4353 16 0 0
t$perf-norm:sp_customer_reserve 1039 1039 0 0.00 0 10278 15 0 0
t$perf-norm:sp_pay_from_customer 7140 7140 0 0.00 0 8390 12 0 0
t$perf-norm:sp_pay_to_supplier 5853 5853 0 0.00 0 5668 9 0 0
t$perf-norm:sp_reserve_write_off 9926 9926 0 0.00 0 3817 10 0 0
t$perf-norm:sp_supplier_invoice 5514 5514 0 0.00 0 6324 23 0 0
t$perf-norm:sp_supplier_order 5490 5490 0 0.00 0 8079 19 0 0
t$perf-norm:srv_aggregate_perf_data 100 100 0 0.00 0 518 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 162 5 0 0
t$perf-norm:srv_make_invnt_saldo 594 594 0 0.00 0 930 19 0 0
t$perf-norm:srv_make_money_saldo 832 832 0 0.00 0 593 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 8 4 0 0
v_add_invoice_to_stock 10174 9978 196 1.92 0 11683 20 0 196
v_all_customers 37514 37514 0 0.00 0 76 1 0 0
v_all_suppliers 30352 30352 0 0.00 0 78 1 0 0
v_all_wares 41060 41060 0 0.00 0 14 1 0 0
v_cancel_adding_invoice 860 860 0 0.00 0 523 6 0 0
v_cancel_client_order 3384 3384 0 0.00 0 3987 5 0 0
v_cancel_customer_prepayment 1324 1324 0 0.00 0 872 4 0 0
v_cancel_customer_reserve 1406 1406 0 0.00 0 1751 4 0 0
v_cancel_payment_to_supplier 828 828 0 0.00 0 3223 7 0 0
v_cancel_supplier_invoice 842 822 20 2.37 0 515 13 0 20
v_cancel_supplier_order 846 846 0 0.00 0 2762 10 0 0
v_cancel_write_off 1952 1952 0 0.00 0 1773 12 0 0
v_max_id_clo_ord 5490 5490 0 0.00 0 1621 2 0 0
v_max_id_clo_res 1039 1039 0 0.00 0 3480 13 0 0
v_max_id_ord_sup 5512 5512 0 0.00 0 5808 5 0 0
v_max_non_paid_invoice 1679 1679 0 0.00 0 1966 4 0 0
v_max_non_paid_realizn 3790 3790 0 0.00 0 2504 4 0 0
v_min_id_clo_ord 5490 5490 0 0.00 0 5316 7 0 0
v_min_id_clo_res 1039 1039 0 0.00 0 4301 13 0 0
v_min_id_ord_sup 5512 5512 0 0.00 0 5608 5 0 0
v_min_non_paid_invoice 5853 5853 0 0.00 0 9917 17 0 0
v_min_non_paid_realizn 7140 7140 0 0.00 0 7260 19 0 0
v_our_firm 10246 10246 0 0.00 0 17 1 0 0
v_reserve_write_off 19852 19852 0 0.00 0 7942 6 0 0
x_lock_depdocs_on_canc_invoice 11897 11897 0 0.00 0 14492 125 0 0
x_lock_depdocs_on_canc_sup_ord 423 423 0 0.00 0 12037 372 0 0
x_make_qstorno_1000_1200 5490 5490 0 0.00 1 62538 1264 0 0
x_make_qstorno_1200_2000 5514 5514 0 0.00 3 83214 3289 0 0
x_make_qstorno_2100_3300 460245 460245 0 0.00 0 81834 168 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 08-05-2022 22:39:06 to 08-05-2022 22:39:10.

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:18 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:19 29936 8192000000 9910470592 10220216320 988528592 10.767 100 89 177 0 1017694112 5499680 23665840
20:10:52 19364 8192000000 9941363008 10260701184 1027346096 11.143 100 90 181 3 1058596528 7852256 23398176
20:12:15 16527 8192000000 9970639792 10300669952 1056287328 11.421 100 81 162 2 1085728512 8852944 20588240
20:13:36 18614 8192000000 10026300464 10372005888 1111362704 11.945 100 79 158 1 1139802656 9532928 18907024
20:14:57 9844 8192000000 10074876624 10433798144 1166595168 12.465 100 75 148 1 1195709184 12881600 16232416
20:16:16 5836 8192000000 10117305520 10487095296 1211862224 12.886 100 78 156 0 1245566624 16187856 17516544
20:17:25 3162 8192000000 10158730192 10531676160 1244156880 13.184 100 91 182 0 1277464192 14093968 19213344
20:18:30 2027 8192000000 10185734688 10570858496 1276515984 13.481 100 86 172 0 1310943536 15097952 19329600
20:19:33 2108 8192000000 10212367760 10600189952 1300887664 13.703 100 90 180 1 1334783584 13339376 20556544
20:20:36 29021 8192000000 10218065296 10613682176 1312041584 13.805 100 71 136 3 1337571808 8366816 17163408
20:22:06 9300 8192000000 10215449408 10627608576 1321603392 13.891 100 37 74 0 1341086128 10903936 8578800
20:23:17 5001 8192000000 10218934080 10633056256 1328005024 13.949 100 19 38 0 1339210128 6847472 4357632
20:24:23 27106 8192000000 10211214240 10637123584 1330553744 13.972 100 32 65 2 1343528192 4654832 8319616
20:25:52 6686 8192000000 10223543488 10645876736 1336390800 14.025 100 29 58 1 1349221136 5639408 7190928
20:27:00 13818 8192000000 10226049024 10648834048 1314012544 13.822 98 15 29 1 1326858112 9336784 3508784
20:28:16 14157 8192000000 10204372096 10616475648 1294472848 13.645 97 22 44 2 1306167152 6245872 5448432
20:29:30 25056 8192000000 10191425680 10609446912 1264624608 13.372 95 16 33 0 1273387728 4940064 3823056
20:31:01 4932 8192000000 10176717104 10586931200 1286778672 13.575 100 31 61 1 1300839184 6710896 7349616
20:32:06 5757 8192000000 10153486032 10551275520 1248629632 13.226 99 37 74 1 1263318768 6603712 8085424
20:33:18 5246 8192000000 10157661744 10547785728 1247739904 13.217 99 48 97 0 1264420064 5059008 11621152
20:35:04 6074 8192000000 10172677136 10578001920 1264368576 13.370 99 28 56 0 1281253088 10520160 6364352
20:36:10 7277 8192000000 10179585040 10589794304 1287703776 13.583 100 22 44 1 1298983152 6122720 5156656
20:37:18 9842 8192000000 10186374496 10603913216 1298353600 13.680 100 18 36 4 1307948576 5710976 3884000
20:38:34 42004 8192000000 10236055376 10642804736 1326685120 13.937 100 49 98 1 1345326032 8290864 10350048
20:40:21 43302 8192000000 10249209968 10664476672 1341211728 14.068 100 54 108 0 1364637488 9921072 13504688
20:42:08 8649 8192000000 10274238528 10687029248 1359716912 14.235 100 63 127 0 1385058064 11320064 14021088
20:43:21 31483 8192000000 10293028608 10712530944 1374168752 14.364 100 58 115 0 1400953968 13795664 12989552
20:44:57 11228 8192000000 10299730400 10724016128 1386888208 14.478 100 47 94 3 1412799584 14970512 10940864
20:46:15 7862 8192000000 10318535424 10742472704 1396665264 14.565 100 49 98 2 1428416544 20234608 11516672
20:47:27 17264 8192000000 10322862512 10744115200 1395748384 14.557 100 57 112 3 1434994096 25592960 13652752
20:48:50 21154 8192000000 10321791168 10749853696 1407797936 14.664 100 45 86 0 1440681456 22495984 10387536
20:50:16 3102 8192000000 10337447232 10755076096 1403701200 14.628 100 63 125 2 1443805184 24438352 15665632
20:51:25 1428 8192000000 10353809728 10771034112 1414094800 14.720 100 67 132 0 1457953936 27776944 16082192
20:52:31 5565 8192000000 10346227120 10766036992 1409237664 14.677 100 61 121 1 1454436464 30491312 14707488
20:53:40 6345 8192000000 10323778448 10743468032 1388255200 14.490 100 66 131 0 1435657632 31482416 15920016
20:54:53 2967 8192000000 10331887152 10736005120 1368096160 14.310 99 84 167 2 1424126800 35675088 20355552
20:56:00 4525 8192000000 10298915712 10707120128 1353823456 14.182 100 59 118 3 1399287072 31149888 14313728
20:57:08 2881 8192000000 10320399120 10731028480 1355489296 14.197 99 75 150 0 1416261248 42003088 18768864
20:58:16 1510 8192000000 10340445280 10741723136 1377407344 14.393 100 81 161 0 1436543424 38272720 20863360
20:59:21 4181 8192000000 10331040544 10743009280 1377104656 14.391 100 62 124 1 1433989504 40360640 16524208
21:00:31 4115 8192000000 10355975024 10760069120 1385001696 14.461 100 72 145 0 1451291760 47652240 18637824
21:01:39 6617 8192000000 10351350000 10773176320 1392963792 14.532 100 57 112 0 1458620816 50147840 15509184
21:02:50 5342 8192000000 10353365920 10779615232 1400694544 14.601 100 49 98 0 1457033088 43936512 12402032
21:04:00 11931 8192000000 10352972624 10785411072 1397256064 14.571 100 31 62 0 1456709840 50629664 8824112
21:05:18 35904 8192000000 10350558272 10791944192 1396516128 14.564 100 25 50 1 1450798192 46710256 7571808
21:07:05 33111 8192000000 10340429408 10784493568 1381476256 14.430 99 30 60 0 1442298160 52172240 8649664
21:08:40 44964 8192000000 10310721120 10766594048 1361890544 14.254 99 14 28 1 1416433232 50669264 3873424
21:10:29 83429 8192000000 10300176752 10740772864 1358814192 14.227 98 27 53 1 1384115264 19100048 6201024
21:13:05 54084 8192000000 10306693472 10750070784 1389110768 14.498 100 23 44 0 1409719040 15251696 5356576
21:15:02 35647 8192000000 10306288976 10768527360 1399098624 14.587 100 20 41 2 1424081728 19865520 5117584
21:16:55 105743 8192000000 10307509776 10771824640 1399513680 14.591 100 9 18 0 1417644864 15859936 2271248
21:20:01 94306 8192000000 10306829056 10763517952 1347315600 14.123 96 22 44 0 1368861968 16855376 4690992
21:22:43 59760 8192000000 10293554784 10765672448 1368592352 14.314 98 12 24 0 1391970208 20899328 2478528
21:24:56 59808 8192000000 10296272656 10751938560 1393855200 14.540 100 30 60 1 1407296384 6802384 6638800
21:27:05 31124 8192000000 10313477648 10770178048 1403963248 14.630 100 38 72 1 1421333200 9525648 7844304
21:28:37 39706 8192000000 10304107552 10768310272 1399813584 14.593 99 15 27 1 1412806160 9964864 3027712
21:30:18 52504 8192000000 10327624064 10780528640 1415699456 14.735 100 39 78 2 1438855952 13899568 9256928
21:32:20 29935 8192000000 10328270704 10797805568 1422605600 14.796 100 16 32 0 1446569696 19359264 4604832
21:34:02 61260 8192000000 10325801776 10801827840 1420605984 14.778 100 10 20 0 1441808528 18482720 2719824
21:36:17 53600 8192000000 10297823664 10763763712 1343106176 14.085 95 17 34 0 1365325456 17846208 4373072
21:38:11 48946 8192000000 10294350816 10762526720 1383572928 14.448 99 22 44 2 1399423680 11391248 4459504
21:40:31 69108 8192000000 10293168288 10758230016 1350356112 14.151 97 17 34 0 1367211568 13165632 3689824
21:42:41 162708 8192000000 10297757040 10757627904 1353870880 14.182 97 15 30 0 1370703072 14119104 2713088
21:46:24 75371 8192000000 10268616560 10726584320 1309242384 13.779 95 17 34 0 1330527264 17904960 3379920
21:48:43 142999 8192000000 10271290128 10721357824 1273010976 13.449 93 6 12 0 1288900720 14628112 1261632
21:52:07 70520 8192000000 10258981136 10692767744 1275244896 13.470 93 17 34 0 1285254368 5822080 4187392
21:54:26 12305 8192000000 10249181040 10690621440 1342511280 14.080 100 27 54 0 1362669856 13967216 6191360
21:55:39 38321 8192000000 10239474784 10683985920 1337828656 14.038 100 24 48 1 1355495376 11998720 5668000
21:57:24 50524 8192000000 10259333344 10705149952 1337758512 14.037 99 13 26 1 1355667728 14886368 3022848
21:59:28 44199 8192000000 10245243616 10696777728 1327548512 13.945 98 8 16 0 1341310064 11932080 1829472
22:01:28 61298 8192000000 10250437424 10700447744 1327339456 13.943 98 14 28 0 1342560752 12113904 3107392
22:03:38 50402 8192000000 10281555856 10724638720 1361793440 14.253 99 21 42 1 1387212080 20240032 5178608
22:06:14 38104 8192000000 10275252144 10725445632 1350461472 14.152 99 20 38 0 1380962848 25981600 4519776
22:08:00 91911 8192000000 10292077808 10741059584 1361710960 14.253 99 16 32 1 1376539728 11687376 3141392
22:10:32 52712 8192000000 10286216752 10731675648 1369224672 14.320 99 22 44 0 1383265632 9486400 4554560
22:12:28 60410 8192000000 10300681616 10764455936 1376912736 14.389 99 25 50 0 1400037840 17635968 5489136
22:14:28 74887 8192000000 10298328896 10752581632 1379541072 14.412 99 10 20 0 1393185552 11696464 1948016
22:16:46 101171 8192000000 10309092992 10761396224 1402895840 14.621 100 27 54 0 1419687440 10650592 6141008
22:19:49 44581 8192000000 10298240816 10753429504 1380511104 14.421 99 17 32 1 1395189424 10926432 3751888
22:21:33 54305 8192000000 10293076992 10759462912 1389279712 14.499 99 8 16 0 1400338432 9355504 1703216
22:23:51 77992 8192000000 10302381376 10761314304 1378440448 14.403 98 19 39 0 1398329744 15081168 4808128
22:26:10 63546 8192000000 10317994368 10771382272 1400574320 14.600 100 25 50 2 1425714192 18901824 6238048
22:28:17 67837 8192000000 10281286528 10741526528 1338698240 14.046 96 6 12 1 1353714112 13594208 1421664
22:30:34 42276 8192000000 10280998752 10723586048 1326421472 13.935 95 33 66 0 1341274096 7445536 7407088
22:32:23 31945 8192000000 10261989408 10721931264 1353123584 14.176 99 16 32 0 1368164032 11521136 3519312
22:33:58 24597 8192000000 10267975952 10708934656 1328405328 13.953 97 29 58 0 1346295312 11583056 6306928
22:35:23 48524 8192000000 10264887680 10706956288 1320982368 13.886 97 33 66 1 1342571872 14210240 7379264
22:37:25 55940 8192000000 9174509824 9431404544 322194912 3.784 24 3 6 0 325407600 2663424 549264

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 08-05-2022 22:39:12 to 08-05-2022 22:39:27.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 98 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 08-05-2022 22:39:28 to 08-05-2022 22:39: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          239393
MON$OLDEST_ACTIVE               239394
MON$OLDEST_SNAPSHOT             239394
MON$NEXT_TRANSACTION            239394
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                       2034272
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33587 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33587 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33587 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33587 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 ---
--- end of diff output ---

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

08.05.22 22:39:31. 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-05-08 22:39:33 No severe PSQL-related problems occured
08.05.22 22:39:34. End of report.