Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 11.06.2022 01: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 16087512 kB
MemAvailable 31267872 kB
Buffers 0 kB
Cached 15592608 kB
SwapCached 2436 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-06-11 01:38:17
DTS_END                         2022-06-11 01:38:17.6910
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_ORDER(38:5) ==> X_MAKE_QSTORNO_1000_1200(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/53076
TRN_ID                          247403
ATT_ID                          665
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: 9397.46 21304 1127695
customer order: creation 180.03 339 21604
customer order: refuse 16.05 362 1926
order to supplier: creation 48.91 1291 5869
order to supplier: removal 3.59 108845 431
invoice (draft): creation 47.24 3418 5669
invoice (draft): removal 52.79 873 6335
invoice accept: apply 44.00 17761 5280
invoice accept: cancel 47.23 849 5667
customer reserve: creation 4003.14 177 480377
customer reserve: removal 4650.77 65 558092
realization accept: apply 87.11 197 10453
realization accept: cancel 80.22 121 9627
payment from customer: creation 61.77 123 7412
payment from customer: removal 6.25 91 750
payment to supplier: creation 50.98 142 6118
payment to supplier: removal 3.89 92 467
service: total inventory turnovers 5.06 1103 607
service: total monetary turnovers 7.43 26 891
service: aggregate perf. data 0.97 15846 116
service: refresh index statistics 0.03 274351 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 11-06-2022 01:39:06 to 11-06-2022 01:39:09.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7167
TEST_TIME 2 5690
TEST_TIME 3 5270
TEST_TIME 4 5073
TEST_TIME 5 5832
TEST_TIME 6 5619
TEST_TIME 7 6232
TEST_TIME 8 5970
TEST_TIME 9 6251
TEST_TIME 10 5981
TEST_TIME 11 6576
TEST_TIME 12 6858
TEST_TIME 13 7147
TEST_TIME 14 7080
TEST_TIME 15 7269
TEST_TIME 16 7178
TEST_TIME 17 7531
TEST_TIME 18 7933
TEST_TIME 19 7965
TEST_TIME 20 8092
TEST_TIME 21 8024
TEST_TIME 22 8261
TEST_TIME 23 8568
TEST_TIME 24 8804
TEST_TIME 25 8667
TEST_TIME 26 8952
TEST_TIME 27 9268
TEST_TIME 28 9203
TEST_TIME 29 9008
TEST_TIME 30 9833
TEST_TIME 31 10536
TEST_TIME 32 10961
TEST_TIME 33 10655
TEST_TIME 34 10714
TEST_TIME 35 10629
TEST_TIME 36 10499
TEST_TIME 37 10414
TEST_TIME 38 10255
TEST_TIME 39 10062
TEST_TIME 40 9945
TEST_TIME 41 9896
TEST_TIME 42 10008
TEST_TIME 43 9966
TEST_TIME 44 10070
TEST_TIME 45 10031
TEST_TIME 46 9971
TEST_TIME 47 9886
TEST_TIME 48 9910
TEST_TIME 49 9911
TEST_TIME 50 9834
TEST_TIME 51 9912
TEST_TIME 52 9793
TEST_TIME 53 9662
TEST_TIME 54 9664
TEST_TIME 55 9634
TEST_TIME 56 9581
TEST_TIME 57 9545
TEST_TIME 58 9517
TEST_TIME 59 9406
TEST_TIME 60 9533
TEST_TIME 61 9651
TEST_TIME 62 9587
TEST_TIME 63 9527
TEST_TIME 64 9420
TEST_TIME 65 9445
TEST_TIME 66 9378
TEST_TIME 67 9510
TEST_TIME 68 9456
TEST_TIME 69 9464
TEST_TIME 70 9448
TEST_TIME 71 9475
TEST_TIME 72 9522
TEST_TIME 73 9540
TEST_TIME 74 9539
TEST_TIME 75 9569
TEST_TIME 76 9680
TEST_TIME 77 9681
TEST_TIME 78 9631
TEST_TIME 79 9591
TEST_TIME 80 9526
TEST_TIME 81 9492
TEST_TIME 82 9431
TEST_TIME 83 9385
TEST_TIME 84 9371
TEST_TIME 85 9412
TEST_TIME 86 9350
TEST_TIME 87 9308
TEST_TIME 88 9354
TEST_TIME 89 9359
TEST_TIME 90 9350
TEST_TIME 91 9382
TEST_TIME 92 9352
TEST_TIME 93 9361
TEST_TIME 94 9374
TEST_TIME 95 9477
TEST_TIME 96 9468
TEST_TIME 97 9459
TEST_TIME 98 9543
TEST_TIME 99 9534
TEST_TIME 100 9490
TEST_TIME 101 9531
TEST_TIME 102 9514
TEST_TIME 103 9439
TEST_TIME 104 9458
TEST_TIME 105 9418
TEST_TIME 106 9419
TEST_TIME 107 9403
TEST_TIME 108 9351
TEST_TIME 109 9294
TEST_TIME 110 9317
TEST_TIME 111 9295
TEST_TIME 112 9330
TEST_TIME 113 9345
TEST_TIME 114 9360
TEST_TIME 115 9322
TEST_TIME 116 9360
TEST_TIME 117 9422
TEST_TIME 118 9411
TEST_TIME 119 9468
TEST_TIME 120 9405

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 11-06-2022 01:39:10 to 11-06-2022 01:39:14.

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 1572027 1572027 0 0.00 0 13961 6 0 0
doc_list_biud 1572025 1572025 0 0.00 0 26063 29 0 0
sp_add_invoice_to_stock 5438 5280 158 2.90 26 176266 17762 0 158
sp_cancel_adding_invoice 5667 5667 0 0.00 3 51625 849 0 0
sp_cancel_client_order 1926 1926 0 0.00 4 24275 362 0 0
sp_cancel_customer_reserve 558092 558092 0 0.00 1 26065 66 0 0
sp_cancel_pay_from_customer 750 750 0 0.00 3 3812 91 0 0
sp_cancel_pay_to_supplier 467 467 0 0.00 3 4160 92 0 0
sp_cancel_supplier_invoice 6350 6335 15 0.23 1 24399 873 0 15
sp_cancel_supplier_order 431 431 0 0.00 6 1056522 108846 0 0
sp_cancel_write_off 9627 9627 0 0.00 1 10107 121 0 0
sp_client_order 21604 21604 0 0.00 3 21258 339 0 0
sp_customer_reserve 480377 480377 0 0.00 0 73514 177 0 0
sp_fill_shopping_cart 33139 33139 0 0.00 1 17020 40 0 0
sp_fill_shopping_cart_clo_res 480368 480368 0 0.00 0 23311 14 0 0
sp_get_clo_for_invoice 5270 5270 0 0.00 0 36034 281 0 0
sp_kill_cost_storno 16511 16511 0 0.00 0 6996 66 0 0
sp_kill_qstorno_ret_qs2qd 1132577 1132577 0 0.00 0 26043 35 0 0
sp_kill_qty_storno 597801 597801 0 0.00 0 26062 76 0 0
sp_lock_selected_doc 21881 21881 0 0.00 0 8590 5 0 0
sp_make_cost_storno 29253 29253 0 0.00 0 13959 57 0 0
sp_multiply_rows_for_pdistr 29253 29253 0 0.00 0 6627 30 0 0
sp_multiply_rows_for_qdistr 21604 21604 0 0.00 0 10762 141 0 0
sp_pay_from_customer 7412 7412 0 0.00 4 29444 124 0 0
sp_pay_to_supplier 6118 6118 0 0.00 5 13966 143 0 0
sp_payment_common 13530 13530 0 0.00 1 29420 30 0 0
sp_qd_handle_on_cancel_clo 1926 1926 0 0.00 0 24268 328 0 0
sp_qd_handle_on_invoice_upd_sts 10937 10937 0 0.00 0 21329 362 0 0
sp_qd_handle_on_reserve_upd_sts 10453 10453 0 0.00 0 17055 61 0 0
sp_reserve_write_off 10453 10453 0 0.00 6 17080 197 0 0
sp_supplier_invoice 5669 5669 0 0.00 7 81904 3418 0 0
sp_supplier_order 5869 5869 0 0.00 5 50968 1292 0 0
srv_aggregate_perf_data 116 116 0 0.00 2985 114645 15847 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1173 127357 45531 0 0
srv_find_qd_qs_mism 1077543 1077543 0 0.00 0 9893 5 0 0
srv_make_invnt_saldo 607 607 0 0.00 30 41147 1104 0 0
srv_make_money_saldo 891 891 0 0.00 1 2315 26 0 0
srv_recalc_idx_stat 4 4 0 0.00 102989 387498 274351 0 0
t$perf-abend:sp_add_invoice_to_stock 158 158 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 15 15 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5280 5280 0 0.00 0 22439 171 0 0
t$perf-norm:sp_cancel_adding_invoice 399 399 0 0.00 0 6386 118 0 0
t$perf-norm:sp_cancel_client_order 1926 1926 0 0.00 0 5170 18 0 0
t$perf-norm:sp_cancel_customer_reserve 730 730 0 0.00 0 2824 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 750 750 0 0.00 0 1910 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 467 467 0 0.00 0 753 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 427 427 0 0.00 0 908 15 0 0
t$perf-norm:sp_cancel_supplier_order 431 431 0 0.00 0 30155 1776 0 0
t$perf-norm:sp_cancel_write_off 1028 1028 0 0.00 0 739 4 0 0
t$perf-norm:sp_client_order 21604 21604 0 0.00 0 7192 19 0 0
t$perf-norm:sp_customer_reserve 1065 1065 0 0.00 0 1021 6 0 0
t$perf-norm:sp_pay_from_customer 7412 7412 0 0.00 0 9990 15 0 0
t$perf-norm:sp_pay_to_supplier 6118 6118 0 0.00 0 8927 14 0 0
t$perf-norm:sp_reserve_write_off 10453 10453 0 0.00 0 13905 11 0 0
t$perf-norm:sp_supplier_invoice 5669 5669 0 0.00 0 8231 18 0 0
t$perf-norm:sp_supplier_order 5869 5869 0 0.00 0 7218 17 0 0
t$perf-norm:srv_aggregate_perf_data 116 116 0 0.00 0 1044 20 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 257 4 0 0
t$perf-norm:srv_make_invnt_saldo 607 607 0 0.00 0 9564 47 0 0
t$perf-norm:srv_make_money_saldo 891 891 0 0.00 0 3168 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 5 3 0 0
v_add_invoice_to_stock 10856 10540 316 2.91 0 9070 23 0 316
v_all_customers 39146 39146 0 0.00 0 53 1 0 0
v_all_suppliers 31852 31852 0 0.00 0 20 1 0 0
v_all_wares 43206 43206 0 0.00 0 18 1 0 0
v_cancel_adding_invoice 798 798 0 0.00 0 4297 10 0 0
v_cancel_client_order 3852 3852 0 0.00 0 4735 9 0 0
v_cancel_customer_prepayment 1500 1500 0 0.00 0 3729 13 0 0
v_cancel_customer_reserve 1460 1460 0 0.00 0 2786 9 0 0
v_cancel_payment_to_supplier 934 934 0 0.00 0 2060 7 0 0
v_cancel_supplier_invoice 884 854 30 3.39 0 1545 17 0 30
v_cancel_supplier_order 862 862 0 0.00 0 9130 19 0 0
v_cancel_write_off 2056 2056 0 0.00 0 10000 22 0 0
v_max_id_clo_ord 5869 5869 0 0.00 0 1048 2 0 0
v_max_id_clo_res 1065 1065 0 0.00 0 2309 11 0 0
v_max_id_ord_sup 5667 5667 0 0.00 0 5984 6 0 0
v_max_non_paid_invoice 1728 1728 0 0.00 0 627 3 0 0
v_max_non_paid_realizn 4084 4084 0 0.00 0 5678 4 0 0
v_min_id_clo_ord 5869 5869 0 0.00 0 9303 10 0 0
v_min_id_clo_res 1065 1065 0 0.00 0 4951 10 0 0
v_min_id_ord_sup 5667 5667 0 0.00 0 5423 7 0 0
v_min_non_paid_invoice 6118 6118 0 0.00 0 8591 21 0 0
v_min_non_paid_realizn 7412 7412 0 0.00 0 29414 23 0 0
v_our_firm 10716 10716 0 0.00 0 13 1 0 0
v_reserve_write_off 20906 20906 0 0.00 0 5882 11 0 0
x_lock_depdocs_on_canc_invoice 11575 11575 0 0.00 0 33819 143 0 0
x_lock_depdocs_on_canc_sup_ord 431 431 0 0.00 0 5156 351 0 0
x_make_qstorno_1000_1200 5869 5869 0 0.00 2 50909 1205 0 0
x_make_qstorno_1200_2000 5669 5669 0 0.00 2 76437 3282 0 0
x_make_qstorno_2100_3300 480377 480377 0 0.00 0 73513 161 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 11-06-2022 01:39:15 to 11-06-2022 01:39:19.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
23:08:21 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:22 31118 8192000000 9920684432 10231259136 990309616 10.784 100 89 177 1 1020592256 6194704 24087936
23:10:59 30132 8192000000 9960408368 10282762240 1040077728 11.265 100 93 186 0 1072994352 8763696 24152928
23:12:39 14419 8192000000 9996700816 10331316224 1075144528 11.601 100 85 171 2 1107057648 10583424 21329696
23:14:00 12877 8192000000 10058675424 10409684992 1141691120 12.231 100 81 162 1 1172071040 11147392 19232528
23:15:18 7873 8192000000 10099889904 10465873920 1189631328 12.680 100 72 144 0 1218598784 12234448 16733008
23:16:30 5426 8192000000 10142125664 10513903616 1233541696 13.087 100 77 155 0 1263944240 12970304 17432240
23:17:39 6111 8192000000 10169083504 10549538816 1254180960 13.277 100 76 152 2 1285421488 15116176 16124352
23:18:47 2296 8192000000 10190700352 10571464704 1280199712 13.515 100 96 192 2 1314339136 12681184 21458240
23:19:51 1255 8192000000 10213713216 10595184640 1293537664 13.636 100 95 191 1 1329285056 15154112 20593280
23:20:55 14799 8192000000 10214111344 10605563904 1303910112 13.731 100 68 134 3 1329179248 10639744 14629392
23:22:13 3101 8192000000 10200677312 10606907392 1309507664 13.782 100 38 76 0 1326390912 8736928 8146320
23:23:18 5802 8192000000 10204834880 10615513088 1316224736 13.843 100 28 56 0 1329710384 6384448 7101200
23:24:26 9071 8192000000 10212014800 10628435968 1325557616 13.927 100 18 36 0 1335096064 5815264 3723184
23:25:38 58421 8192000000 10229702464 10643697664 1321779504 13.893 99 37 72 0 1336376048 5892368 8704176
23:27:37 2996 8192000000 10226780144 10643382272 1324667888 13.919 99 34 67 0 1340605664 7008320 8929456
23:28:40 10535 8192000000 10190666048 10599829504 1258287664 13.314 95 23 43 2 1268802960 5750880 4764416
23:29:53 6353 8192000000 10159725904 10563428352 1237894976 13.127 96 19 36 2 1247132176 4933552 4303648
23:31:02 28752 8192000000 10184913312 10567196672 1238654640 13.134 96 64 127 4 1257842240 5106432 14081168
23:32:32 5088 8192000000 10132118896 10528788480 1234309088 13.094 99 26 52 0 1247166368 6019808 6837472
23:33:37 4261 8192000000 10171687248 10572845056 1278066656 13.495 100 30 60 0 1291448096 6716112 6665328
23:34:44 17919 8192000000 10187407936 10587865088 1278725360 13.501 99 31 62 2 1291481520 5084880 7671280
23:36:02 11742 8192000000 10191257568 10600345600 1295997936 13.659 100 26 51 0 1307056592 4968624 6090032
23:37:15 7530 8192000000 10199354816 10608562176 1306774432 13.757 100 28 55 0 1319394336 6139792 6480112
23:38:27 8879 8192000000 10230242704 10640838656 1330743424 13.974 100 38 75 2 1345626752 5614272 9269056
23:39:43 3108 8192000000 10261109904 10670051328 1350372544 14.151 100 64 124 1 1374197152 9546016 14278592
23:40:51 75158 8192000000 10258347744 10688311296 1360727104 14.244 100 29 58 0 1381518336 13555872 7235360
23:43:17 11846 8192000000 10283799760 10705657856 1372827728 14.352 100 34 68 2 1398674496 17479584 8367184
23:44:31 11849 8192000000 10298627680 10717085696 1380377760 14.420 100 49 97 0 1408670704 17083168 11209776
23:45:55 13746 8192000000 10305420912 10732777472 1392381360 14.527 100 48 96 0 1420019104 16105392 11532352
23:47:18 12771 8192000000 10320927520 10748715008 1403088272 14.622 100 48 96 0 1437998224 23084416 11825536
23:48:37 18697 8192000000 10331462832 10757595136 1401775744 14.611 100 58 116 0 1440356384 25124448 13456192
23:49:58 3068 8192000000 10343018960 10765455360 1410482752 14.688 100 65 129 1 1450388976 24639680 15266544
23:51:05 2075 8192000000 10359355136 10771296256 1411214160 14.695 100 80 158 1 1457747840 28035056 18498624
23:52:13 2714 8192000000 10334436720 10750795776 1395048160 14.551 100 67 129 0 1440355760 29698208 15609392
23:53:21 1171 8192000000 10348844336 10754818048 1397454560 14.572 100 81 160 0 1448002720 32255488 18292672
23:54:27 3615 8192000000 10326850624 10736361472 1369503760 14.323 99 72 143 2 1421246592 33761232 17981600
23:55:38 4628 8192000000 10343059008 10750414848 1384898256 14.460 100 80 161 0 1445707968 40185920 20623792
23:56:48 20368 8192000000 10325341840 10753372160 1391356304 14.518 100 37 74 0 1440305952 38886176 10063472
23:58:13 2375 8192000000 10342420752 10758217728 1393089248 14.533 100 65 128 1 1445155088 35589712 16476128
23:59:21 3132 8192000000 10326050768 10744197120 1381874800 14.433 100 58 116 2 1433949088 36111888 15962400
00:00:31 5472 8192000000 10332621968 10742353920 1379206960 14.409 100 77 152 5 1440173088 41097792 19868336
00:01:45 5796 8192000000 10358223872 10778841088 1402370976 14.616 100 72 143 0 1461190784 40017616 18802192
00:02:58 14316 8192000000 10352486016 10783858688 1410493552 14.688 100 37 74 1 1457132512 35891536 10747424
00:04:37 16225 8192000000 10351808256 10788745216 1398045296 14.578 99 42 84 2 1455549440 44886880 12617264
00:06:19 10507 8192000000 10363952624 10807730176 1419252704 14.766 100 32 64 4 1468685120 40492496 8939920
00:07:32 25411 8192000000 10361900832 10799767552 1397236336 14.570 100 36 72 1 1464589456 56160800 11192320
00:09:00 21472 8192000000 10323004912 10776977408 1364087552 14.274 98 19 38 3 1418416400 48750432 5578416
00:10:25 15165 8192000000 10325969440 10757173248 1379280960 14.410 99 59 117 0 1405956144 13132272 13542912
00:12:03 40421 8192000000 10277847808 10732949504 1340888304 14.065 97 20 40 0 1359679232 14669600 4121328
00:13:47 50048 8192000000 10295910992 10749739008 1387994800 14.488 100 12 24 0 1398212048 7525792 2691456
00:15:45 90207 8192000000 10321768368 10780000256 1407064400 14.658 100 23 46 0 1423903152 10994960 5843792
00:18:51 68985 8192000000 10318765504 10772213760 1389421952 14.501 99 20 40 2 1421125216 26961408 4741856
00:21:02 89404 8192000000 10302283152 10769440768 1365293776 14.285 98 8 16 0 1385324576 17953328 2077472
00:23:32 80710 8192000000 10294936320 10747658240 1376170240 14.382 99 5 10 0 1386308304 8664448 1473616
00:25:53 28158 8192000000 10305810576 10756182016 1396071232 14.560 100 21 42 0 1411711360 10639984 5000144
00:27:31 105197 8192000000 10306400416 10767777792 1371911072 14.344 98 8 16 0 1389585856 15714464 1960320
00:30:19 116648 8192000000 10281787488 10736939008 1371778672 14.343 99 36 72 2 1387881728 7383472 8719584
00:33:19 46396 8192000000 10312528176 10766319616 1403204880 14.624 100 36 71 0 1422190416 10558192 8427344
00:35:06 9844 8192000000 10295595248 10742775808 1382435824 14.438 100 46 92 2 1402691152 10083040 10172288
00:36:20 81675 8192000000 10296704352 10733015040 1342773344 14.082 97 25 50 0 1361261664 12380608 6107712
00:38:44 17455 8192000000 10291042000 10733686784 1375413488 14.376 100 47 94 1 1397523392 10989920 11119984
00:40:05 67945 8192000000 10259643840 10714669056 1335743232 14.019 97 10 21 1 1348354608 10053696 2557680
00:42:13 85291 8192000000 10277641616 10732052480 1352321376 14.168 99 12 24 0 1367782064 13122000 2338688
00:44:39 53646 8192000000 10260653280 10714812416 1320157248 13.878 97 17 34 1 1341557744 17226096 4174400
00:46:32 24485 8192000000 10285772352 10727415808 1353159904 14.176 99 22 44 2 1368132704 9926560 5046240
00:47:58 29581 8192000000 10285149728 10728349696 1358494672 14.224 99 26 50 2 1376349392 11721664 6133056
00:49:30 36558 8192000000 10286775136 10729349120 1342969392 14.084 97 28 56 1 1361114016 11939648 6204976
00:51:15 127357 8192000000 10296205600 10749931520 1325569008 13.927 96 13 26 0 1338824096 10499792 2755296
00:54:29 72117 8192000000 10300215888 10759454720 1387609264 14.485 100 13 26 1 1409794672 19350064 2835344
00:56:42 20686 8192000000 10302639760 10736013312 1363211104 14.266 99 40 80 2 1382054752 10093424 8750224
00:58:06 102769 8192000000 10277533200 10728960000 1325295616 13.925 96 25 50 1 1338163712 7085552 5782544
01:00:50 34243 8192000000 10287090320 10742427648 1384588048 14.458 100 21 42 2 1401266128 11944496 4733584
01:02:32 66734 8192000000 10295820016 10763464704 1397329888 14.571 100 12 24 2 1412347424 11806416 3211120
01:04:38 81986 8192000000 10279139088 10745126912 1374750464 14.370 99 14 28 0 1392728096 14680288 3297344
01:07:01 66829 8192000000 10288974272 10758148096 1386984368 14.479 100 6 12 0 1405301968 16934848 1382752
01:09:08 73064 8192000000 10292788912 10750279680 1357558800 14.215 97 15 29 1 1374483712 13588672 3336240
01:11:23 64060 8192000000 10294694032 10744987648 1359402384 14.232 98 25 50 0 1377440976 12650688 5387904
01:13:27 108356 8192000000 10296878048 10754187264 1349208720 14.140 97 19 38 0 1367777920 14478784 4090416
01:16:18 95771 8192000000 10309462432 10772344832 1387612352 14.485 99 9 18 0 1403752816 14272352 1868112
01:18:54 13561 8192000000 10312331184 10745118720 1382342912 14.437 99 59 118 0 1407736688 11989520 13404256
01:20:11 62975 8192000000 10262639456 10707685376 1303927872 13.731 94 19 38 1 1316122032 6862336 5331824
01:22:40 87896 8192000000 10283676080 10743369728 1351585936 14.162 98 8 16 2 1370901392 17783088 1532368
01:25:09 85355 8192000000 10263325344 10727153664 1346607008 14.117 98 7 14 0 1358751072 10564240 1579824
01:27:35 107651 8192000000 10255950672 10711113728 1317094640 13.850 96 20 40 1 1331366688 9285104 4986944
01:30:37 69943 8192000000 10265565712 10722115584 1346960432 14.120 99 16 32 1 1361970240 11283024 3726784
01:32:50 59208 8192000000 10269853728 10708312064 1311754608 13.802 96 21 42 0 1329367072 12674496 4937968
01:34:50 51681 8192000000 10261004240 10709561344 1289855008 13.603 95 17 34 2 1306013792 12453200 3705584
01:36:42 70952 8192000000 10253064432 10712408064 1307278064 13.761 97 12 24 0 1325352064 15456512 2617488

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 11-06-2022 01:39:21 to 11-06-2022 01:39:36.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 158 v_add_invoice_to_stock 335544517
except 15 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 11-06-2022 01:39:37 to 11-06-2022 01:39:38.

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          246359
MON$OLDEST_ACTIVE               247567
MON$OLDEST_SNAPSHOT             247567
MON$NEXT_TRANSACTION            247567
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                       2053728
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.33602 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33602 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33602 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33602 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 ---
:365: 
:366: mscs-test-161.budzdorov.ru    Sat Jun 11 01:38:22 2022
:367:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:368: 
:369: 
:370: mscs-test-161.budzdorov.ru    Sat Jun 11 01:38:23 2022
:371:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:372: 
--- end of diff output ---

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

11.06.22 01:39:39. 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-06-11 01:39:42 No severe PSQL-related problems occured
11.06.22 01:39:43. End of report.