Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 02.02.2023 04: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 15094184 kB
MemAvailable 30355556 kB
Buffers 20 kB
Cached 16525008 kB
SwapCached 3612 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 4.0.3
DB_NAME                         /home/bases/oltp40-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/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/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=3400
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/oltp40-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-oltp40
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 2023-02-02 04:37:25
DTS_END                         2023-02-02 04:37:25.7610
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(68:17)
IP                              ::1/60068
TRN_ID                          211566
ATT_ID                          644
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: 8866.67 27552 1064001
customer order: creation 160.63 522 19275
customer order: refuse 14.28 375 1713
order to supplier: creation 42.43 1418 5091
order to supplier: removal 3.44 165979 413
invoice (draft): creation 42.31 3088 5077
invoice (draft): removal 52.60 936 6312
invoice accept: apply 38.19 26594 4583
invoice accept: cancel 47.02 1115 5642
customer reserve: creation 3582.70 253 429924
customer reserve: removal 4601.19 97 552143
realization accept: apply 78.51 259 9421
realization accept: cancel 81.74 158 9809
payment from customer: creation 55.66 179 6679
payment from customer: removal 5.34 124 641
payment to supplier: creation 45.18 202 5422
payment to supplier: removal 3.50 111 420
service: total inventory turnovers 4.21 3426 505
service: total monetary turnovers 6.83 419 820
service: aggregate perf. data 0.89 17807 107
service: refresh index statistics 0.03 327979 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 02-02-2023 04:38:26 to 02-02-2023 04:38:29.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8967
TEST_TIME 2 5564
TEST_TIME 3 5790
TEST_TIME 4 5639
TEST_TIME 5 5259
TEST_TIME 6 5314
TEST_TIME 7 5379
TEST_TIME 8 5575
TEST_TIME 9 5520
TEST_TIME 10 5942
TEST_TIME 11 5992
TEST_TIME 12 6401
TEST_TIME 13 6333
TEST_TIME 14 6415
TEST_TIME 15 6477
TEST_TIME 16 6423
TEST_TIME 17 6974
TEST_TIME 18 6969
TEST_TIME 19 7059
TEST_TIME 20 7231
TEST_TIME 21 7556
TEST_TIME 22 7776
TEST_TIME 23 8011
TEST_TIME 24 8403
TEST_TIME 25 8962
TEST_TIME 26 9225
TEST_TIME 27 9502
TEST_TIME 28 9913
TEST_TIME 29 10339
TEST_TIME 30 10444
TEST_TIME 31 10761
TEST_TIME 32 10562
TEST_TIME 33 10352
TEST_TIME 34 10207
TEST_TIME 35 10065
TEST_TIME 36 10057
TEST_TIME 37 9864
TEST_TIME 38 10082
TEST_TIME 39 10165
TEST_TIME 40 10001
TEST_TIME 41 9946
TEST_TIME 42 9807
TEST_TIME 43 9765
TEST_TIME 44 10013
TEST_TIME 45 9874
TEST_TIME 46 9786
TEST_TIME 47 9660
TEST_TIME 48 9580
TEST_TIME 49 9616
TEST_TIME 50 9581
TEST_TIME 51 9586
TEST_TIME 52 9610
TEST_TIME 53 9471
TEST_TIME 54 9333
TEST_TIME 55 9255
TEST_TIME 56 9131
TEST_TIME 57 9099
TEST_TIME 58 9085
TEST_TIME 59 9143
TEST_TIME 60 9095
TEST_TIME 61 8993
TEST_TIME 62 8931
TEST_TIME 63 9020
TEST_TIME 64 8969
TEST_TIME 65 8999
TEST_TIME 66 9038
TEST_TIME 67 9132
TEST_TIME 68 9079
TEST_TIME 69 9124
TEST_TIME 70 9150
TEST_TIME 71 9082
TEST_TIME 72 9085
TEST_TIME 73 9174
TEST_TIME 74 9165
TEST_TIME 75 9159
TEST_TIME 76 9209
TEST_TIME 77 9216
TEST_TIME 78 9226
TEST_TIME 79 9134
TEST_TIME 80 9070
TEST_TIME 81 9134
TEST_TIME 82 9063
TEST_TIME 83 8997
TEST_TIME 84 9010
TEST_TIME 85 8951
TEST_TIME 86 8932
TEST_TIME 87 8917
TEST_TIME 88 8917
TEST_TIME 89 8882
TEST_TIME 90 8862
TEST_TIME 91 8852
TEST_TIME 92 8900
TEST_TIME 93 8933
TEST_TIME 94 8949
TEST_TIME 95 8954
TEST_TIME 96 9013
TEST_TIME 97 9088
TEST_TIME 98 9055
TEST_TIME 99 9117
TEST_TIME 100 9095
TEST_TIME 101 9123
TEST_TIME 102 9081
TEST_TIME 103 9014
TEST_TIME 104 8979
TEST_TIME 105 8962
TEST_TIME 106 8965
TEST_TIME 107 8921
TEST_TIME 108 8876
TEST_TIME 109 8864
TEST_TIME 110 8823
TEST_TIME 111 8790
TEST_TIME 112 8758
TEST_TIME 113 8835
TEST_TIME 114 8823
TEST_TIME 115 8816
TEST_TIME 116 8812
TEST_TIME 117 8855
TEST_TIME 118 8849
TEST_TIME 119 8892
TEST_TIME 120 8885

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 02-02-2023 04:38:30 to 02-02-2023 04:38:34.

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 1461193 1461193 0 0.00 0 22348 13 0 0
doc_list_biud 1461174 1461174 0 0.00 0 23863 42 0 0
sp_add_invoice_to_stock 4762 4583 179 3.75 40 193211 26595 0 179
sp_cancel_adding_invoice 5642 5642 0 0.00 5 65134 1116 0 0
sp_cancel_client_order 1713 1713 0 0.00 6 9828 376 0 0
sp_cancel_customer_reserve 552143 552143 0 0.00 1 20796 97 0 0
sp_cancel_pay_from_customer 641 641 0 0.00 4 5066 124 0 0
sp_cancel_pay_to_supplier 420 420 0 0.00 3 2635 111 0 0
sp_cancel_supplier_invoice 6329 6312 17 0.26 2 17466 937 0 17
sp_cancel_supplier_order 413 413 0 0.00 12 1297490 165980 0 0
sp_cancel_write_off 9809 9809 0 0.00 2 22400 158 0 0
sp_client_order 19275 19275 0 0.00 5 22036 522 0 0
sp_customer_reserve 429924 429924 0 0.00 0 93446 253 0 0
sp_fill_shopping_cart 29434 29434 0 0.00 1 14228 77 0 0
sp_fill_shopping_cart_clo_res 429886 429886 0 0.00 0 13422 19 0 0
sp_get_clo_for_invoice 4537 4537 0 0.00 0 35590 349 0 0
sp_kill_cost_storno 16512 16512 0 0.00 0 11511 70 0 0
sp_kill_qstorno_ret_qs2qd 1120820 1120820 0 0.00 0 22392 43 0 0
sp_kill_qty_storno 589987 589987 0 0.00 0 23862 97 0 0
sp_lock_selected_doc 19582 19582 0 0.00 0 6980 7 0 0
sp_make_cost_storno 26058 26058 0 0.00 0 13748 79 0 0
sp_multiply_rows_for_pdistr 26057 26057 0 0.00 0 6946 38 0 0
sp_multiply_rows_for_qdistr 19274 19274 0 0.00 0 11402 206 0 0
sp_pay_from_customer 6679 6679 0 0.00 5 16379 180 0 0
sp_pay_to_supplier 5422 5422 0 0.00 7 13185 203 0 0
sp_payment_common 12101 12101 0 0.00 2 9607 41 0 0
sp_qd_handle_on_cancel_clo 1713 1713 0 0.00 1 9820 303 0 0
sp_qd_handle_on_invoice_upd_sts 10178 10178 0 0.00 0 23862 473 0 0
sp_qd_handle_on_reserve_upd_sts 9419 9419 0 0.00 0 10335 56 0 0
sp_reserve_write_off 9421 9421 0 0.00 8 11983 260 0 0
sp_supplier_invoice 5077 5077 0 0.00 10 80137 3089 0 0
sp_supplier_order 5091 5091 0 0.00 8 39573 1418 0 0
srv_aggregate_perf_data 107 107 0 0.00 2598 165731 17807 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 864 162019 39073 0 0
srv_find_qd_qs_mism 1019022 1019022 0 0.00 0 22347 10 0 0
srv_make_invnt_saldo 505 505 0 0.00 53 54882 3427 0 0
srv_make_money_saldo 820 820 0 0.00 1 24102 419 0 0
srv_recalc_idx_stat 4 4 0 0.00 42650 536900 327980 0 0
t$perf-abend:sp_add_invoice_to_stock 179 179 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 17 17 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4584 4584 0 0.00 0 11417 819 0 0
t$perf-norm:sp_cancel_adding_invoice 384 384 0 0.00 1 16875 435 0 0
t$perf-norm:sp_cancel_client_order 1713 1713 0 0.00 0 5007 33 0 0
t$perf-norm:sp_cancel_customer_reserve 651 651 0 0.00 0 5274 34 0 0
t$perf-norm:sp_cancel_pay_from_customer 641 641 0 0.00 0 1800 17 0 0
t$perf-norm:sp_cancel_pay_to_supplier 420 420 0 0.00 0 5628 36 0 0
t$perf-norm:sp_cancel_supplier_invoice 404 404 0 0.00 0 2446 42 0 0
t$perf-norm:sp_cancel_supplier_order 413 413 0 0.00 0 92340 6985 0 0
t$perf-norm:sp_cancel_write_off 1003 1003 0 0.00 0 562 17 0 0
t$perf-norm:sp_client_order 19276 19276 0 0.00 0 7663 35 0 0
t$perf-norm:sp_customer_reserve 957 957 0 0.00 0 1146 11 0 0
t$perf-norm:sp_pay_from_customer 6679 6679 0 0.00 0 7164 17 0 0
t$perf-norm:sp_pay_to_supplier 5423 5423 0 0.00 0 5712 19 0 0
t$perf-norm:sp_reserve_write_off 9421 9421 0 0.00 0 6307 21 0 0
t$perf-norm:sp_supplier_invoice 5077 5077 0 0.00 0 1756 26 0 0
t$perf-norm:sp_supplier_order 5091 5091 0 0.00 0 3404 31 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 728 15 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 194 7 0 0
t$perf-norm:srv_make_invnt_saldo 505 505 0 0.00 0 731 10 0 0
t$perf-norm:srv_make_money_saldo 820 820 0 0.00 0 149 4 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 6 149 60 0 0
v_add_invoice_to_stock 9426 9068 358 3.79 0 16649 30 0 358
v_all_customers 35540 35540 0 0.00 0 132 3 0 0
v_all_suppliers 28394 28394 0 0.00 0 50 4 0 0
v_all_wares 38544 38544 0 0.00 0 60 4 0 0
v_cancel_adding_invoice 768 768 0 0.00 0 1935 18 0 0
v_cancel_client_order 3426 3426 0 0.00 0 1381 9 0 0
v_cancel_customer_prepayment 1282 1282 0 0.00 0 2753 13 0 0
v_cancel_customer_reserve 1302 1302 0 0.00 0 1108 11 0 0
v_cancel_payment_to_supplier 840 840 0 0.00 0 1157 12 0 0
v_cancel_supplier_invoice 842 808 34 4.03 0 7386 34 0 34
v_cancel_supplier_order 826 826 0 0.00 0 6344 20 0 0
v_cancel_write_off 2006 2006 0 0.00 0 3167 26 0 0
v_max_id_clo_ord 5090 5090 0 0.00 0 272 4 0 0
v_max_id_clo_res 957 957 0 0.00 0 8024 23 0 0
v_max_id_ord_sup 5072 5072 0 0.00 0 4747 6 0 0
v_max_non_paid_invoice 1387 1387 0 0.00 0 65 7 0 0
v_max_non_paid_realizn 3324 3324 0 0.00 0 1465 8 0 0
v_min_id_clo_ord 5090 5090 0 0.00 0 1630 7 0 0
v_min_id_clo_res 957 957 0 0.00 0 3460 12 0 0
v_min_id_ord_sup 5072 5072 0 0.00 0 4920 10 0 0
v_min_non_paid_invoice 5422 5422 0 0.00 0 6983 12 0 0
v_min_non_paid_realizn 6679 6679 0 0.00 0 4942 14 0 0
v_our_firm 9714 9714 0 0.00 0 61 4 0 0
v_reserve_write_off 18838 18838 0 0.00 0 11943 14 0 0
x_lock_depdocs_on_canc_invoice 11550 11550 0 0.00 0 15265 136 0 0
x_lock_depdocs_on_canc_sup_ord 413 413 0 0.00 0 11581 373 0 0
x_make_qstorno_1000_1200 5091 5091 0 0.00 2 39559 1265 0 0
x_make_qstorno_1200_2000 5077 5077 0 0.00 4 79926 2874 0 0
x_make_qstorno_2100_3300 429924 429924 0 0.00 0 93444 223 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 02-02-2023 04:38:35 to 02-02-2023 04:38:39.

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
02:07:29 2 8192000000 8798442800 8809533440 3189136 0.038 1 1 2 0 3286752 61840 35776
02:08:31 36024 8192000000 9725887552 10024275968 797980704 8.876 100 92 184 0 827381088 7837520 21562864
02:10:12 16736 8192000000 9767954432 10076229632 838509424 9.285 100 88 176 0 871915984 12470336 20936224
02:11:34 13576 8192000000 9803428176 10123276288 874819040 9.648 100 83 166 0 911514736 18026624 18669072
02:12:53 7250 8192000000 9862696288 10194755584 915276832 10.049 100 80 160 0 976628976 44070272 17281872
02:14:07 3947 8192000000 9925275792 10265550848 956001136 10.450 100 86 171 0 1035869216 62340368 17527712
02:15:13 2372 8192000000 9957647216 10311639040 991108960 10.792 100 84 164 0 1071291072 64131440 16050672
02:16:18 1091 8192000000 10007644608 10369089536 1021729200 11.089 100 95 181 0 1129511248 90036720 17745328
02:17:22 964 8192000000 10019041248 10387726336 1044542960 11.308 100 92 180 1 1140294240 76922736 18828544
02:18:24 1006 8192000000 10030569840 10406866944 1058656272 11.444 100 98 185 0 1152255536 75124624 18474640
02:19:25 1112 8192000000 10021883056 10399068160 1062491712 11.480 100 93 180 1 1143225600 61907088 18826800
02:20:38 1554 8192000000 10023248560 10407723008 1070774832 11.559 100 77 147 2 1143291584 57948768 14567984
02:21:41 1076 8192000000 10053174576 10439696384 1074381120 11.594 100 82 158 1 1174304704 83744976 16178608
02:22:42 1222 8192000000 10036744960 10419470336 1078546320 11.634 100 96 184 1 1159733040 62765168 18421552
02:23:45 1173 8192000000 10056817456 10440667136 1084026480 11.686 100 95 188 0 1173260976 70135392 19099104
02:24:46 1167 8192000000 10053768528 10439835648 1087199552 11.716 100 95 183 1 1176400128 70489728 18710848
02:25:48 1068 8192000000 10042034800 10428579840 1086149616 11.706 100 95 179 2 1159854256 56029072 17675568
02:26:50 1184 8192000000 10051295616 10441093120 1089157040 11.735 100 98 181 2 1172680640 63882976 19640624
02:27:52 9870 8192000000 10046154592 10434105344 1085235184 11.697 100 72 133 6 1159795728 60787264 13773280
02:29:05 1089 8192000000 10025715904 10416087040 1082772672 11.674 100 85 157 2 1146817824 48106448 15938704
02:30:07 1156 8192000000 10016234480 10401300480 1067544992 11.529 99 83 153 2 1139087920 56820720 14722208
02:31:09 1033 8192000000 10010064000 10385379328 1045603120 11.318 99 92 172 1 1134622240 70547744 18471376
02:32:11 1457 8192000000 9993624432 10365800448 1045192976 11.315 100 95 178 2 1114889840 52449920 17246944
02:33:13 1296 8192000000 10000838560 10370306048 1041689568 11.281 100 100 187 0 1124887984 63796880 19401536
02:34:15 1158 8192000000 10002008912 10370048000 1029428480 11.163 99 96 175 2 1124366544 76092144 18845920
02:35:17 7493 8192000000 9981563312 10360762368 1016613296 11.039 97 63 118 1 1083972480 54543600 12815584
02:36:25 1001 8192000000 10007116768 10383876096 1048795184 11.349 100 95 186 1 1129559712 61881104 18883424
02:37:36 4109 8192000000 9999855776 10385772544 1062479776 11.480 100 62 112 1 1118740544 46077600 10183168
02:38:46 982 8192000000 10021614784 10404925440 1087080352 11.715 100 86 166 2 1146349424 43038256 16230816
02:39:51 10431 8192000000 10081007040 10467041280 1101427184 11.851 100 75 142 3 1199015120 83028128 14559808
02:41:05 1825 8192000000 10082202528 10473422848 1116022832 11.989 100 74 132 4 1201202000 71567904 13611264
02:42:15 20613 8192000000 10138957200 10539171840 1129289216 12.115 100 61 115 0 1253830720 112490208 12051296
02:43:42 2885 8192000000 10205164688 10604740608 1143271568 12.246 100 82 159 0 1324949984 164292736 17385680
02:44:51 5293 8192000000 10247053344 10658316288 1155318528 12.359 100 65 127 1 1360891856 191314160 14259168
02:46:03 5459 8192000000 10264859168 10670915584 1169103328 12.488 100 72 141 2 1381613328 197007440 15502560
02:47:13 1931 8192000000 10321210272 10739716096 1182456384 12.613 100 95 181 2 1436357808 232998176 20903248
02:48:17 1796 8192000000 10340791392 10746400768 1191838368 12.700 100 88 165 1 1453156816 242417744 18900704
02:49:22 949 8192000000 10383436752 10796978176 1199753760 12.774 100 95 187 0 1496022592 273300944 22967888
02:50:26 5677 8192000000 10393854896 10801381376 1199119424 12.768 100 84 164 0 1507630208 288995760 19515024
02:51:37 831 8192000000 10444723552 10868113408 1205118352 12.824 100 95 185 0 1561164416 332187168 23858896
02:52:42 2680 8192000000 10486815184 10894348288 1207816080 12.849 100 96 185 2 1596173152 366382288 21974784
02:53:50 848 8192000000 10495565936 10949361664 1213249168 12.899 100 91 179 0 1602598176 368654928 20694080
02:54:54 1090 8192000000 10534009616 10949574656 1213582784 12.902 100 91 178 1 1640324752 405248688 21493280
02:55:59 2319 8192000000 10548975728 10999668736 1211655968 12.884 100 71 138 0 1656496304 426430560 18409776
02:57:06 1178 8192000000 10561947328 11014942720 1206883248 12.840 100 91 180 1 1664996640 436589280 21524112
02:58:10 5313 8192000000 10572942688 11053981696 1209212656 12.862 100 61 121 0 1677284656 452395184 15676816
02:59:21 1032 8192000000 10580172432 11020181504 1206517568 12.837 100 95 186 0 1683935856 454192896 23225392
03:00:26 4103 8192000000 10585845472 11054931968 1199658672 12.773 100 67 132 0 1686002288 469346720 16996896
03:01:41 9843 8192000000 10529206624 10979856384 1190703888 12.690 100 57 114 0 1645991232 440626432 14660912
03:03:03 13862 8192000000 10449254944 10914918400 1183582384 12.624 100 37 72 0 1547213024 354290624 9340016
03:04:36 30177 8192000000 10356992896 10882666496 1167280304 12.471 100 25 50 0 1468286256 294209904 6796048
03:06:37 25509 8192000000 10186158096 10694868992 1147777136 12.289 100 25 50 0 1286569872 133286368 5506368
03:08:08 10073 8192000000 10159306608 10647048192 1172736640 12.522 100 63 120 0 1258755776 72365872 13653264
03:09:19 90600 8192000000 10186827056 10639253504 1157680496 12.382 99 47 91 1 1267056544 99731424 9644624
03:12:09 65067 8192000000 10175966192 10651668480 1126646976 12.090 97 17 36 0 1258328400 127974560 3706864
03:15:23 81175 8192000000 10093395200 10601353216 1156040784 12.366 100 14 29 0 1267985968 108436496 3508688
03:17:45 49065 8192000000 10123217984 10580357120 1142823184 12.242 100 31 61 0 1234404144 85185776 6395184
03:19:34 55166 8192000000 10136338112 10605010944 1122526160 12.051 98 26 50 0 1216833376 88519328 5787888
03:21:53 54804 8192000000 10058833008 10492841984 1127931136 12.102 99 35 61 2 1152434416 19254656 5248624
03:23:52 54160 8192000000 10098098192 10526507008 1126938400 12.092 99 51 99 0 1158326608 20628688 10759520
03:25:47 86433 8192000000 10097499936 10588991488 1132919776 12.149 100 15 30 0 1220535472 84704096 2911600
03:28:28 128597 8192000000 10072884640 10581495808 1116928288 11.998 99 8 17 0 1155747472 36823824 1995360
03:31:37 23999 8192000000 10059918816 10495336448 1125626800 12.080 99 52 90 0 1156278944 20728240 9923904
03:33:02 40806 8192000000 10097577376 10539208704 1132813488 12.148 99 36 72 1 1181303200 40908608 7581104
03:34:44 49713 8192000000 10139478896 10598019072 1119069024 12.018 98 38 75 0 1214398944 87372304 7957616
03:38:00 162019 8192000000 10133979840 10658725888 1134202544 12.161 99 19 37 0 1205483264 67253088 4027632
03:42:30 73958 8192000000 10126389888 10584580096 1111690368 11.948 97 17 34 0 1202473376 87437632 3345376
03:44:45 62858 8192000000 10096618992 10533023744 1098063456 11.819 96 17 34 0 1157088080 55251040 3773584
03:47:16 48961 8192000000 10087170656 10509332480 1113432528 11.965 98 33 60 1 1165461120 44723280 7305312
03:49:07 6353 8192000000 10099637104 10507587584 1105656560 11.891 98 72 140 0 1174504864 54234096 14614208
03:50:14 48760 8192000000 10063555760 10506805248 1100971472 11.847 98 20 40 0 1156961936 52096464 3894000
03:52:04 47501 8192000000 10141780000 10580185088 1105811968 11.893 98 33 66 0 1182281792 69826688 6643136
03:53:52 87934 8192000000 10111509552 10559467520 1112368976 11.955 98 13 26 0 1172661360 57657104 2635280
03:56:27 112707 8192000000 10119498944 10579591168 1128910624 12.111 100 41 82 0 1195287264 57585344 8791296
03:59:23 30788 8192000000 10060727936 10554322944 1110075184 11.933 98 18 36 0 1168069680 53826816 4167680
04:01:11 72641 8192000000 10089102576 10562420736 1127196448 12.095 100 16 31 0 1184248960 53865040 3187472
04:03:24 74770 8192000000 10083652816 10541989888 1111719792 11.949 98 8 16 0 1184783344 70970592 2092960
04:05:45 38052 8192000000 10049729696 10481635328 1085833424 11.703 97 23 44 0 1128826064 38526448 4466192
04:08:02 47393 8192000000 10145908720 10587811840 1117422912 12.003 98 34 67 1 1227207552 102869904 6914736
04:09:50 53526 8192000000 10101396176 10532487168 1118717248 12.015 98 32 64 1 1180256912 55143904 6395760
04:11:49 56773 8192000000 10152034608 10615603200 1146086000 12.273 99 19 38 0 1248588816 98107792 4395024
04:13:46 64867 8192000000 10066168016 10510745600 1089305152 11.736 96 22 44 0 1133826592 40075984 4445456
04:15:51 31962 8192000000 10139842080 10590175232 1128363680 12.106 99 33 66 1 1204808208 69709200 6735328
04:17:25 10569 8192000000 10096222800 10531672064 1135196864 12.170 100 42 84 0 1199988112 55920944 8870304
04:18:37 27610 8192000000 10104910320 10545188864 1134176144 12.161 100 40 78 2 1187778032 45821376 7780512
04:20:07 74224 8192000000 10100533440 10556735488 1119601712 12.023 98 41 81 1 1198366752 70849504 7915536
04:22:33 62171 8192000000 10061051792 10545090560 1112582800 11.957 98 8 16 0 1149473696 35264720 1626176
04:24:38 75763 8192000000 10068047232 10510491648 1084846784 11.694 95 17 34 1 1128459248 40043856 3568608
04:27:04 59627 8192000000 10100725504 10588418048 1132847648 12.148 100 11 22 0 1205962560 70558944 2555968
04:29:06 60537 8192000000 10153031472 10602319872 1130850752 12.129 99 33 66 0 1239423184 101039376 7533056
04:31:07 33698 8192000000 10115367984 10559315968 1124978000 12.074 99 35 68 2 1211920112 79819824 7122288
04:32:44 28777 8192000000 10179187536 10610958336 1144730112 12.260 100 53 106 0 1250607024 95189168 10687744
04:34:17 63860 8192000000 10131600352 10593193984 1094589616 11.786 96 29 56 0 1213740336 113046384 6104336
04:36:22 48555 8192000000 10128478144 10605879296 1083900496 11.685 96 33 64 0 1177702720 87043552 6758672

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 02-02-2023 04:38:41 to 02-02-2023 04:38:57.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 179 v_add_invoice_to_stock 335544517
except 17 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 02-02-2023 04:38:58 to 02-02-2023 04:39:00.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          211565
MON$OLDEST_ACTIVE               215010
MON$OLDEST_SNAPSHOT             215010
MON$NEXT_TRANSACTION            215010
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-30 07:30:08.6030 GMT
MON$PAGES                       2048288
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             768
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2894 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2894 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2894 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2894 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.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 ---
:337: 
:338: mscs-test-161.budzdorov.ru    Thu Feb  2 04:37:39 2023
:339:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:340: 
:341: 
:342: mscs-test-161.budzdorov.ru    Thu Feb  2 04:37:39 2023
:343:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:344: 
--- end of diff output ---

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

02.02.23 04:39:01. There are 3 logs in /var/tmp/logs-oltp40 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
2023-02-02 04:39:04 No severe PSQL-related problems occured
02.02.23 04:39:05. End of report.