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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16220656 kB
MemAvailable 30985812 kB
Buffers 0 kB
Cached 15483720 kB
SwapCached 2512 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-31 19:38:28
DTS_END                         2022-07-31 19:38:28.1540
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/37498
TRN_ID                          238363
ATT_ID                          615
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: 9153.67 19982 1098440
customer order: creation 170.16 357 20419
customer order: refuse 14.13 364 1695
order to supplier: creation 44.17 1275 5300
order to supplier: removal 3.48 90060 418
invoice (draft): creation 45.35 3063 5442
invoice (draft): removal 51.92 712 6230
invoice accept: apply 41.53 17578 4983
invoice accept: cancel 47.23 713 5667
customer reserve: creation 3857.81 174 462937
customer reserve: removal 4574.52 53 548942
realization accept: apply 82.97 191 9956
realization accept: cancel 91.22 97 10946
payment from customer: creation 59.30 124 7116
payment from customer: removal 5.98 101 718
payment to supplier: creation 48.83 131 5859
payment to supplier: removal 3.38 66 405
service: total inventory turnovers 4.35 918 522
service: total monetary turnovers 6.52 22 782
service: aggregate perf. data 0.83 20390 99
service: refresh index statistics 0.03 263241 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 31-07-2022 19:39:31 to 31-07-2022 19:39:34.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3638
TEST_TIME 2 3895
TEST_TIME 3 3414
TEST_TIME 4 3971
TEST_TIME 5 4710
TEST_TIME 6 4476
TEST_TIME 7 4940
TEST_TIME 8 5282
TEST_TIME 9 5094
TEST_TIME 10 5298
TEST_TIME 11 5239
TEST_TIME 12 5647
TEST_TIME 13 6165
TEST_TIME 14 6227
TEST_TIME 15 6144
TEST_TIME 16 6430
TEST_TIME 17 6827
TEST_TIME 18 7373
TEST_TIME 19 7530
TEST_TIME 20 7626
TEST_TIME 21 7500
TEST_TIME 22 7831
TEST_TIME 23 8477
TEST_TIME 24 9147
TEST_TIME 25 9474
TEST_TIME 26 9929
TEST_TIME 27 9790
TEST_TIME 28 10037
TEST_TIME 29 9845
TEST_TIME 30 9877
TEST_TIME 31 9733
TEST_TIME 32 9579
TEST_TIME 33 9487
TEST_TIME 34 9321
TEST_TIME 35 9343
TEST_TIME 36 9310
TEST_TIME 37 9319
TEST_TIME 38 9221
TEST_TIME 39 9164
TEST_TIME 40 9078
TEST_TIME 41 9088
TEST_TIME 42 9153
TEST_TIME 43 9122
TEST_TIME 44 8988
TEST_TIME 45 9007
TEST_TIME 46 8999
TEST_TIME 47 9076
TEST_TIME 48 9049
TEST_TIME 49 9014
TEST_TIME 50 9114
TEST_TIME 51 9132
TEST_TIME 52 9267
TEST_TIME 53 9328
TEST_TIME 54 9271
TEST_TIME 55 9296
TEST_TIME 56 9301
TEST_TIME 57 9265
TEST_TIME 58 9499
TEST_TIME 59 9552
TEST_TIME 60 9534
TEST_TIME 61 9434
TEST_TIME 62 9377
TEST_TIME 63 9296
TEST_TIME 64 9318
TEST_TIME 65 9328
TEST_TIME 66 9268
TEST_TIME 67 9269
TEST_TIME 68 9257
TEST_TIME 69 9285
TEST_TIME 70 9289
TEST_TIME 71 9223
TEST_TIME 72 9391
TEST_TIME 73 9317
TEST_TIME 74 9341
TEST_TIME 75 9357
TEST_TIME 76 9388
TEST_TIME 77 9377
TEST_TIME 78 9398
TEST_TIME 79 9405
TEST_TIME 80 9387
TEST_TIME 81 9290
TEST_TIME 82 9272
TEST_TIME 83 9225
TEST_TIME 84 9199
TEST_TIME 85 9187
TEST_TIME 86 9131
TEST_TIME 87 9068
TEST_TIME 88 9071
TEST_TIME 89 9134
TEST_TIME 90 9092
TEST_TIME 91 9130
TEST_TIME 92 9124
TEST_TIME 93 9146
TEST_TIME 94 9239
TEST_TIME 95 9212
TEST_TIME 96 9243
TEST_TIME 97 9234
TEST_TIME 98 9238
TEST_TIME 99 9266
TEST_TIME 100 9340
TEST_TIME 101 9353
TEST_TIME 102 9343
TEST_TIME 103 9370
TEST_TIME 104 9344
TEST_TIME 105 9280
TEST_TIME 106 9259
TEST_TIME 107 9233
TEST_TIME 108 9245
TEST_TIME 109 9253
TEST_TIME 110 9224
TEST_TIME 111 9196
TEST_TIME 112 9208
TEST_TIME 113 9144
TEST_TIME 114 9156
TEST_TIME 115 9146
TEST_TIME 116 9129
TEST_TIME 117 9145
TEST_TIME 118 9142
TEST_TIME 119 9205
TEST_TIME 120 9158

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 31-07-2022 19:39:35 to 31-07-2022 19:39:38.

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 1525129 1525129 0 0.00 0 19521 6 0 0
doc_list_biud 1525128 1525128 0 0.00 0 58544 25 0 0
sp_add_invoice_to_stock 5126 4983 143 2.78 27 162867 17578 0 143
sp_cancel_adding_invoice 5667 5667 0 0.00 3 58292 713 0 0
sp_cancel_client_order 1695 1695 0 0.00 4 9343 364 0 0
sp_cancel_customer_reserve 548942 548942 0 0.00 0 58547 54 0 0
sp_cancel_pay_from_customer 718 718 0 0.00 2 6867 101 0 0
sp_cancel_pay_to_supplier 405 405 0 0.00 3 3142 66 0 0
sp_cancel_supplier_invoice 6242 6230 12 0.19 2 21018 712 0 12
sp_cancel_supplier_order 418 418 0 0.00 7 1034835 90061 0 0
sp_cancel_write_off 10946 10946 0 0.00 1 9487 97 0 0
sp_client_order 20419 20419 0 0.00 3 17947 358 0 0
sp_customer_reserve 462937 462937 0 0.00 0 86461 174 0 0
sp_fill_shopping_cart 31151 31151 0 0.00 1 15979 37 0 0
sp_fill_shopping_cart_clo_res 462930 462930 0 0.00 0 11689 15 0 0
sp_get_clo_for_invoice 4973 4973 0 0.00 0 16449 323 0 0
sp_kill_cost_storno 17736 17736 0 0.00 0 19510 50 0 0
sp_kill_qstorno_ret_qs2qd 1115478 1115478 0 0.00 0 58528 29 0 0
sp_kill_qty_storno 588827 588827 0 0.00 0 58543 63 0 0
sp_lock_selected_doc 20627 20627 0 0.00 0 8450 8 0 0
sp_make_cost_storno 27904 27904 0 0.00 0 12873 53 0 0
sp_multiply_rows_for_pdistr 27904 27904 0 0.00 0 10573 25 0 0
sp_multiply_rows_for_qdistr 20419 20419 0 0.00 0 11828 168 0 0
sp_pay_from_customer 7116 7116 0 0.00 4 12474 124 0 0
sp_pay_to_supplier 5859 5859 0 0.00 4 10594 132 0 0
sp_payment_common 12975 12975 0 0.00 1 9327 29 0 0
sp_qd_handle_on_cancel_clo 1695 1695 0 0.00 0 8211 314 0 0
sp_qd_handle_on_invoice_upd_sts 10640 10640 0 0.00 0 26055 318 0 0
sp_qd_handle_on_reserve_upd_sts 9956 9956 0 0.00 0 9932 63 0 0
sp_reserve_write_off 9956 9956 0 0.00 6 15031 192 0 0
sp_supplier_invoice 5442 5442 0 0.00 6 86138 3064 0 0
sp_supplier_order 5300 5300 0 0.00 4 28710 1276 0 0
srv_aggregate_perf_data 99 99 0 0.00 2734 250209 20391 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1768 109900 43908 0 0
srv_find_qd_qs_mism 1049935 1049935 0 0.00 0 11716 6 0 0
srv_make_invnt_saldo 522 522 0 0.00 45 25383 918 0 0
srv_make_money_saldo 782 782 0 0.00 0 490 22 0 0
srv_recalc_idx_stat 4 4 0 0.00 137544 446585 263241 0 0
t$perf-abend:sp_add_invoice_to_stock 143 143 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4983 4983 0 0.00 0 10951 140 0 0
t$perf-norm:sp_cancel_adding_invoice 407 407 0 0.00 0 1860 70 0 0
t$perf-norm:sp_cancel_client_order 1695 1695 0 0.00 0 13899 28 0 0
t$perf-norm:sp_cancel_customer_reserve 680 680 0 0.00 0 9032 27 0 0
t$perf-norm:sp_cancel_pay_from_customer 718 718 0 0.00 0 2339 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 405 405 0 0.00 0 1042 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 366 366 0 0.00 0 674 8 0 0
t$perf-norm:sp_cancel_supplier_order 418 418 0 0.00 0 25734 1547 0 0
t$perf-norm:sp_cancel_write_off 1009 1009 0 0.00 0 885 4 0 0
t$perf-norm:sp_client_order 20419 20419 0 0.00 0 11216 15 0 0
t$perf-norm:sp_customer_reserve 1041 1041 0 0.00 0 5098 10 0 0
t$perf-norm:sp_pay_from_customer 7116 7116 0 0.00 0 10502 10 0 0
t$perf-norm:sp_pay_to_supplier 5859 5859 0 0.00 0 10437 10 0 0
t$perf-norm:sp_reserve_write_off 9956 9956 0 0.00 0 13076 12 0 0
t$perf-norm:sp_supplier_invoice 5442 5442 0 0.00 0 14381 19 0 0
t$perf-norm:sp_supplier_order 5300 5300 0 0.00 0 5540 13 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 249 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 61 2 0 0
t$perf-norm:srv_make_invnt_saldo 522 522 0 0.00 0 3359 32 0 0
t$perf-norm:srv_make_money_saldo 782 782 0 0.00 0 10887 27 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 11 4 0 0
v_add_invoice_to_stock 10232 9946 286 2.79 0 9696 22 0 286
v_all_customers 37408 37408 0 0.00 0 18 0 0 0
v_all_suppliers 30180 30180 0 0.00 0 20 1 0 0
v_all_wares 40838 40838 0 0.00 0 33 1 0 0
v_cancel_adding_invoice 814 814 0 0.00 0 2964 15 0 0
v_cancel_client_order 3390 3390 0 0.00 0 8487 17 0 0
v_cancel_customer_prepayment 1436 1436 0 0.00 0 6837 17 0 0
v_cancel_customer_reserve 1360 1360 0 0.00 0 2376 5 0 0
v_cancel_payment_to_supplier 810 810 0 0.00 0 3131 9 0 0
v_cancel_supplier_invoice 756 732 24 3.17 0 6890 32 0 24
v_cancel_supplier_order 836 836 0 0.00 0 252 3 0 0
v_cancel_write_off 2018 2018 0 0.00 0 4412 20 0 0
v_max_id_clo_ord 5300 5300 0 0.00 0 5174 3 0 0
v_max_id_clo_res 1041 1041 0 0.00 0 2308 16 0 0
v_max_id_ord_sup 5432 5432 0 0.00 0 5004 6 0 0
v_max_non_paid_invoice 1501 1501 0 0.00 0 1963 5 0 0
v_max_non_paid_realizn 3734 3734 0 0.00 0 5072 6 0 0
v_min_id_clo_ord 5300 5300 0 0.00 0 3877 8 0 0
v_min_id_clo_res 1041 1041 0 0.00 0 5249 10 0 0
v_min_id_ord_sup 5432 5432 0 0.00 0 6642 13 0 0
v_min_non_paid_invoice 5859 5859 0 0.00 0 9322 22 0 0
v_min_non_paid_realizn 7116 7116 0 0.00 0 6299 22 0 0
v_our_firm 10194 10194 0 0.00 0 13 1 0 0
v_reserve_write_off 19912 19912 0 0.00 0 9701 12 0 0
x_lock_depdocs_on_canc_invoice 11531 11531 0 0.00 0 21728 119 0 0
x_lock_depdocs_on_canc_sup_ord 418 418 0 0.00 0 17617 379 0 0
x_make_qstorno_1000_1200 5300 5300 0 0.00 1 28700 1203 0 0
x_make_qstorno_1200_2000 5442 5442 0 0.00 3 85924 2929 0 0
x_make_qstorno_2100_3300 462937 462937 0 0.00 0 86460 157 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 31-07-2022 19:39:39 to 31-07-2022 19:39:43.

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:31 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:33 23112 8192000000 9903702320 10215530496 989861104 10.780 100 84 168 0 1017813008 5722832 22229072
17:11:05 17343 8192000000 9948269728 10272608256 1031655632 11.184 100 88 176 0 1061844064 7981968 22206464
17:12:24 11824 8192000000 9984896928 10315689984 1068237856 11.535 100 80 160 0 1097360000 8946512 20175632
17:13:38 9098 8192000000 10026097840 10375147520 1119858192 12.026 100 74 145 1 1146621072 9567024 17195856
17:14:56 5005 8192000000 10073449024 10430578688 1161850016 12.421 100 76 150 1 1190831728 12209584 16772128
17:16:06 3467 8192000000 10131565360 10497687552 1212714528 12.894 100 86 171 0 1244703840 13084656 18904656
17:17:11 2545 8192000000 10163312192 10537693184 1247773120 13.218 100 91 177 0 1281381296 13843248 19764928
17:18:15 1441 8192000000 10177386064 10556428288 1266949536 13.394 100 89 177 0 1298902576 12633808 19319232
17:19:19 902 8192000000 10199924416 10584850432 1290927920 13.613 100 87 173 0 1324072032 14100752 19043360
17:20:23 6715 8192000000 10198319600 10592808960 1292854928 13.630 100 65 130 0 1318681920 11396512 14430480
17:21:32 10174 8192000000 10195392512 10600116224 1301993024 13.713 100 22 44 1 1311946816 5425888 4527904
17:22:43 4823 8192000000 10206380208 10617610240 1314980544 13.831 100 25 50 0 1331159792 10542064 5637184
17:23:49 53492 8192000000 10219256160 10633428992 1321146768 13.887 100 41 82 2 1336383888 5674800 9562320
17:25:43 4757 8192000000 10219530544 10637762560 1328235408 13.951 100 31 62 0 1342658384 7673600 6749376
17:26:49 4702 8192000000 10224343152 10638700544 1330148320 13.968 100 35 68 0 1344511264 7230688 7132256
17:27:57 15949 8192000000 10219597696 10645172224 1334314336 14.006 100 26 52 0 1347389984 7459312 5616336
17:29:13 6993 8192000000 10196239440 10612711424 1295743616 13.657 99 14 28 0 1304146624 5253056 3149952
17:30:27 4322 8192000000 10167853216 10564435968 1221263344 12.973 95 41 85 0 1237107440 5418512 10425584
17:31:34 58155 8192000000 10133440640 10535419904 1181434496 12.604 94 32 64 2 1198138752 9832320 6871936
17:33:37 4591 8192000000 10140397120 10543431680 1253363216 13.269 100 38 76 0 1268942704 5974528 9604960
17:34:43 9831 8192000000 10168211936 10581729280 1281721616 13.529 100 17 34 0 1291343104 5447168 4174320
17:35:54 52458 8192000000 10200185168 10603757568 1294066896 13.641 100 31 63 0 1311996672 10104176 7825600
17:37:47 8326 8192000000 10196778560 10608508928 1302796864 13.721 100 26 52 0 1314163696 5530096 5836736
17:39:05 19380 8192000000 10228781152 10644094976 1332318656 13.988 100 42 84 0 1348930544 7104464 9507424
17:40:29 6391 8192000000 10258767616 10675593216 1353019680 14.175 100 43 84 0 1373208352 10327728 9860944
17:41:38 29838 8192000000 10267529584 10683793408 1362927312 14.264 100 52 102 0 1388761680 14124640 11709728
17:43:14 32347 8192000000 10281441440 10704666624 1370778784 14.334 100 40 80 0 1396155888 15154880 10222224
17:45:04 13562 8192000000 10278306304 10704719872 1376650192 14.387 100 49 98 0 1402462176 13965120 11846864
17:46:23 7298 8192000000 10295428320 10717962240 1383438544 14.447 100 52 97 1 1411635392 16936432 11260416
17:47:36 14059 8192000000 10309589136 10734215168 1393661232 14.539 100 48 93 0 1424971840 20214944 11095664
17:48:54 19174 8192000000 10326737552 10750377984 1400178672 14.597 100 39 77 0 1435420784 25343648 9898464
17:50:16 2937 8192000000 10349843968 10767306752 1410482144 14.688 100 68 136 1 1460158896 32794688 16882064
17:51:29 7439 8192000000 10352286704 10772168704 1411305200 14.696 100 68 131 0 1458648080 31687376 15655504
17:52:42 2091 8192000000 10352605584 10763288576 1394695472 14.548 98 75 152 2 1443950544 30396448 18858624
17:53:50 3464 8192000000 10367944608 10779680768 1416703600 14.743 100 77 153 1 1473598800 37560128 19335072
17:54:57 1766 8192000000 10352283344 10762076160 1396240384 14.562 99 74 143 2 1455590896 40644784 18705728
17:56:09 2299 8192000000 10356431856 10768285696 1389455328 14.501 99 80 155 1 1450357760 42316384 18586048
17:57:15 20419 8192000000 10330814944 10758053888 1374121728 14.364 98 28 56 0 1417536464 35689328 7725408
17:58:38 20640 8192000000 10320058464 10745004032 1337764192 14.037 96 39 78 0 1387208432 38665456 10778784
18:00:01 16941 8192000000 10299944656 10731237376 1349772384 14.145 99 29 58 1 1405807648 47717696 8317568
18:01:20 8807 8192000000 10307025184 10728685568 1355890816 14.200 100 49 100 0 1412531840 43105664 13535360
18:02:32 14384 8192000000 10269082992 10698756096 1320378848 13.880 98 18 36 0 1355959504 30667248 4913408
18:04:14 38363 8192000000 10242309904 10674245632 1307989424 13.768 97 17 34 1 1337126480 24565280 4571776
18:06:05 45948 8192000000 10253108608 10700894208 1333601952 14.000 99 24 48 0 1362295888 22908208 5785728
18:07:59 67585 8192000000 10271766672 10722414592 1366544880 14.296 100 20 41 0 1381820656 11091664 4184112
18:10:13 98649 8192000000 10264977824 10712354816 1295526128 13.655 94 12 24 0 1308618672 10579392 2513152
18:12:54 41122 8192000000 10273020368 10724741120 1370634912 14.333 100 16 33 0 1386068128 11271600 4161616
18:15:31 62908 8192000000 10267504800 10729484288 1356369632 14.205 99 23 46 1 1378010912 16508096 5133184
18:17:59 18005 8192000000 10292313728 10732347392 1375086416 14.373 100 54 103 2 1401253472 14400592 11766464
18:19:17 42465 8192000000 10278216000 10724806656 1371971680 14.345 100 15 31 0 1387747568 12245920 3529968
18:21:04 36431 8192000000 10270291024 10739511296 1371489744 14.340 100 9 18 0 1393527360 19848352 2189264
18:22:41 39002 8192000000 10269249696 10719793152 1364428432 14.277 99 25 49 2 1385754720 14874800 6451488
18:24:26 58702 8192000000 10292030496 10751504384 1388195888 14.490 100 21 42 0 1401768768 8975904 4596976
18:26:25 66489 8192000000 10301417024 10751823872 1392985824 14.532 100 19 38 0 1410544800 13316832 4242144
18:28:35 30719 8192000000 10309658320 10777546752 1398976432 14.586 100 28 56 0 1426173744 21023280 6174032
18:30:07 92417 8192000000 10304068560 10781892608 1386439888 14.474 99 16 32 0 1411202896 21289872 3473136
18:32:40 6844 8192000000 10323613328 10764230656 1402537648 14.618 100 62 121 0 1426237296 9573792 14125856
18:33:48 105205 8192000000 10318661760 10775478272 1355392480 14.196 96 26 52 0 1379207840 18350272 5465088
18:36:39 109899 8192000000 10312814224 10783707136 1393743808 14.539 99 8 16 0 1413724816 18330384 1650624
18:40:18 47532 8192000000 10302445488 10759057408 1371075824 14.337 97 17 34 0 1385878752 11131840 3671088
18:42:18 54788 8192000000 10279502128 10740883456 1351879584 14.164 97 22 44 0 1368337136 11533696 4923856
18:45:13 56569 8192000000 10290717904 10755702784 1370468384 14.331 98 7 14 0 1388034336 15530320 2035632
18:47:10 44652 8192000000 10265024032 10714451968 1286985296 13.577 93 16 32 0 1303847904 12659952 4202656
18:48:56 31825 8192000000 10286717952 10724761600 1326303392 13.934 96 44 89 0 1350261648 13344080 10614176
18:50:29 66067 8192000000 10283008144 10726531072 1319589088 13.873 96 16 32 0 1340765984 17111280 4065616
18:52:35 44414 8192000000 10261517904 10700439552 1311555840 13.800 96 24 48 0 1327114592 10514288 5044464
18:54:20 39053 8192000000 10259860096 10707005440 1348115136 14.131 100 24 48 0 1367741680 14100864 5525680
18:56:00 53027 8192000000 10264019456 10710102016 1334897312 14.011 98 24 48 0 1350433760 10642080 4894368
18:58:01 105065 8192000000 10247539024 10687201280 1305008576 13.741 96 19 38 2 1317818832 8922352 3887904
19:01:14 60672 8192000000 10248349184 10688741376 1320389344 13.880 98 29 56 0 1335304288 9183040 5731904
19:03:17 46402 8192000000 10237172928 10688356352 1327947488 13.949 99 6 12 0 1335439184 6409584 1082112
19:05:03 76158 8192000000 10263461504 10709049344 1347720672 14.127 99 19 38 0 1365443632 13056576 4666384
19:07:19 54390 8192000000 10259565440 10716463104 1358579600 14.225 100 14 26 0 1380899744 18736432 3583712
19:09:17 53876 8192000000 10285004240 10736115712 1349515408 14.143 98 14 27 0 1367044736 14372336 3156992
19:11:11 69072 8192000000 10288236640 10741751808 1346869872 14.119 97 12 24 0 1366853648 17584384 2399392
19:13:21 29330 8192000000 10303596048 10737508352 1361782400 14.253 98 42 84 1 1378721216 7582640 9356176
19:15:00 65028 8192000000 10309243360 10758041600 1378901360 14.407 99 25 49 0 1393586768 9093088 5592320
19:17:46 53921 8192000000 10308512544 10770857984 1376367824 14.384 98 22 44 0 1396857472 15831120 4658528
19:19:59 107178 8192000000 10306172848 10762657792 1361762224 14.253 97 7 14 1 1376683952 13112672 1809056
19:22:47 91379 8192000000 10311866112 10755928064 1390754656 14.513 99 30 59 0 1406062208 7989872 7317680
19:25:39 67596 8192000000 10303523552 10756935680 1386970464 14.479 98 29 58 0 1409761984 16536784 6254736
19:27:47 36622 8192000000 10266470448 10724290560 1350989552 14.156 97 7 14 0 1362073504 9677888 1406064
19:29:24 62298 8192000000 10282080064 10733137920 1349804352 14.146 97 14 28 0 1363659696 10585568 3269776
19:31:26 72732 8192000000 10288799376 10747977728 1387048032 14.480 100 11 21 0 1399708240 10468528 2191680
19:34:36 34096 8192000000 10279579296 10740187136 1380311104 14.419 100 17 34 0 1393276480 8791024 4174352
19:36:11 44829 8192000000 10266663536 10711531520 1329496560 13.963 98 17 34 0 1344502080 11245360 3760160
19:37:56 37456 8192000000 9106569472 9341186048 266314608 3.148 20 4 8 0 273653232 6407760 930864

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 31-07-2022 19:39:45 to 31-07-2022 19:40:00.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 143 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 31-07-2022 19:40:01 to 31-07-2022 19:40:03.

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          238643
MON$OLDEST_ACTIVE               238644
MON$OLDEST_SNAPSHOT             238644
MON$NEXT_TRANSACTION            238644
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                       2002560
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.33613 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33613 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33613 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33613 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 ---
:583: 
:584: mscs-test-161.budzdorov.ru    Sun Jul 31 19:38:38 2022
:585:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:586: 
:587: 
:588: mscs-test-161.budzdorov.ru    Sun Jul 31 19:38:41 2022
:589:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:590: 
:591: 
:592: mscs-test-161.budzdorov.ru    Sun Jul 31 19:38:41 2022
:593:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:594: 
:595: 
:596: mscs-test-161.budzdorov.ru    Sun Jul 31 19:38:42 2022
:597:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:598: 
--- end of diff output ---

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

31.07.22 19:40:04. 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-31 19:40:06 No severe PSQL-related problems occured
31.07.22 19:40:07. End of report.