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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16198296 kB
MemAvailable 31071252 kB
Buffers 0 kB
Cached 15490488 kB
SwapCached 2520 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-07-14 19:38:26
DTS_END                         2022-07-14 19:38:26.3830
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/58328
TRN_ID                          249119
ATT_ID                          710
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: 9356.94 25109 1122833
customer order: creation 179.13 367 21496
customer order: refuse 15.88 366 1905
order to supplier: creation 47.87 1259 5744
order to supplier: removal 3.66 88953 439
invoice (draft): creation 46.95 3176 5634
invoice (draft): removal 51.75 804 6210
invoice accept: apply 43.82 17185 5258
invoice accept: cancel 46.98 786 5637
customer reserve: creation 4032.36 172 483883
customer reserve: removal 4583.63 55 550035
realization accept: apply 87.38 159 10486
realization accept: cancel 81.05 108 9726
payment from customer: creation 61.48 128 7378
payment from customer: removal 6.10 133 732
payment to supplier: creation 52.23 145 6267
payment to supplier: removal 3.31 83 397
service: total inventory turnovers 5.13 1081 616
service: total monetary turnovers 7.25 30 870
service: aggregate perf. data 0.97 16331 116
service: refresh index statistics 0.03 370849 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 14-07-2022 19:39:16 to 14-07-2022 19:39:18.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3451
TEST_TIME 2 2893
TEST_TIME 3 2709
TEST_TIME 4 2877
TEST_TIME 5 2944
TEST_TIME 6 3416
TEST_TIME 7 3998
TEST_TIME 8 4570
TEST_TIME 9 4947
TEST_TIME 10 5302
TEST_TIME 11 5552
TEST_TIME 12 5601
TEST_TIME 13 5878
TEST_TIME 14 6097
TEST_TIME 15 6559
TEST_TIME 16 7169
TEST_TIME 17 7242
TEST_TIME 18 7818
TEST_TIME 19 7958
TEST_TIME 20 8391
TEST_TIME 21 8575
TEST_TIME 22 8840
TEST_TIME 23 9481
TEST_TIME 24 9847
TEST_TIME 25 10439
TEST_TIME 26 10453
TEST_TIME 27 10317
TEST_TIME 28 10092
TEST_TIME 29 10049
TEST_TIME 30 9978
TEST_TIME 31 9669
TEST_TIME 32 9768
TEST_TIME 33 9659
TEST_TIME 34 9760
TEST_TIME 35 9688
TEST_TIME 36 9662
TEST_TIME 37 9658
TEST_TIME 38 9587
TEST_TIME 39 9501
TEST_TIME 40 9548
TEST_TIME 41 9541
TEST_TIME 42 9450
TEST_TIME 43 9452
TEST_TIME 44 9668
TEST_TIME 45 9641
TEST_TIME 46 9608
TEST_TIME 47 9611
TEST_TIME 48 9652
TEST_TIME 49 9620
TEST_TIME 50 9588
TEST_TIME 51 9561
TEST_TIME 52 9497
TEST_TIME 53 9713
TEST_TIME 54 9739
TEST_TIME 55 9832
TEST_TIME 56 9847
TEST_TIME 57 9752
TEST_TIME 58 9630
TEST_TIME 59 9641
TEST_TIME 60 9593
TEST_TIME 61 9550
TEST_TIME 62 9541
TEST_TIME 63 9519
TEST_TIME 64 9503
TEST_TIME 65 9440
TEST_TIME 66 9356
TEST_TIME 67 9332
TEST_TIME 68 9349
TEST_TIME 69 9431
TEST_TIME 70 9451
TEST_TIME 71 9479
TEST_TIME 72 9451
TEST_TIME 73 9493
TEST_TIME 74 9550
TEST_TIME 75 9549
TEST_TIME 76 9469
TEST_TIME 77 9571
TEST_TIME 78 9575
TEST_TIME 79 9595
TEST_TIME 80 9522
TEST_TIME 81 9447
TEST_TIME 82 9357
TEST_TIME 83 9393
TEST_TIME 84 9346
TEST_TIME 85 9398
TEST_TIME 86 9354
TEST_TIME 87 9308
TEST_TIME 88 9359
TEST_TIME 89 9315
TEST_TIME 90 9297
TEST_TIME 91 9339
TEST_TIME 92 9357
TEST_TIME 93 9368
TEST_TIME 94 9366
TEST_TIME 95 9418
TEST_TIME 96 9459
TEST_TIME 97 9446
TEST_TIME 98 9475
TEST_TIME 99 9491
TEST_TIME 100 9467
TEST_TIME 101 9384
TEST_TIME 102 9388
TEST_TIME 103 9333
TEST_TIME 104 9349
TEST_TIME 105 9319
TEST_TIME 106 9290
TEST_TIME 107 9254
TEST_TIME 108 9228
TEST_TIME 109 9251
TEST_TIME 110 9231
TEST_TIME 111 9209
TEST_TIME 112 9209
TEST_TIME 113 9280
TEST_TIME 114 9281
TEST_TIME 115 9276
TEST_TIME 116 9243
TEST_TIME 117 9334
TEST_TIME 118 9368
TEST_TIME 119 9394
TEST_TIME 120 9358

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 14-07-2022 19:39:19 to 14-07-2022 19:39:23.

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 1570724 1570724 0 0.00 0 29636 6 0 0
doc_list_biud 1570724 1570724 0 0.00 0 56152 25 0 0
sp_add_invoice_to_stock 5370 5258 112 2.08 39 207599 17186 0 112
sp_cancel_adding_invoice 5637 5637 0 0.00 4 75663 787 0 0
sp_cancel_client_order 1905 1905 0 0.00 3 20497 367 0 0
sp_cancel_customer_reserve 550035 550035 0 0.00 0 56153 56 0 0
sp_cancel_pay_from_customer 732 732 0 0.00 2 18377 133 0 0
sp_cancel_pay_to_supplier 397 397 0 0.00 2 8247 83 0 0
sp_cancel_supplier_invoice 6215 6210 5 0.08 1 18118 805 0 5
sp_cancel_supplier_order 439 439 0 0.00 7 973612 88954 0 0
sp_cancel_write_off 9726 9726 0 0.00 1 21010 109 0 0
sp_client_order 21496 21496 0 0.00 3 28373 367 0 0
sp_customer_reserve 483883 483883 0 0.00 0 45286 172 0 0
sp_fill_shopping_cart 32865 32865 0 0.00 1 13801 39 0 0
sp_fill_shopping_cart_clo_res 483878 483878 0 0.00 0 31596 15 0 0
sp_get_clo_for_invoice 5253 5253 0 0.00 0 14999 267 0 0
sp_kill_cost_storno 16492 16492 0 0.00 0 20979 51 0 0
sp_kill_qstorno_ret_qs2qd 1116445 1116445 0 0.00 0 56148 31 0 0
sp_kill_qty_storno 589691 589691 0 0.00 0 56151 66 0 0
sp_lock_selected_doc 21791 21791 0 0.00 0 8232 3 0 0
sp_make_cost_storno 29384 29384 0 0.00 0 29551 54 0 0
sp_multiply_rows_for_pdistr 29384 29384 0 0.00 0 20746 28 0 0
sp_multiply_rows_for_qdistr 21496 21496 0 0.00 0 25430 158 0 0
sp_pay_from_customer 7378 7378 0 0.00 4 20653 129 0 0
sp_pay_to_supplier 6267 6267 0 0.00 4 25636 145 0 0
sp_payment_common 13645 13645 0 0.00 1 20125 41 0 0
sp_qd_handle_on_cancel_clo 1905 1905 0 0.00 0 10754 328 0 0
sp_qd_handle_on_invoice_upd_sts 10890 10890 0 0.00 0 24604 298 0 0
sp_qd_handle_on_reserve_upd_sts 10486 10486 0 0.00 0 12882 47 0 0
sp_reserve_write_off 10486 10486 0 0.00 5 32728 160 0 0
sp_supplier_invoice 5634 5634 0 0.00 6 93450 3176 0 0
sp_supplier_order 5744 5744 0 0.00 4 45797 1260 0 0
srv_aggregate_perf_data 116 116 0 0.00 1897 119501 16332 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 2199 124515 44038 0 0
srv_find_qd_qs_mism 1072825 1072825 0 0.00 0 20729 6 0 0
srv_make_invnt_saldo 616 616 0 0.00 27 147157 1081 0 0
srv_make_money_saldo 870 870 0 0.00 1 8113 30 0 0
srv_recalc_idx_stat 4 4 0 0.00 276699 483883 370849 0 0
t$perf-abend:sp_add_invoice_to_stock 112 112 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5258 5258 0 0.00 0 15818 153 0 0
t$perf-norm:sp_cancel_adding_invoice 457 457 0 0.00 0 4724 90 0 0
t$perf-norm:sp_cancel_client_order 1905 1905 0 0.00 0 3310 17 0 0
t$perf-norm:sp_cancel_customer_reserve 728 728 0 0.00 0 2132 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 732 732 0 0.00 0 1237 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 397 397 0 0.00 0 505 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 414 414 0 0.00 0 4334 30 0 0
t$perf-norm:sp_cancel_supplier_order 439 439 0 0.00 0 28911 1311 0 0
t$perf-norm:sp_cancel_write_off 980 980 0 0.00 0 601 4 0 0
t$perf-norm:sp_client_order 21496 21496 0 0.00 0 12520 15 0 0
t$perf-norm:sp_customer_reserve 1058 1058 0 0.00 0 3896 7 0 0
t$perf-norm:sp_pay_from_customer 7378 7378 0 0.00 0 12946 11 0 0
t$perf-norm:sp_pay_to_supplier 6267 6267 0 0.00 0 1832 7 0 0
t$perf-norm:sp_reserve_write_off 10486 10486 0 0.00 0 8425 12 0 0
t$perf-norm:sp_supplier_invoice 5634 5634 0 0.00 0 3810 17 0 0
t$perf-norm:sp_supplier_order 5744 5744 0 0.00 0 8179 13 0 0
t$perf-norm:srv_aggregate_perf_data 116 116 0 0.00 0 596 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 20 1 0 0
t$perf-norm:srv_make_invnt_saldo 616 616 0 0.00 0 3191 21 0 0
t$perf-norm:srv_make_money_saldo 870 870 0 0.00 0 3357 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 69 19 0 0
v_add_invoice_to_stock 10726 10502 224 2.08 0 16010 18 0 224
v_all_customers 38936 38936 0 0.00 0 330 1 0 0
v_all_suppliers 31830 31830 0 0.00 0 16 1 0 0
v_all_wares 42992 42992 0 0.00 0 33 1 0 0
v_cancel_adding_invoice 914 914 0 0.00 0 3167 13 0 0
v_cancel_client_order 3810 3810 0 0.00 0 20127 14 0 0
v_cancel_customer_prepayment 1464 1464 0 0.00 0 9630 20 0 0
v_cancel_customer_reserve 1456 1456 0 0.00 0 2344 7 0 0
v_cancel_payment_to_supplier 794 794 0 0.00 0 3430 7 0 0
v_cancel_supplier_invoice 838 828 10 1.19 0 1257 14 0 10
v_cancel_supplier_order 878 878 0 0.00 0 1199 4 0 0
v_cancel_write_off 1960 1960 0 0.00 0 3451 18 0 0
v_max_id_clo_ord 5740 5740 0 0.00 0 1731 1 0 0
v_max_id_clo_res 1058 1058 0 0.00 0 6193 21 0 0
v_max_id_ord_sup 5629 5629 0 0.00 0 7023 5 0 0
v_max_non_paid_invoice 1721 1721 0 0.00 0 1210 3 0 0
v_max_non_paid_realizn 4109 4109 0 0.00 0 16455 9 0 0
v_min_id_clo_ord 5740 5740 0 0.00 0 13729 7 0 0
v_min_id_clo_res 1058 1058 0 0.00 0 1770 6 0 0
v_min_id_ord_sup 5629 5629 0 0.00 0 1569 2 0 0
v_min_non_paid_invoice 6267 6267 0 0.00 0 20121 27 0 0
v_min_non_paid_realizn 7378 7378 0 0.00 0 20072 34 0 0
v_our_firm 10594 10594 0 0.00 0 22 1 0 0
v_reserve_write_off 20972 20972 0 0.00 0 20904 8 0 0
x_lock_depdocs_on_canc_invoice 11433 11433 0 0.00 0 14446 110 0 0
x_lock_depdocs_on_canc_sup_ord 439 439 0 0.00 0 17573 328 0 0
x_make_qstorno_1000_1200 5744 5744 0 0.00 1 45789 1192 0 0
x_make_qstorno_1200_2000 5634 5634 0 0.00 2 93387 3027 0 0
x_make_qstorno_2100_3300 483883 483883 0 0.00 0 37303 155 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 14-07-2022 19:39:24 to 14-07-2022 19:39: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
17:08:30 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:30 15903 8192000000 9891830208 10199646208 980098848 10.685 100 88 174 1 1008703200 6080240 22524112
17:10:58 15344 8192000000 9941747680 10260742144 1024879456 11.119 100 92 184 0 1056918816 8461392 23577968
17:12:27 15737 8192000000 9965071328 10295128064 1052528768 11.385 100 84 167 0 1083235968 9426272 21280928
17:13:48 10366 8192000000 10036176832 10384662528 1120904800 12.036 100 80 160 0 1150276704 10367904 19004000
17:15:02 6437 8192000000 10085280944 10446192640 1174912976 12.543 100 75 147 0 1203507008 12267952 16326080
17:16:11 3983 8192000000 10134980128 10505822208 1223643888 12.995 100 76 152 0 1251570976 11000032 16927056
17:17:17 1715 8192000000 10167257984 10541662208 1254536960 13.280 100 83 166 0 1285809168 13383072 17889136
17:18:21 1121 8192000000 10193549008 10575245312 1283411968 13.544 100 80 158 0 1314771296 13521328 17838000
17:19:24 34160 8192000000 10196447232 10588385280 1296794432 13.666 100 57 109 2 1318533168 9758896 11979840
17:20:59 3990 8192000000 10193458112 10598195200 1302948864 13.722 100 44 88 1 1321590128 9558000 9083264
17:22:10 8050 8192000000 10202245264 10617585664 1315370032 13.835 100 22 44 1 1330817872 10736992 4710848
17:23:19 14326 8192000000 10210561120 10630168576 1326188368 13.933 100 17 33 0 1335498048 5465456 3844224
17:24:35 35916 8192000000 10220937440 10640781312 1329908496 13.966 100 29 58 0 1346915072 10290832 6715744
17:26:12 8013 8192000000 10229926128 10643607552 1322281792 13.897 99 17 35 0 1331170800 4878960 4010048
17:27:25 10187 8192000000 10221175488 10638307328 1308007440 13.768 98 32 63 0 1321637520 6277696 7352384
17:28:36 9208 8192000000 10189869744 10603393024 1273019040 13.449 97 23 45 0 1283633184 5577536 5036608
17:29:45 17672 8192000000 10179854096 10591711232 1241733968 13.162 95 31 60 1 1254666304 6348608 6583728
17:31:05 4910 8192000000 10122560576 10510786560 1174320784 12.537 93 35 70 0 1187801056 4694784 8785488
17:32:11 5852 8192000000 10160694592 10557542400 1249940096 13.238 99 26 52 1 1262119712 5960016 6219600
17:33:21 7810 8192000000 10162182160 10566193152 1269180016 13.414 100 18 34 0 1278001264 4945584 3875664
17:34:29 28903 8192000000 10173773856 10581348352 1267674832 13.400 99 45 87 1 1283378864 6537248 9166784
17:35:58 6802 8192000000 10192128096 10600599552 1293287312 13.634 100 22 44 0 1304171344 5752640 5131392
17:37:09 5655 8192000000 10195046144 10612334592 1304262896 13.734 100 23 46 0 1315142416 5517152 5362368
17:38:16 29378 8192000000 10232750496 10646728704 1330005200 13.967 100 18 36 0 1338806864 4614304 4187360
17:40:30 16895 8192000000 10260662160 10679132160 1359322928 14.231 100 39 77 0 1377417456 9537200 8557328
17:41:54 56870 8192000000 10267261248 10695675904 1366912224 14.299 100 27 54 0 1386155984 12909280 6334480
17:44:14 17349 8192000000 10299430832 10722394112 1385178976 14.463 100 46 92 0 1415896272 20228640 10488656
17:45:37 5313 8192000000 10323656160 10747056128 1399448416 14.590 100 57 110 1 1434542096 22014400 13079280
17:46:46 3850 8192000000 10327976096 10747113472 1394847616 14.549 100 57 113 0 1433850896 25277904 13725376
17:48:00 10994 8192000000 10322726000 10754478080 1405551360 14.644 100 49 98 0 1442790768 25302560 11936848
17:49:20 2295 8192000000 10332299568 10758471680 1404522064 14.635 100 51 102 1 1444849600 27652000 12675536
17:50:40 2194 8192000000 10347561104 10764017664 1409182720 14.677 100 82 164 2 1458661952 29842080 19637152
17:51:46 4529 8192000000 10350713024 10765869056 1395015920 14.551 99 70 142 2 1447850416 34300096 18534400
17:52:55 4247 8192000000 10357799216 10778525696 1414465392 14.724 100 63 126 0 1468858032 38472592 15920048
17:54:02 4336 8192000000 10373691440 10791559168 1417949648 14.755 100 67 132 0 1475689184 41337632 16401904
17:55:10 5419 8192000000 10322349984 10734047232 1357782704 14.217 98 66 132 0 1413431760 38908752 16740304
17:56:24 2975 8192000000 10346981952 10760572928 1387445200 14.483 100 67 134 1 1446055424 41769008 16841216
17:57:38 8797 8192000000 10312444304 10729295872 1366009808 14.291 99 47 95 0 1418415600 39485984 12919808
17:58:54 13554 8192000000 10315011040 10741534720 1372374432 14.348 100 34 68 0 1422389248 40543392 9471424
18:00:11 12289 8192000000 10311794480 10742026240 1369334608 14.321 100 36 71 1 1415577008 36654432 9587968
18:01:31 29121 8192000000 10285662944 10721255424 1338174896 14.041 99 21 43 0 1377544880 33272736 6097248
18:03:10 50876 8192000000 10259616672 10698903552 1328141424 13.950 98 23 46 1 1348884304 14203264 6539616
18:05:10 28719 8192000000 10279374240 10712702976 1362082288 14.256 100 36 70 2 1378607488 8686224 7838976
18:06:44 28546 8192000000 10281347984 10711609344 1360392448 14.241 100 39 78 0 1379806304 9557408 9856448
18:08:17 82135 8192000000 10284318336 10724802560 1364952272 14.282 99 21 42 0 1380686064 10818656 4915136
18:10:42 112703 8192000000 10296432320 10751217664 1363827728 14.272 98 18 36 2 1382633472 14763440 4042304
18:13:36 42452 8192000000 10284889536 10727948288 1357426192 14.214 98 14 28 0 1373968224 13102384 3439648
18:15:27 28093 8192000000 10290076304 10738151424 1377269168 14.392 99 25 49 0 1394950080 12330368 5350544
18:16:56 12763 8192000000 10328501648 10769879040 1406435680 14.652 100 42 83 0 1431591216 15066912 10088624
18:18:11 20004 8192000000 10340647232 10775490560 1407152640 14.659 100 59 119 1 1438250192 16873792 14223760
18:19:33 54477 8192000000 10318362640 10783543296 1399032720 14.586 99 17 34 0 1421647312 18451872 4162720
18:22:01 49719 8192000000 10324055904 10791989248 1393099760 14.534 99 26 49 1 1422959664 24243888 5616016
18:24:09 24190 8192000000 10311428864 10763890688 1407033792 14.658 100 27 54 0 1421209888 8206480 5969616
18:25:38 47181 8192000000 10309035104 10770079744 1397088512 14.569 99 22 45 0 1412358080 10254896 5014672
18:27:38 63741 8192000000 10331089056 10782748672 1413725808 14.717 100 20 39 1 1426494208 8879712 3888688
18:29:45 59066 8192000000 10329443968 10785865728 1403090704 14.623 99 21 42 0 1425251488 17587888 4572896
18:31:44 97077 8192000000 10318089616 10783412224 1357826416 14.218 96 10 20 0 1380975664 20799968 2349280
18:34:22 66074 8192000000 10294834096 10732060672 1304010288 13.732 93 14 28 1 1318006752 10663680 3332784
18:36:38 74576 8192000000 10280134464 10724945920 1330683952 13.973 96 39 77 0 1349985824 10448208 8853664
18:38:55 23087 8192000000 10275466688 10716078080 1342720016 14.082 98 40 76 1 1360915664 10191248 8004400
18:40:32 29679 8192000000 10274553632 10714456064 1344909824 14.102 98 32 63 0 1361559824 10049024 6600976
18:42:07 49314 8192000000 10253163856 10706046976 1325301504 13.925 97 6 12 0 1335807088 9289680 1215904
18:44:09 56060 8192000000 10253342176 10700128256 1316402416 13.844 96 20 41 0 1328276912 6704896 5169600
18:46:06 80013 8192000000 10272397216 10723987456 1348077744 14.130 98 23 47 0 1365224336 11435648 5710944
18:48:27 47782 8192000000 10263844944 10705334272 1318538080 13.863 97 29 58 0 1335745504 10391856 6815568
18:50:16 62343 8192000000 10281778784 10713182208 1345627936 14.108 99 38 76 0 1366300736 12520336 8152464
18:52:18 49578 8192000000 10256641792 10705133568 1320742720 13.883 97 12 23 0 1335647376 12602416 2302240
18:54:09 26141 8192000000 10289045280 10714722304 1347295152 14.123 99 48 96 1 1366535008 8450048 10789808
18:55:44 89111 8192000000 10266647552 10720845824 1317504800 13.854 96 17 34 0 1335904944 14755616 3644528
18:58:15 80395 8192000000 10276958912 10723020800 1367743392 14.307 100 25 50 0 1384826080 11156480 5926208
19:00:45 17003 8192000000 10278268080 10727755776 1359253360 14.231 99 21 40 0 1383825808 19843072 4729376
19:02:02 65194 8192000000 10281202736 10744995840 1375447088 14.376 100 16 32 0 1393023344 14130672 3445584
19:04:08 91940 8192000000 10272293888 10736787456 1365386544 14.286 99 14 27 1 1376088160 7353072 3348544
19:06:41 42779 8192000000 10295029824 10747731968 1380049232 14.417 100 30 56 1 1395701872 9113760 6538880
19:08:25 68062 8192000000 10306535856 10769076224 1373108656 14.355 98 23 45 0 1390320480 12230288 4981536
19:11:14 48702 8192000000 10301403200 10757054464 1382215744 14.436 99 15 29 0 1395318224 10383408 2719072
19:13:04 60418 8192000000 10300058880 10767527936 1386042016 14.471 99 10 20 0 1400915024 12739088 2133920
19:15:05 52968 8192000000 10298184448 10745815040 1326472256 13.935 95 24 46 1 1340370368 8696720 5201392
19:17:02 90563 8192000000 10297763808 10752208896 1339395664 14.052 96 13 26 0 1356180000 14172352 2611984
19:19:33 124515 8192000000 10270822800 10724212736 1324965872 13.922 96 33 64 0 1342088384 9570336 7552176
19:22:41 45264 8192000000 10266793008 10729046016 1338854416 14.047 97 11 20 1 1352218480 11354688 2009376
19:24:27 78987 8192000000 10250266448 10704994304 1323858624 13.912 97 7 14 0 1334098576 8848624 1391328
19:26:46 73741 8192000000 10244492672 10714931200 1306513040 13.754 96 6 12 0 1318488560 10691104 1284416
19:29:00 25821 8192000000 10266880064 10712371200 1355665264 14.198 100 29 58 1 1370004000 7935632 6403104
19:30:28 40259 8192000000 10296063760 10745655296 1369068912 14.319 100 35 69 0 1391717072 15095696 7552464
19:32:10 53197 8192000000 10274124528 10716626944 1296047584 13.659 95 20 40 0 1313323264 12767296 4508384
19:34:36 62516 8192000000 10279502064 10745974784 1362652768 14.261 100 23 46 0 1391509856 23575536 5281552
19:36:41 54125 8192000000 10282298016 10737000448 1361674864 14.252 99 25 49 0 1385423904 18649776 5099264

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 14-07-2022 19:39:29 to 14-07-2022 19:39:44.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 112 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 14-07-2022 19:39:45 to 14-07-2022 19:39:47.

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          247611
MON$OLDEST_ACTIVE               249528
MON$OLDEST_SNAPSHOT             249528
MON$NEXT_TRANSACTION            249528
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                       2015616
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.33608 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33608 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33608 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33608 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 ---
:1079: 
:1080: mscs-test-161.budzdorov.ru    Thu Jul 14 19:38:34 2022
:1081:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1082: 
:1083: 
:1084: mscs-test-161.budzdorov.ru    Thu Jul 14 19:38:34 2022
:1085:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1086: 
:1087: 
:1088: mscs-test-161.budzdorov.ru    Thu Jul 14 19:38:36 2022
:1089:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1090: 
--- end of diff output ---

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

14.07.22 19:39:48. 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-07-14 19:39:51 No severe PSQL-related problems occured
14.07.22 19:39:52. End of report.