Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 09.12.2022 10: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 14944764 kB
MemAvailable 30133904 kB
Buffers 0 kB
Cached 16783404 kB
SwapCached 3264 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 2022-12-09 10:37:22
DTS_END                         2022-12-09 10:37:22.9350
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/42506
TRN_ID                          214978
ATT_ID                          633
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: 8569.58 25411 1028350
customer order: creation 165.28 476 19834
customer order: refuse 13.46 387 1615
order to supplier: creation 43.40 1312 5208
order to supplier: removal 3.33 163006 400
invoice (draft): creation 42.71 3031 5125
invoice (draft): removal 47.73 1011 5728
invoice accept: apply 39.36 25299 4723
invoice accept: cancel 42.99 1192 5159
customer reserve: creation 3668.23 240 440188
customer reserve: removal 4219.18 102 506301
realization accept: apply 79.26 257 9511
realization accept: cancel 81.08 157 9730
payment from customer: creation 56.48 167 6777
payment from customer: removal 5.61 128 673
payment to supplier: creation 46.74 174 5609
payment to supplier: removal 3.28 138 394
service: total inventory turnovers 3.59 4243 431
service: total monetary turnovers 6.93 351 831
service: aggregate perf. data 0.91 15404 109
service: refresh index statistics 0.03 291149 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 09-12-2022 10:39:30 to 09-12-2022 10:39:33.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6386
TEST_TIME 2 5233
TEST_TIME 3 5474
TEST_TIME 4 5377
TEST_TIME 5 5323
TEST_TIME 6 5554
TEST_TIME 7 5658
TEST_TIME 8 5613
TEST_TIME 9 5711
TEST_TIME 10 6067
TEST_TIME 11 6144
TEST_TIME 12 6407
TEST_TIME 13 6594
TEST_TIME 14 6806
TEST_TIME 15 7066
TEST_TIME 16 7374
TEST_TIME 17 7787
TEST_TIME 18 7879
TEST_TIME 19 8056
TEST_TIME 20 8269
TEST_TIME 21 8432
TEST_TIME 22 8305
TEST_TIME 23 8425
TEST_TIME 24 8488
TEST_TIME 25 8659
TEST_TIME 26 8735
TEST_TIME 27 9302
TEST_TIME 28 9588
TEST_TIME 29 9845
TEST_TIME 30 9945
TEST_TIME 31 10223
TEST_TIME 32 10407
TEST_TIME 33 10472
TEST_TIME 34 10674
TEST_TIME 35 10456
TEST_TIME 36 10300
TEST_TIME 37 10296
TEST_TIME 38 10126
TEST_TIME 39 10000
TEST_TIME 40 9881
TEST_TIME 41 9735
TEST_TIME 42 9582
TEST_TIME 43 9498
TEST_TIME 44 9435
TEST_TIME 45 9332
TEST_TIME 46 9252
TEST_TIME 47 9264
TEST_TIME 48 9105
TEST_TIME 49 9087
TEST_TIME 50 9169
TEST_TIME 51 9123
TEST_TIME 52 9066
TEST_TIME 53 9137
TEST_TIME 54 9092
TEST_TIME 55 9063
TEST_TIME 56 9003
TEST_TIME 57 8978
TEST_TIME 58 8985
TEST_TIME 59 8945
TEST_TIME 60 8899
TEST_TIME 61 8963
TEST_TIME 62 9103
TEST_TIME 63 9179
TEST_TIME 64 9249
TEST_TIME 65 9257
TEST_TIME 66 9211
TEST_TIME 67 9202
TEST_TIME 68 9100
TEST_TIME 69 9078
TEST_TIME 70 9062
TEST_TIME 71 9002
TEST_TIME 72 8996
TEST_TIME 73 8944
TEST_TIME 74 8941
TEST_TIME 75 8917
TEST_TIME 76 8843
TEST_TIME 77 8755
TEST_TIME 78 8812
TEST_TIME 79 8804
TEST_TIME 80 8840
TEST_TIME 81 8847
TEST_TIME 82 8825
TEST_TIME 83 8853
TEST_TIME 84 8919
TEST_TIME 85 8934
TEST_TIME 86 8907
TEST_TIME 87 8912
TEST_TIME 88 8927
TEST_TIME 89 8928
TEST_TIME 90 8884
TEST_TIME 91 8813
TEST_TIME 92 8772
TEST_TIME 93 8752
TEST_TIME 94 8831
TEST_TIME 95 8818
TEST_TIME 96 8781
TEST_TIME 97 8749
TEST_TIME 98 8741
TEST_TIME 99 8741
TEST_TIME 100 8723
TEST_TIME 101 8715
TEST_TIME 102 8691
TEST_TIME 103 8688
TEST_TIME 104 8726
TEST_TIME 105 8702
TEST_TIME 106 8726
TEST_TIME 107 8760
TEST_TIME 108 8837
TEST_TIME 109 8979
TEST_TIME 110 8909
TEST_TIME 111 8879
TEST_TIME 112 8837
TEST_TIME 113 8806
TEST_TIME 114 8820
TEST_TIME 115 8780
TEST_TIME 116 8759
TEST_TIME 117 8709
TEST_TIME 118 8678
TEST_TIME 119 8653
TEST_TIME 120 8592

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 09-12-2022 10:39:34 to 09-12-2022 10:39:37.

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 1434757 1434757 0 0.00 0 27871 12 0 0
doc_list_biud 1434744 1434744 0 0.00 0 57819 42 0 0
sp_add_invoice_to_stock 4852 4723 129 2.65 72 197701 25299 0 129
sp_cancel_adding_invoice 5159 5159 0 0.00 6 80384 1193 0 0
sp_cancel_client_order 1615 1615 0 0.00 6 10040 388 0 0
sp_cancel_customer_reserve 506301 506301 0 0.00 1 57821 102 0 0
sp_cancel_pay_from_customer 673 673 0 0.00 4 6971 128 0 0
sp_cancel_pay_to_supplier 394 394 0 0.00 4 6967 139 0 0
sp_cancel_supplier_invoice 5737 5728 9 0.15 3 44663 1012 0 9
sp_cancel_supplier_order 400 400 0 0.00 7 1321318 163006 0 0
sp_cancel_write_off 9730 9730 0 0.00 2 10209 158 0 0
sp_client_order 19834 19834 0 0.00 5 57552 476 0 0
sp_customer_reserve 440188 440188 0 0.00 0 48692 241 0 0
sp_fill_shopping_cart 30147 30147 0 0.00 1 19503 80 0 0
sp_fill_shopping_cart_clo_res 440161 440161 0 0.00 0 11488 21 0 0
sp_get_clo_for_invoice 4691 4691 0 0.00 0 29659 415 0 0
sp_kill_cost_storno 15956 15956 0 0.00 0 14038 71 0 0
sp_kill_qstorno_ret_qs2qd 1028460 1028460 0 0.00 0 57605 46 0 0
sp_kill_qty_storno 543128 543128 0 0.00 0 57819 103 0 0
sp_lock_selected_doc 19563 19563 0 0.00 0 4816 6 0 0
sp_make_cost_storno 26584 26584 0 0.00 0 10510 77 0 0
sp_multiply_rows_for_pdistr 26582 26582 0 0.00 0 27864 38 0 0
sp_multiply_rows_for_qdistr 19829 19829 0 0.00 0 57543 203 0 0
sp_pay_from_customer 6777 6777 0 0.00 6 27878 168 0 0
sp_pay_to_supplier 5609 5609 0 0.00 6 8260 175 0 0
sp_payment_common 12384 12384 0 0.00 2 8603 44 0 0
sp_qd_handle_on_cancel_clo 1615 1615 0 0.00 0 9840 326 0 0
sp_qd_handle_on_invoice_upd_sts 9847 9847 0 0.00 0 37942 505 0 0
sp_qd_handle_on_reserve_upd_sts 9507 9507 0 0.00 0 12199 59 0 0
sp_reserve_write_off 9511 9511 0 0.00 8 12217 257 0 0
sp_supplier_invoice 5125 5125 0 0.00 10 81040 3031 0 0
sp_supplier_order 5208 5208 0 0.00 6 51700 1312 0 0
srv_aggregate_perf_data 109 109 0 0.00 2457 220772 15404 0 0
srv_fill_mon_cache_memory 71 71 0 0.00 877 100099 35428 0 0
srv_find_qd_qs_mism 982226 982226 0 0.00 0 16066 9 0 0
srv_make_invnt_saldo 431 431 0 0.00 62 84081 4244 0 0
srv_make_money_saldo 831 831 0 0.00 1 15899 352 0 0
srv_recalc_idx_stat 4 4 0 0.00 90739 435293 291149 0 0
t$perf-abend:sp_add_invoice_to_stock 129 129 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4728 4728 0 0.00 0 19318 809 0 0
t$perf-norm:sp_cancel_adding_invoice 369 369 0 0.00 1 9244 431 0 0
t$perf-norm:sp_cancel_client_order 1615 1615 0 0.00 0 4784 30 0 0
t$perf-norm:sp_cancel_customer_reserve 593 593 0 0.00 0 950 27 0 0
t$perf-norm:sp_cancel_pay_from_customer 673 673 0 0.00 0 1071 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 394 394 0 0.00 0 3170 30 0 0
t$perf-norm:sp_cancel_supplier_invoice 343 343 0 0.00 0 1607 30 0 0
t$perf-norm:sp_cancel_supplier_order 400 400 0 0.00 0 95180 5996 0 0
t$perf-norm:sp_cancel_write_off 982 982 0 0.00 0 1032 15 0 0
t$perf-norm:sp_client_order 19835 19835 0 0.00 0 13858 35 0 0
t$perf-norm:sp_customer_reserve 992 992 0 0.00 0 2280 15 0 0
t$perf-norm:sp_pay_from_customer 6777 6777 0 0.00 0 15865 23 0 0
t$perf-norm:sp_pay_to_supplier 5609 5609 0 0.00 0 5735 19 0 0
t$perf-norm:sp_reserve_write_off 9512 9512 0 0.00 0 13699 24 0 0
t$perf-norm:sp_supplier_invoice 5125 5125 0 0.00 0 3244 25 0 0
t$perf-norm:sp_supplier_order 5208 5208 0 0.00 0 3650 26 0 0
t$perf-norm:srv_aggregate_perf_data 109 109 0 0.00 0 666 17 0 0
t$perf-norm:srv_fill_mon_cache_memory 71 71 0 0.00 0 646 22 0 0
t$perf-norm:srv_make_invnt_saldo 431 431 0 0.00 0 1940 15 0 0
t$perf-norm:srv_make_money_saldo 831 831 0 0.00 0 1066 4 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 6 213 63 0 0
v_add_invoice_to_stock 9630 9372 258 2.67 0 7443 24 0 258
v_all_customers 36314 36314 0 0.00 0 119 3 0 0
v_all_suppliers 29085 29085 0 0.00 0 79 4 0 0
v_all_wares 39650 39650 0 0.00 0 164 3 0 0
v_cancel_adding_invoice 738 738 0 0.00 0 840 13 0 0
v_cancel_client_order 3230 3230 0 0.00 0 3419 12 0 0
v_cancel_customer_prepayment 1346 1346 0 0.00 0 6945 18 0 0
v_cancel_customer_reserve 1186 1186 0 0.00 0 1112 10 0 0
v_cancel_payment_to_supplier 788 788 0 0.00 0 6945 19 0 0
v_cancel_supplier_invoice 704 686 18 2.55 0 475 20 0 18
v_cancel_supplier_order 800 800 0 0.00 0 176 9 0 0
v_cancel_write_off 1964 1964 0 0.00 0 3471 24 0 0
v_max_id_clo_ord 5203 5203 0 0.00 0 1417 4 0 0
v_max_id_clo_res 992 992 0 0.00 0 2574 20 0 0
v_max_id_ord_sup 5117 5117 0 0.00 0 4270 8 0 0
v_max_non_paid_invoice 1384 1384 0 0.00 0 210 6 0 0
v_max_non_paid_realizn 3511 3511 0 0.00 0 1940 8 0 0
v_min_id_clo_ord 5203 5203 0 0.00 0 6076 12 0 0
v_min_id_clo_res 992 992 0 0.00 0 5669 12 0 0
v_min_id_ord_sup 5117 5117 0 0.00 0 5149 11 0 0
v_min_non_paid_invoice 5607 5607 0 0.00 0 5825 20 0 0
v_min_non_paid_realizn 6776 6776 0 0.00 0 8585 20 0 0
v_our_firm 9864 9864 0 0.00 0 69 4 0 0
v_reserve_write_off 19014 19014 0 0.00 0 7002 10 0 0
x_lock_depdocs_on_canc_invoice 10544 10544 0 0.00 0 9135 132 0 0
x_lock_depdocs_on_canc_sup_ord 400 400 0 0.00 0 11661 385 0 0
x_make_qstorno_1000_1200 5208 5208 0 0.00 2 51636 1167 0 0
x_make_qstorno_1200_2000 5125 5125 0 0.00 5 80906 2789 0 0
x_make_qstorno_2100_3300 440188 440188 0 0.00 0 47620 209 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 09-12-2022 10:39:38 to 09-12-2022 10:39:42.

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
08:07:26 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
08:08:27 21104 8192000000 9722939296 10023325696 800067856 8.897 100 88 177 0 828926688 7608224 21250608
08:09:51 17333 8192000000 9769996608 10079150080 842813552 9.328 100 84 168 0 873942528 11203984 19924992
08:11:15 7353 8192000000 9801740480 10118537216 870228816 9.602 100 89 178 0 908472192 17835184 20408192
08:12:29 8565 8192000000 9850725056 10183602176 907619008 9.974 100 80 160 0 961758208 36060288 18078912
08:13:42 3755 8192000000 9902999184 10246189056 943560528 10.328 100 81 160 0 1011742320 51307456 16874336
08:14:48 1412 8192000000 9964017552 10313007104 983624352 10.719 100 94 179 2 1072376528 70285248 18466928
08:15:53 1202 8192000000 9990890720 10352500736 1009140624 10.967 100 92 182 1 1118340208 90161760 19037824
08:16:57 2120 8192000000 10008577920 10375716864 1035399664 11.220 100 90 171 0 1125776736 73103472 17273600
08:18:01 1274 8192000000 10035649648 10411307008 1061642336 11.472 100 96 184 0 1152414048 71322384 19449328
08:19:03 1060 8192000000 10026443792 10403602432 1070342048 11.555 100 97 191 2 1144995680 55542832 19110800
08:20:06 1004 8192000000 10046510864 10432663552 1082665616 11.673 100 82 159 0 1173530096 75004368 15860112
08:21:08 1309 8192000000 10043631168 10435252224 1089076160 11.734 100 95 175 2 1171454496 62952272 19426064
08:22:10 1260 8192000000 10029787728 10429329408 1094909392 11.789 100 73 139 1 1157391664 48032208 14450064
08:23:12 972 8192000000 10051738576 10440183808 1100989360 11.847 100 93 183 0 1177851024 57784000 19077664
08:24:14 888 8192000000 10058028256 10447183872 1100270400 11.840 100 86 163 2 1183971008 65871856 17828752
08:25:16 2979 8192000000 10077628832 10479579136 1107250624 11.906 100 80 159 1 1206464944 84228496 14985824
08:26:21 1060 8192000000 10079722912 10475401216 1103090864 11.867 100 86 163 3 1205933904 86292032 16551008
08:27:23 4085 8192000000 10052713376 10458624000 1109812096 11.931 100 63 126 0 1181431872 59237408 12382368
08:28:28 5159 8192000000 10067844848 10459365376 1091672768 11.759 99 69 134 2 1181697696 77754816 12270112
08:29:35 1523 8192000000 10023249024 10410430464 1082665744 11.673 100 84 156 2 1142046864 43064976 16316144
08:30:37 1358 8192000000 10026581088 10411659264 1069490480 11.547 100 75 148 1 1151269024 67144528 14634016
08:31:39 2255 8192000000 9990098288 10377334784 1054503232 11.404 99 79 147 3 1114699840 44135168 16061440
08:32:44 1176 8192000000 9993303312 10373148672 1053532688 11.395 99 86 158 3 1116786048 47490496 15762864
08:33:46 764 8192000000 9985769648 10367324160 1050588624 11.366 100 84 158 4 1113354336 44735424 18030288
08:34:48 812 8192000000 10009827328 10390888448 1054597712 11.405 100 85 171 2 1136515504 63728016 18189776
08:35:51 1208 8192000000 10014829088 10394882048 1055820608 11.416 100 96 189 0 1137802528 63274208 18707712
08:36:53 842 8192000000 9994864768 10376953856 1055746240 11.416 100 82 155 2 1117390000 43293840 18349920
08:38:02 29649 8192000000 10019926368 10407096320 1076595568 11.615 100 62 119 0 1138729728 50677104 11457056
08:39:38 12583 8192000000 10076740928 10472181760 1094842160 11.789 100 67 127 1 1189726144 82688208 12195776
08:40:54 6776 8192000000 10079051072 10468343808 1091756528 11.759 99 85 165 0 1181105968 72485104 16864336
08:42:13 2195 8192000000 10112319408 10504966144 1113884528 11.969 100 81 155 1 1232981680 100576880 18520272
08:43:19 6912 8192000000 10142752560 10540470272 1133887072 12.158 100 69 134 0 1261688864 113407360 14394432
08:44:29 1152 8192000000 10198734400 10591555584 1143609648 12.249 100 87 151 1 1303411472 143938768 15863056
08:45:38 866 8192000000 10234073504 10631266304 1159173008 12.396 100 96 187 2 1351609936 170927040 21509888
08:46:42 2157 8192000000 10251568240 10645598208 1151502000 12.324 99 85 162 1 1364695728 195355712 17838016
08:47:49 1518 8192000000 10273724176 10671935488 1166303776 12.462 100 88 166 1 1386053456 200032784 19716896
08:48:55 1105 8192000000 10305605040 10710769664 1169730704 12.494 100 89 169 0 1423125136 233956080 19438352
08:49:59 1968 8192000000 10329284368 10751148032 1178620528 12.577 100 81 160 0 1441434496 243950592 18863376
08:51:04 1072 8192000000 10361337472 10786988032 1184663136 12.634 100 92 181 1 1471116192 264222624 22230432
08:52:09 887 8192000000 10391508064 10813722624 1179647856 12.587 100 96 183 2 1499416848 298393216 21375776
08:53:14 1582 8192000000 10378958704 10834563072 1196717792 12.746 100 99 191 2 1490644304 271287120 22639392
08:54:18 1252 8192000000 10399147552 10836832256 1197129216 12.750 100 95 186 0 1506217200 286444288 22643696
08:55:22 6255 8192000000 10377644528 10806996992 1176808560 12.560 100 62 121 2 1491730640 300125568 14796512
08:56:31 4591 8192000000 10396991712 10832400384 1190567328 12.689 100 58 115 0 1502715360 298045968 14102064
08:57:40 11832 8192000000 10381379280 10833076224 1165233008 12.452 99 40 79 1 1471192624 295704912 10254704
08:58:54 6341 8192000000 10368038592 10802343936 1164374912 12.444 99 45 91 1 1474486320 298932864 11178544
09:00:06 2608 8192000000 10461990800 10880368640 1178769712 12.579 100 82 161 2 1565545808 366886000 19890096
09:01:12 6105 8192000000 10440775168 10890399744 1177459424 12.566 100 51 100 0 1553753568 363450864 12843280
09:02:20 17104 8192000000 10433244816 10879795200 1144298928 12.256 99 45 88 1 1527372560 371826336 11247296
09:03:40 12516 8192000000 10393633760 10882318336 1132682960 12.147 99 44 88 0 1509553536 365164624 11705952
09:04:55 6652 8192000000 10337848576 10786537472 1132844560 12.148 99 33 65 0 1435892928 295122784 7925584
09:06:12 12485 8192000000 10276583984 10706096128 1127667376 12.099 99 36 72 0 1392788288 256187904 8933008
09:07:35 32763 8192000000 10203714736 10666160128 1106668976 11.901 99 25 50 0 1339062064 226171712 6221376
09:09:11 23752 8192000000 10156524112 10590859264 1102992496 11.866 98 27 50 0 1265562992 156357600 6212896
09:10:39 28777 8192000000 10113670176 10595971072 1108877552 11.922 99 35 70 0 1247833184 131314976 7640656
09:12:10 7309 8192000000 10080243440 10501820416 1121997344 12.046 100 67 123 1 1184057360 45859376 16200640
09:13:18 52382 8192000000 10062449408 10545926144 1121156784 12.038 100 12 24 0 1201920112 77999488 2763840
09:15:24 30229 8192000000 10064494320 10508120064 1126600784 12.089 100 28 54 1 1154189856 22250224 5338848
09:17:02 32711 8192000000 10074872208 10513321984 1120872096 12.035 99 45 87 1 1172730096 42111872 9746128
09:18:42 49456 8192000000 10117838080 10555146240 1125776592 12.082 99 27 51 0 1201261216 69827584 5657040
09:20:38 89676 8192000000 10161570240 10627821568 1114803040 11.978 98 16 31 0 1240746768 122429664 3514064
09:23:09 59811 8192000000 10179649072 10631684096 1133204000 12.152 99 26 52 1 1272925760 134067376 5654384
09:25:20 56892 8192000000 10185042400 10635948032 1121495184 12.041 98 15 31 0 1263876320 138381776 3999360
09:27:17 74030 8192000000 10098188176 10562347008 1133503952 12.154 100 36 70 0 1208801552 68826592 6471008
09:29:37 64237 8192000000 10071173616 10588864512 1126094896 12.085 99 11 22 1 1197075120 68575360 2404864
09:31:42 48291 8192000000 10082442272 10509504512 1143518704 12.249 100 43 86 1 1185191216 32261872 9410640
09:33:30 81698 8192000000 10096910592 10559209472 1138645328 12.203 99 20 40 0 1207352000 64783056 3923616
09:36:05 18709 8192000000 10132987920 10573873152 1156308256 12.369 100 30 58 0 1220073536 56741808 7023472
09:37:36 63780 8192000000 10161585328 10666147840 1139018048 12.206 98 22 44 0 1259938864 116073648 4847168
09:39:40 93948 8192000000 10126187856 10596823040 1122750240 12.053 97 9 18 1 1168262160 43852912 1659008
09:42:15 75162 8192000000 10076043632 10530824192 1118314576 12.011 97 20 37 0 1157586544 35848832 3423136
09:44:32 72265 8192000000 10065747760 10530639872 1115265136 11.982 99 25 50 0 1191588032 70932528 5390368
09:47:20 70413 8192000000 10070162016 10546216960 1118241760 12.010 99 41 82 0 1199856528 73127360 8487408
09:49:32 15321 8192000000 10081863776 10520346624 1128283056 12.105 99 46 86 1 1187410592 50205184 8922352
09:50:48 100099 8192000000 10065406800 10541580288 1097383296 11.813 97 13 26 0 1159613824 59491248 2739280
09:53:42 64314 8192000000 10097135760 10545016832 1108891760 11.922 98 9 18 0 1189512992 78238704 2382528
09:56:17 54566 8192000000 10065568128 10513846272 1102374304 11.860 98 15 29 0 1174129424 68693040 3062080
09:58:23 72302 8192000000 10070947632 10527334400 1076925312 11.618 96 11 21 1 1109220304 30637728 1657264
10:00:40 34148 8192000000 10065884800 10501279744 1068124752 11.534 97 24 48 0 1149712704 76767920 4820032
10:02:17 31213 8192000000 10048212672 10487746560 1082873648 11.675 98 26 52 0 1133710576 45252816 5584112
10:03:51 15340 8192000000 10050447984 10482847744 1088583600 11.729 99 46 92 0 1153974880 55855936 9535344
10:05:15 70409 8192000000 10016301840 10452520960 1010980064 10.985 93 31 62 0 1064782944 47665120 6137760
10:07:29 16193 8192000000 10103281616 10515562496 1089332272 11.736 99 48 96 0 1160566016 61619424 9614320
10:09:08 97850 8192000000 10113873360 10569773056 1109716032 11.930 100 33 63 0 1244525600 128303872 6505696
10:11:49 53693 8192000000 10065743936 10543779840 1110922384 11.941 100 10 20 0 1181423440 68595840 1905216
10:13:44 49816 8192000000 10036739904 10480222208 1073941008 11.590 96 27 53 0 1116061776 36282272 5838496
10:15:34 37888 8192000000 10052270064 10489081856 1100648368 11.844 99 31 61 0 1153595056 46644272 6302416
10:17:31 28920 8192000000 10092580384 10522488832 1115194752 11.982 99 27 53 0 1184851920 64029312 5627856
10:19:00 19775 8192000000 10135743424 10567323648 1120425680 12.031 99 33 66 0 1234051936 106523376 7102880
10:20:21 19625 8192000000 10189004976 10629599232 1145732704 12.269 100 36 72 0 1289187376 135154160 8300512
10:21:47 23814 8192000000 10172287024 10614730752 1144678112 12.260 100 40 78 0 1268507936 115134736 8695088
10:23:18 19651 8192000000 10262502304 10704560128 1152374400 12.332 100 63 120 0 1357485504 191282064 13829040
10:24:40 73073 8192000000 10169789760 10663989248 1131322480 12.134 99 13 26 0 1291841360 157114416 3404464
10:26:55 93772 8192000000 10101529632 10545184768 1132463504 12.145 99 34 68 0 1171832608 32651552 6717552
10:29:37 44947 8192000000 10094151392 10571431936 1131759792 12.138 99 16 30 0 1183826400 49363184 2703424
10:31:23 56867 8192000000 10059184752 10551943168 1127731344 12.100 99 6 12 0 1160490672 31852912 906416
10:33:34 75539 8192000000 10062250336 10530611200 1136280608 12.181 100 23 46 0 1177892560 36635072 4976880
10:36:27 61480 8192000000 9910351712 10354032640 961081728 10.500 85 25 50 0 1039167264 73109776 4975760

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 09-12-2022 10:39:44 to 09-12-2022 10:40:01.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 129 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 09-12-2022 10:40:02 to 09-12-2022 10:40:04.

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          215594
MON$OLDEST_ACTIVE               215595
MON$OLDEST_SNAPSHOT             215595
MON$NEXT_TRANSACTION            215595
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                       2051616
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             763
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2883 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2883 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2883 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2883 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 ---
--- end of diff output ---

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

09.12.22 10:40:05. 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
2022-12-09 10:40:07 No severe PSQL-related problems occured
09.12.22 10:40:09. End of report.