Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 26.12.2022 22:40
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 15262312 kB
MemAvailable 30136348 kB
Buffers 0 kB
Cached 16477108 kB
SwapCached 3292 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-12-26 22:38:31
DTS_END                         2022-12-26 22:38:31.0970
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/38340
TRN_ID                          235829
ATT_ID                          659
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: 8827.19 22347 1059263
customer order: creation 169.88 397 20385
customer order: refuse 14.45 394 1734
order to supplier: creation 45.65 1262 5478
order to supplier: removal 3.13 101074 375
invoice (draft): creation 45.18 3724 5421
invoice (draft): removal 49.53 834 5944
invoice accept: apply 40.54 19785 4865
invoice accept: cancel 44.83 818 5379
customer reserve: creation 3719.18 198 446301
customer reserve: removal 4409.38 55 529125
realization accept: apply 81.63 196 9795
realization accept: cancel 77.68 100 9321
payment from customer: creation 58.01 137 6961
payment from customer: removal 5.71 105 685
payment to supplier: creation 46.64 140 5597
payment to supplier: removal 3.46 53 415
service: total inventory turnovers 5.02 1029 602
service: total monetary turnovers 6.45 24 774
service: aggregate perf. data 0.85 20443 102
service: refresh index statistics 0.03 296170 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 26-12-2022 22:40:38 to 26-12-2022 22:40:41.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 956
TEST_TIME 2 1888
TEST_TIME 3 2654
TEST_TIME 4 3298
TEST_TIME 5 4166
TEST_TIME 6 4305
TEST_TIME 7 4641
TEST_TIME 8 5043
TEST_TIME 9 5048
TEST_TIME 10 5342
TEST_TIME 11 5658
TEST_TIME 12 5768
TEST_TIME 13 5841
TEST_TIME 14 6143
TEST_TIME 15 6491
TEST_TIME 16 6978
TEST_TIME 17 7051
TEST_TIME 18 7100
TEST_TIME 19 7830
TEST_TIME 20 8196
TEST_TIME 21 8370
TEST_TIME 22 8368
TEST_TIME 23 8733
TEST_TIME 24 8947
TEST_TIME 25 9177
TEST_TIME 26 9534
TEST_TIME 27 10233
TEST_TIME 28 10573
TEST_TIME 29 10389
TEST_TIME 30 10148
TEST_TIME 31 10078
TEST_TIME 32 10094
TEST_TIME 33 9885
TEST_TIME 34 9895
TEST_TIME 35 9707
TEST_TIME 36 9762
TEST_TIME 37 9863
TEST_TIME 38 9747
TEST_TIME 39 9633
TEST_TIME 40 9503
TEST_TIME 41 9364
TEST_TIME 42 9263
TEST_TIME 43 9212
TEST_TIME 44 9209
TEST_TIME 45 9295
TEST_TIME 46 9186
TEST_TIME 47 9184
TEST_TIME 48 9088
TEST_TIME 49 9070
TEST_TIME 50 9078
TEST_TIME 51 9251
TEST_TIME 52 9220
TEST_TIME 53 9177
TEST_TIME 54 9305
TEST_TIME 55 9249
TEST_TIME 56 9301
TEST_TIME 57 9255
TEST_TIME 58 9334
TEST_TIME 59 9323
TEST_TIME 60 9412
TEST_TIME 61 9359
TEST_TIME 62 9367
TEST_TIME 63 9410
TEST_TIME 64 9473
TEST_TIME 65 9587
TEST_TIME 66 9455
TEST_TIME 67 9373
TEST_TIME 68 9279
TEST_TIME 69 9263
TEST_TIME 70 9220
TEST_TIME 71 9284
TEST_TIME 72 9202
TEST_TIME 73 9168
TEST_TIME 74 9146
TEST_TIME 75 9071
TEST_TIME 76 9009
TEST_TIME 77 9127
TEST_TIME 78 9085
TEST_TIME 79 9070
TEST_TIME 80 9100
TEST_TIME 81 9064
TEST_TIME 82 9056
TEST_TIME 83 9152
TEST_TIME 84 9121
TEST_TIME 85 9122
TEST_TIME 86 9108
TEST_TIME 87 9189
TEST_TIME 88 9169
TEST_TIME 89 9129
TEST_TIME 90 9206
TEST_TIME 91 9191
TEST_TIME 92 9118
TEST_TIME 93 9065
TEST_TIME 94 9054
TEST_TIME 95 9027
TEST_TIME 96 8979
TEST_TIME 97 8930
TEST_TIME 98 8944
TEST_TIME 99 8925
TEST_TIME 100 9044
TEST_TIME 101 9024
TEST_TIME 102 8998
TEST_TIME 103 9032
TEST_TIME 104 9050
TEST_TIME 105 9025
TEST_TIME 106 9060
TEST_TIME 107 9085
TEST_TIME 108 9116
TEST_TIME 109 9128
TEST_TIME 110 9113
TEST_TIME 111 9038
TEST_TIME 112 8996
TEST_TIME 113 9010
TEST_TIME 114 8989
TEST_TIME 115 8952
TEST_TIME 116 8961
TEST_TIME 117 8941
TEST_TIME 118 8920
TEST_TIME 119 8897
TEST_TIME 120 8830

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 26-12-2022 22:40:42 to 26-12-2022 22:40:45.

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 1472147 1472147 0 0.00 0 45283 7 0 0
doc_list_biud 1472115 1472115 0 0.00 0 31898 26 0 0
sp_add_invoice_to_stock 5016 4865 151 3.01 27 212816 19785 0 151
sp_cancel_adding_invoice 5379 5379 0 0.00 4 77016 818 0 0
sp_cancel_client_order 1734 1734 0 0.00 4 16883 394 0 0
sp_cancel_customer_reserve 529125 529125 0 0.00 0 31900 56 0 0
sp_cancel_pay_from_customer 685 685 0 0.00 3 8801 106 0 0
sp_cancel_pay_to_supplier 415 415 0 0.00 3 1581 53 0 0
sp_cancel_supplier_invoice 5952 5944 8 0.13 2 13147 835 0 8
sp_cancel_supplier_order 375 375 0 0.00 8 998283 101074 0 0
sp_cancel_write_off 9321 9321 0 0.00 1 6402 100 0 0
sp_client_order 20385 20385 0 0.00 3 40748 398 0 0
sp_customer_reserve 446301 446301 0 0.00 0 91111 198 0 0
sp_fill_shopping_cart 31284 31284 0 0.00 1 28936 45 0 0
sp_fill_shopping_cart_clo_res 446299 446299 0 0.00 0 26956 14 0 0
sp_get_clo_for_invoice 4862 4862 0 0.00 0 33781 384 0 0
sp_kill_cost_storno 15800 15800 0 0.00 0 9662 58 0 0
sp_kill_qstorno_ret_qs2qd 1073890 1073890 0 0.00 0 31893 31 0 0
sp_kill_qty_storno 566509 566509 0 0.00 0 31898 67 0 0
sp_lock_selected_doc 20232 20232 0 0.00 0 6004 5 0 0
sp_make_cost_storno 27215 27215 0 0.00 0 45280 64 0 0
sp_multiply_rows_for_pdistr 27183 27183 0 0.00 0 7183 27 0 0
sp_multiply_rows_for_qdistr 20385 20385 0 0.00 0 27307 148 0 0
sp_pay_from_customer 6961 6961 0 0.00 4 12795 137 0 0
sp_pay_to_supplier 5597 5597 0 0.00 4 13374 140 0 0
sp_payment_common 12552 12552 0 0.00 1 13366 38 0 0
sp_qd_handle_on_cancel_clo 1734 1734 0 0.00 0 16877 362 0 0
sp_qd_handle_on_invoice_upd_sts 10227 10227 0 0.00 0 25359 327 0 0
sp_qd_handle_on_reserve_upd_sts 9783 9783 0 0.00 0 14595 65 0 0
sp_reserve_write_off 9795 9795 0 0.00 6 14762 196 0 0
sp_supplier_invoice 5421 5421 0 0.00 7 125728 3725 0 0
sp_supplier_order 5478 5478 0 0.00 5 86780 1262 0 0
srv_aggregate_perf_data 102 102 0 0.00 2773 356922 20443 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1289 141665 47363 0 0
srv_find_qd_qs_mism 1012487 1012487 0 0.00 0 25292 6 0 0
srv_make_invnt_saldo 602 602 0 0.00 40 31366 1030 0 0
srv_make_money_saldo 774 774 0 0.00 1 888 25 0 0
srv_recalc_idx_stat 4 4 0 0.00 180721 422636 296170 0 0
t$perf-abend:sp_add_invoice_to_stock 151 151 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4865 4865 0 0.00 0 16539 198 0 0
t$perf-norm:sp_cancel_adding_invoice 391 391 0 0.00 0 17089 175 0 0
t$perf-norm:sp_cancel_client_order 1734 1734 0 0.00 0 2902 13 0 0
t$perf-norm:sp_cancel_customer_reserve 647 647 0 0.00 0 4603 23 0 0
t$perf-norm:sp_cancel_pay_from_customer 685 685 0 0.00 0 4042 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 415 415 0 0.00 0 4204 27 0 0
t$perf-norm:sp_cancel_supplier_invoice 377 377 0 0.00 0 3813 31 0 0
t$perf-norm:sp_cancel_supplier_order 375 375 0 0.00 0 26019 1900 0 0
t$perf-norm:sp_cancel_write_off 978 978 0 0.00 0 3609 13 0 0
t$perf-norm:sp_client_order 20385 20385 0 0.00 0 16300 19 0 0
t$perf-norm:sp_customer_reserve 1024 1024 0 0.00 0 575 3 0 0
t$perf-norm:sp_pay_from_customer 6961 6961 0 0.00 0 16346 18 0 0
t$perf-norm:sp_pay_to_supplier 5597 5597 0 0.00 0 15399 16 0 0
t$perf-norm:sp_reserve_write_off 9795 9795 0 0.00 0 7822 10 0 0
t$perf-norm:sp_supplier_invoice 5421 5421 0 0.00 0 5997 19 0 0
t$perf-norm:sp_supplier_order 5478 5478 0 0.00 0 9786 21 0 0
t$perf-norm:srv_aggregate_perf_data 102 102 0 0.00 0 402 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 189 4 0 0
t$perf-norm:srv_make_invnt_saldo 602 602 0 0.00 0 9265 35 0 0
t$perf-norm:srv_make_money_saldo 774 774 0 0.00 0 1227 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 6 3 0 0
v_add_invoice_to_stock 9998 9696 302 3.02 0 8767 25 0 302
v_all_customers 36968 36968 0 0.00 0 22 1 0 0
v_all_suppliers 29446 29446 0 0.00 0 22 1 0 0
v_all_wares 40770 40770 0 0.00 0 32 1 0 0
v_cancel_adding_invoice 782 782 0 0.00 0 2348 11 0 0
v_cancel_client_order 3468 3468 0 0.00 0 4082 9 0 0
v_cancel_customer_prepayment 1370 1370 0 0.00 0 8772 17 0 0
v_cancel_customer_reserve 1294 1294 0 0.00 0 1147 4 0 0
v_cancel_payment_to_supplier 830 830 0 0.00 0 191 3 0 0
v_cancel_supplier_invoice 770 754 16 2.07 0 259 13 0 16
v_cancel_supplier_order 750 750 0 0.00 0 3549 8 0 0
v_cancel_write_off 1956 1956 0 0.00 0 1091 14 0 0
v_max_id_clo_ord 5478 5478 0 0.00 0 1247 2 0 0
v_max_id_clo_res 1024 1024 0 0.00 0 2066 9 0 0
v_max_id_ord_sup 5421 5421 0 0.00 0 5692 6 0 0
v_max_non_paid_invoice 1770 1770 0 0.00 0 525 2 0 0
v_max_non_paid_realizn 3717 3717 0 0.00 0 4603 6 0 0
v_min_id_clo_ord 5478 5478 0 0.00 0 4780 11 0 0
v_min_id_clo_res 1024 1024 0 0.00 0 5408 11 0 0
v_min_id_ord_sup 5421 5421 0 0.00 0 19069 15 0 0
v_min_non_paid_invoice 5594 5594 0 0.00 0 13362 25 0 0
v_min_non_paid_realizn 6958 6958 0 0.00 0 10901 32 0 0
v_our_firm 10284 10284 0 0.00 0 18 1 0 0
v_reserve_write_off 19564 19564 0 0.00 0 8826 9 0 0
x_lock_depdocs_on_canc_invoice 10946 10946 0 0.00 0 20438 120 0 0
x_lock_depdocs_on_canc_sup_ord 375 375 0 0.00 0 7355 363 0 0
x_make_qstorno_1000_1200 5478 5478 0 0.00 1 86769 1178 0 0
x_make_qstorno_1200_2000 5421 5421 0 0.00 3 125622 3553 0 0
x_make_qstorno_2100_3300 446301 446301 0 0.00 0 91110 181 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 26-12-2022 22:40:46 to 26-12-2022 22:40:51.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
20:08:34 8 8192000000 8801546272 8813969408 6652880 0.081 3 1 2 0 6753808 58144 42784
20:09:35 29066 8192000000 9917866848 10230472704 998752432 10.866 100 91 182 0 1028601744 6485456 23363856
20:11:14 10677 8192000000 9966499360 10293014528 1050798560 11.368 100 89 178 0 1081442752 8871984 21772208
20:12:34 12579 8192000000 10001344016 10335997952 1084025344 11.686 100 87 174 0 1115213216 9814256 21373616
20:13:53 7606 8192000000 10043227776 10391183360 1130541232 12.126 100 79 158 0 1160289264 10970336 18777696
20:15:07 8094 8192000000 10083320688 10441338880 1169231824 12.490 100 79 158 0 1199587984 12105456 18250704
20:16:16 6442 8192000000 10117732944 10488016896 1211127680 12.880 100 76 152 0 1240367680 11831952 17408048
20:17:26 3970 8192000000 10151669136 10532667392 1246677488 13.208 100 83 163 0 1277659728 13053984 17928256
20:18:32 5517 8192000000 10172668400 10556375040 1265489456 13.380 100 74 148 0 1293820016 12494912 15835648
20:19:40 1176 8192000000 10212699136 10596593664 1294484160 13.645 100 87 174 1 1327238528 12780608 19973760
20:20:42 2313 8192000000 10220757280 10617266176 1315027072 13.832 100 66 132 0 1341786256 11573040 15186144
20:21:46 3588 8192000000 10212010752 10620645376 1318054976 13.859 100 36 72 0 1334284256 8660800 7568480
20:22:52 10064 8192000000 10216811520 10630742016 1327635792 13.946 100 36 73 1 1342712336 6875648 8200896
20:24:02 48825 8192000000 10223694416 10647318528 1335004832 14.012 100 28 56 0 1345816320 5175968 5635520
20:26:22 5258 8192000000 10238569968 10657304576 1344301136 14.096 100 39 79 0 1361143296 7659520 9182640
20:27:31 12447 8192000000 10221266336 10644819968 1333960624 14.003 100 20 41 0 1345415152 5781264 5673264
20:28:45 42567 8192000000 10218067760 10643103744 1332082816 13.986 100 29 59 1 1344256704 4983504 7190384
20:30:29 4740 8192000000 10194471056 10602184704 1274136768 13.459 97 28 55 2 1285741056 4908480 6695808
20:31:36 10734 8192000000 10142689264 10541027328 1220030448 12.962 96 22 43 0 1230856800 5751408 5074944
20:32:49 14952 8192000000 10121059328 10512101376 1172546672 12.521 94 13 26 0 1181130176 5205984 3377520
20:34:06 32987 8192000000 10162315696 10553597952 1225967536 13.017 97 30 61 0 1239110304 6631072 6511696
20:35:44 7737 8192000000 10167455504 10574016512 1277850000 13.493 100 31 62 0 1290693312 5529568 7313744
20:36:53 12711 8192000000 10175404736 10588012544 1286925632 13.576 100 22 44 0 1298101120 5611776 5563712
20:38:08 13080 8192000000 10203096480 10611515392 1308102032 13.769 100 20 40 2 1316943824 4370512 4471280
20:39:28 72588 8192000000 10235296592 10641129472 1325230496 13.924 100 59 116 0 1346434688 8676896 12527296
20:41:44 6745 8192000000 10256683712 10668564480 1348896208 14.138 100 54 108 1 1373862016 12519312 12446496
20:42:58 25381 8192000000 10261796288 10683998208 1354662176 14.189 100 46 90 0 1379372272 14394960 10315136
20:44:36 6642 8192000000 10288237904 10707214336 1374656416 14.369 100 51 102 0 1404075248 17635216 11783616
20:45:46 10878 8192000000 10293310736 10713481216 1379489072 14.412 100 44 87 1 1408583232 19178784 9915376
20:47:02 15695 8192000000 10314119120 10737565696 1394810624 14.549 100 55 110 0 1428284160 21204992 12268544
20:48:22 3805 8192000000 10320031344 10741051392 1395623552 14.556 100 60 120 2 1431544208 21691616 14229040
20:49:29 1583 8192000000 10312509328 10726817792 1380726944 14.423 100 68 134 1 1421884160 24583424 16573792
20:50:34 2649 8192000000 10344892720 10755899392 1400008192 14.595 100 78 156 0 1448324128 29672768 18643168
20:51:43 3991 8192000000 10354235040 10768015360 1395258112 14.553 99 75 150 0 1452377872 38995136 18124624
20:52:55 10311 8192000000 10344635888 10762416128 1391149024 14.516 100 57 111 0 1448328112 42996528 14182560
20:54:12 2610 8192000000 10353027920 10765418496 1396368816 14.563 100 87 172 0 1462516272 44835840 21311616
20:55:20 1287 8192000000 10346301904 10755977216 1387631552 14.485 100 88 171 4 1452742528 43318944 21792032
20:56:32 1868 8192000000 10346835008 10750861312 1377791088 14.397 100 86 170 0 1448158240 48552272 21814880
20:57:41 4610 8192000000 10342196224 10756935680 1379388256 14.411 100 74 148 1 1446891632 48955616 18547760
20:59:00 9248 8192000000 10304899408 10726387712 1350208336 14.149 98 41 78 0 1393738096 32951152 10578608
21:00:15 18909 8192000000 10284372144 10715881472 1340815280 14.065 98 27 54 0 1388682176 40081904 7784992
21:01:47 22503 8192000000 10298679392 10728615936 1344515760 14.098 99 24 48 0 1387683056 36222688 6944608
21:03:11 7522 8192000000 10320271072 10757361664 1370860624 14.335 100 31 62 0 1421777520 42199744 8717152
21:04:26 20566 8192000000 10309102048 10749845504 1367646720 14.306 100 27 54 0 1416139424 40947344 7545360
21:06:05 66103 8192000000 10234243904 10684006400 1300015648 13.695 96 17 34 0 1329584928 25294768 4274512
21:08:36 24382 8192000000 10275592272 10710134784 1362031952 14.256 100 44 87 0 1383841328 12519984 9289392
21:10:04 141665 8192000000 10299346256 10757865472 1386005936 14.470 100 13 26 0 1410370560 21293328 3071296
21:13:26 48776 8192000000 10290632304 10746798080 1372233456 14.347 99 10 20 0 1398766256 23862640 2670160
21:16:33 85120 8192000000 10271045552 10725888000 1354075664 14.184 98 12 24 0 1366173792 9381296 2716832
21:19:24 52331 8192000000 10291000032 10732695552 1365292048 14.285 99 32 64 0 1382443584 9335840 7815696
21:21:18 27778 8192000000 10294693280 10740125696 1389522416 14.502 100 20 38 0 1403606640 9351840 4732384
21:22:59 47837 8192000000 10276419376 10739281920 1354782592 14.190 97 15 30 1 1367156944 8894080 3480272
21:24:47 66024 8192000000 10305642736 10764533760 1380047152 14.417 99 16 32 0 1406450448 22600848 3802448
21:27:14 54307 8192000000 10313389328 10781286400 1394178768 14.543 100 20 40 0 1422540992 23348128 5014096
21:29:14 47896 8192000000 10302699392 10769281024 1380181280 14.418 99 16 31 0 1400027200 16299616 3546304
21:31:07 116604 8192000000 10302199600 10773266432 1389437328 14.501 99 11 22 0 1414741648 22241504 3062816
21:34:08 20485 8192000000 10316597696 10757001216 1396032368 14.560 99 53 104 1 1419213328 11501872 11679088
21:35:33 99486 8192000000 10305475264 10764185600 1355227312 14.194 97 13 26 0 1372350656 14539440 2583904
21:38:12 64125 8192000000 10303551792 10756640768 1358124288 14.221 97 24 48 0 1382900384 19362384 5413712
21:40:17 40996 8192000000 10307532752 10753585152 1358868960 14.227 97 24 48 0 1374315760 10406352 5040448
21:42:04 88320 8192000000 10271775168 10730901504 1296815104 13.666 94 23 45 0 1314591488 12967696 4808688
21:44:51 106178 8192000000 10262423088 10689347584 1312418000 13.808 96 40 81 0 1328611584 7143952 9049632
21:47:39 61547 8192000000 10276319344 10732740608 1358927760 14.228 99 35 68 0 1388621056 22255328 7437968
21:49:40 41681 8192000000 10257130880 10699829248 1342629216 14.081 98 13 26 0 1359980400 14215648 3135536
21:51:27 60007 8192000000 10269525680 10712641536 1346772176 14.118 99 20 40 0 1360013712 8223184 5018352
21:53:35 95924 8192000000 10267801152 10722611200 1337108320 14.031 97 15 30 0 1361913952 21251168 3554464
21:56:12 53678 8192000000 10257480448 10710544384 1334480688 14.008 98 16 32 0 1354090096 15878400 3731008
21:58:50 35584 8192000000 10263435840 10707652608 1329222256 13.960 97 27 54 0 1345352928 10188800 5941872
22:00:34 81867 8192000000 10262065808 10706780160 1295940432 13.658 95 17 34 0 1314024672 14126096 3958144
22:02:56 27901 8192000000 10274860784 10719260672 1365463248 14.286 100 19 38 0 1379488304 9809600 4215456
22:04:36 108731 8192000000 10277744320 10722082816 1353733552 14.181 99 23 46 0 1373441936 15071104 4637280
22:07:30 10962 8192000000 10290334656 10715979776 1360149408 14.239 100 51 102 0 1382271456 11378112 10743936
22:08:43 48416 8192000000 10293601104 10739879936 1377655072 14.396 100 34 68 0 1397602400 12456960 7490368
22:10:35 74006 8192000000 10280732944 10718695424 1347193712 14.122 97 33 66 1 1361345376 6963600 7188064
22:12:51 43693 8192000000 10269012784 10725462016 1366306432 14.294 99 8 14 0 1377461952 9928352 1227168
22:14:35 47596 8192000000 10293926832 10749054976 1387116128 14.480 100 7 14 0 1395061600 6559936 1385536
22:16:23 53161 8192000000 10309683392 10759503872 1383188960 14.445 99 24 48 0 1400787824 11307568 6291296
22:18:18 80913 8192000000 10284365472 10742693888 1366529328 14.296 98 12 25 1 1382883552 13245488 3108736
22:20:42 34847 8192000000 10292717744 10744131584 1361940912 14.255 98 36 70 0 1381870576 11940192 7989472
22:22:20 72507 8192000000 10288554144 10742194176 1336845136 14.029 96 15 30 0 1350161008 10016688 3299184
22:24:41 118105 8192000000 10302902608 10759602176 1336706752 14.028 96 9 18 0 1356234800 17702992 1825056
22:27:41 58211 8192000000 10275580176 10722578432 1351530864 14.161 98 31 63 0 1366614128 8154576 6928688
22:29:39 107566 8192000000 10282850768 10743869440 1362158656 14.257 98 32 64 0 1381497776 11954976 7384144
22:32:28 55683 8192000000 10269786816 10731573248 1347261680 14.123 97 9 18 0 1358406080 9194976 1949424
22:34:24 60563 8192000000 10260146848 10720907264 1335813920 14.020 97 15 30 0 1349326736 10202752 3310064
22:36:25 55480 8192000000 10261550736 10719735808 1365274352 14.285 99 20 40 1 1378526480 8855248 4396880

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 26-12-2022 22:40:53 to 26-12-2022 22:41:08.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 151 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 26-12-2022 22:41:08 to 26-12-2022 22:41:10.

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          234933
MON$OLDEST_ACTIVE               236925
MON$OLDEST_SNAPSHOT             236925
MON$NEXT_TRANSACTION            236925
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                       2011584
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.33650 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33650 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33650 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33650 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 ---
:714: 
:715: mscs-test-161.budzdorov.ru    Mon Dec 26 22:38:54 2022
:716:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:717: 
:718: 
:719: mscs-test-161.budzdorov.ru    Mon Dec 26 22:38:54 2022
:720:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:721: 
--- end of diff output ---

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

26.12.22 22:41:12. 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-12-26 22:41:14 No severe PSQL-related problems occured
26.12.22 22:41:15. End of report.