Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 26.12.2022 13: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 14384096 kB
MemAvailable 30133804 kB
Buffers 0 kB
Cached 17332124 kB
SwapCached 3680 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-26 13:37:23
DTS_END                         2022-12-26 13:37:23.4390
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/47408
TRN_ID                          222604
ATT_ID                          669
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: 8780.91 28530 1053709
customer order: creation 172.88 506 20746
customer order: refuse 15.68 487 1882
order to supplier: creation 46.14 1409 5537
order to supplier: removal 3.27 173344 392
invoice (draft): creation 46.40 3447 5568
invoice (draft): removal 46.80 1039 5616
invoice accept: apply 42.27 27543 5072
invoice accept: cancel 41.94 1433 5033
customer reserve: creation 3943.54 256 473225
customer reserve: removal 4127.01 107 495241
realization accept: apply 85.96 312 10315
realization accept: cancel 75.20 205 9024
payment from customer: creation 60.79 198 7295
payment from customer: removal 6.03 139 724
payment to supplier: creation 50.11 223 6013
payment to supplier: removal 3.68 145 441
service: total inventory turnovers 3.90 4984 468
service: total monetary turnovers 8.30 430 996
service: aggregate perf. data 0.97 17955 116
service: refresh index statistics 0.04 336442 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 26-12-2022 13:39:07 to 26-12-2022 13:39:09.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5248
TEST_TIME 2 4752
TEST_TIME 3 5992
TEST_TIME 4 6604
TEST_TIME 5 6344
TEST_TIME 6 6386
TEST_TIME 7 6386
TEST_TIME 8 6416
TEST_TIME 9 6438
TEST_TIME 10 6401
TEST_TIME 11 6795
TEST_TIME 12 6954
TEST_TIME 13 6964
TEST_TIME 14 7077
TEST_TIME 15 7250
TEST_TIME 16 7335
TEST_TIME 17 7366
TEST_TIME 18 7349
TEST_TIME 19 7404
TEST_TIME 20 7389
TEST_TIME 21 7617
TEST_TIME 22 7726
TEST_TIME 23 7995
TEST_TIME 24 8299
TEST_TIME 25 8334
TEST_TIME 26 8304
TEST_TIME 27 8297
TEST_TIME 28 8541
TEST_TIME 29 8593
TEST_TIME 30 8738
TEST_TIME 31 8602
TEST_TIME 32 8684
TEST_TIME 33 8801
TEST_TIME 34 8769
TEST_TIME 35 9007
TEST_TIME 36 9412
TEST_TIME 37 9910
TEST_TIME 38 10229
TEST_TIME 39 10411
TEST_TIME 40 10243
TEST_TIME 41 10302
TEST_TIME 42 10212
TEST_TIME 43 10289
TEST_TIME 44 10175
TEST_TIME 45 10072
TEST_TIME 46 10123
TEST_TIME 47 10019
TEST_TIME 48 9927
TEST_TIME 49 9849
TEST_TIME 50 9759
TEST_TIME 51 9731
TEST_TIME 52 9658
TEST_TIME 53 9746
TEST_TIME 54 9717
TEST_TIME 55 9704
TEST_TIME 56 9739
TEST_TIME 57 9693
TEST_TIME 58 9581
TEST_TIME 59 9498
TEST_TIME 60 9456
TEST_TIME 61 9424
TEST_TIME 62 9364
TEST_TIME 63 9257
TEST_TIME 64 9180
TEST_TIME 65 9168
TEST_TIME 66 9140
TEST_TIME 67 9058
TEST_TIME 68 9059
TEST_TIME 69 9051
TEST_TIME 70 9100
TEST_TIME 71 9021
TEST_TIME 72 8983
TEST_TIME 73 9073
TEST_TIME 74 9046
TEST_TIME 75 9049
TEST_TIME 76 9017
TEST_TIME 77 9036
TEST_TIME 78 9023
TEST_TIME 79 9003
TEST_TIME 80 9042
TEST_TIME 81 9005
TEST_TIME 82 9093
TEST_TIME 83 9068
TEST_TIME 84 9076
TEST_TIME 85 9082
TEST_TIME 86 9099
TEST_TIME 87 9210
TEST_TIME 88 9167
TEST_TIME 89 9101
TEST_TIME 90 9045
TEST_TIME 91 9004
TEST_TIME 92 8986
TEST_TIME 93 8947
TEST_TIME 94 8980
TEST_TIME 95 8929
TEST_TIME 96 8914
TEST_TIME 97 8867
TEST_TIME 98 8878
TEST_TIME 99 8901
TEST_TIME 100 8873
TEST_TIME 101 8869
TEST_TIME 102 8870
TEST_TIME 103 8908
TEST_TIME 104 8904
TEST_TIME 105 8905
TEST_TIME 106 8920
TEST_TIME 107 8895
TEST_TIME 108 8961
TEST_TIME 109 8981
TEST_TIME 110 8999
TEST_TIME 111 8999
TEST_TIME 112 9061
TEST_TIME 113 9034
TEST_TIME 114 8966
TEST_TIME 115 8981
TEST_TIME 116 8922
TEST_TIME 117 8942
TEST_TIME 118 8913
TEST_TIME 119 8868
TEST_TIME 120 8804

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 26-12-2022 13:39:10 to 26-12-2022 13:39:14.

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1488821 1488821 0 0.00 0 27302 15 0 0
doc_list_biud 1488814 1488814 0 0.00 0 39984 42 0 0
sp_add_invoice_to_stock 5181 5072 109 2.10 70 200745 27543 0 109
sp_cancel_adding_invoice 5033 5033 0 0.00 5 98580 1433 0 0
sp_cancel_client_order 1882 1882 0 0.00 5 11532 487 0 0
sp_cancel_customer_reserve 495241 495241 0 0.00 1 26001 107 0 0
sp_cancel_pay_from_customer 724 724 0 0.00 4 4651 139 0 0
sp_cancel_pay_to_supplier 441 441 0 0.00 3 2493 145 0 0
sp_cancel_supplier_invoice 5625 5616 9 0.16 2 22230 1040 0 9
sp_cancel_supplier_order 392 392 0 0.00 9 1458927 173344 0 0
sp_cancel_write_off 9024 9024 0 0.00 2 12874 205 0 0
sp_client_order 20746 20746 0 0.00 5 36738 507 0 0
sp_customer_reserve 473225 473225 0 0.00 0 120658 257 0 0
sp_fill_shopping_cart 31829 31829 0 0.00 1 13235 90 0 0
sp_fill_shopping_cart_clo_res 473192 473192 0 0.00 0 24502 18 0 0
sp_get_clo_for_invoice 5036 5036 0 0.00 0 28322 290 0 0
sp_kill_cost_storno 15222 15222 0 0.00 0 13959 91 0 0
sp_kill_qstorno_ret_qs2qd 1005514 1005514 0 0.00 0 25900 46 0 0
sp_kill_qty_storno 532539 532539 0 0.00 0 39984 107 0 0
sp_lock_selected_doc 21411 21411 0 0.00 0 9239 9 0 0
sp_make_cost_storno 28659 28659 0 0.00 0 18874 91 0 0
sp_multiply_rows_for_pdistr 28659 28659 0 0.00 0 8659 43 0 0
sp_multiply_rows_for_qdistr 20746 20746 0 0.00 0 11391 162 0 0
sp_pay_from_customer 7295 7295 0 0.00 5 10526 199 0 0
sp_pay_to_supplier 6013 6013 0 0.00 6 14143 223 0 0
sp_payment_common 13308 13308 0 0.00 2 7926 51 0 0
sp_qd_handle_on_cancel_clo 1882 1882 0 0.00 0 8151 389 0 0
sp_qd_handle_on_invoice_upd_sts 10069 10069 0 0.00 0 39982 616 0 0
sp_qd_handle_on_reserve_upd_sts 10315 10315 0 0.00 0 11022 64 0 0
sp_reserve_write_off 10315 10315 0 0.00 8 19488 313 0 0
sp_supplier_invoice 5568 5568 0 0.00 9 78319 3447 0 0
sp_supplier_order 5537 5537 0 0.00 8 34702 1409 0 0
srv_aggregate_perf_data 116 116 0 0.00 2506 242745 17955 0 0
srv_fill_mon_cache_memory 71 71 0 0.00 791 171778 34493 0 0
srv_find_qd_qs_mism 1003462 1003462 0 0.00 0 27300 12 0 0
srv_make_invnt_saldo 468 468 0 0.00 38 135908 4985 0 0
srv_make_money_saldo 996 996 0 0.00 1 15186 430 0 0
srv_recalc_idx_stat 5 5 0 0.00 126982 473798 336443 0 0
t$perf-abend:sp_add_invoice_to_stock 109 109 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 5076 5076 0 0.00 0 19286 1055 0 0
t$perf-norm:sp_cancel_adding_invoice 445 445 0 0.00 1 5972 490 0 0
t$perf-norm:sp_cancel_client_order 1882 1882 0 0.00 0 3576 32 0 0
t$perf-norm:sp_cancel_customer_reserve 728 728 0 0.00 0 5252 42 0 0
t$perf-norm:sp_cancel_pay_from_customer 724 724 0 0.00 0 840 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 441 441 0 0.00 0 470 16 0 0
t$perf-norm:sp_cancel_supplier_invoice 429 429 0 0.00 0 3413 51 0 0
t$perf-norm:sp_cancel_supplier_order 392 392 0 0.00 0 120669 7755 0 0
t$perf-norm:sp_cancel_write_off 1023 1023 0 0.00 0 3276 28 0 0
t$perf-norm:sp_client_order 20747 20747 0 0.00 0 9040 43 0 0
t$perf-norm:sp_customer_reserve 1015 1015 0 0.00 0 1160 19 0 0
t$perf-norm:sp_pay_from_customer 7295 7295 0 0.00 0 4502 21 0 0
t$perf-norm:sp_pay_to_supplier 6013 6013 0 0.00 0 7598 25 0 0
t$perf-norm:sp_reserve_write_off 10315 10315 0 0.00 0 4420 23 0 0
t$perf-norm:sp_supplier_invoice 5568 5568 0 0.00 0 9609 37 0 0
t$perf-norm:sp_supplier_order 5538 5538 0 0.00 0 11988 39 0 0
t$perf-norm:srv_aggregate_perf_data 116 116 0 0.00 0 607 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 71 71 0 0.00 0 13 3 0 0
t$perf-norm:srv_make_invnt_saldo 468 468 0 0.00 0 835 14 0 0
t$perf-norm:srv_make_money_saldo 996 996 0 0.00 0 850 5 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 3 184 77 0 0
v_add_invoice_to_stock 10282 10064 218 2.12 0 8386 31 0 218
v_all_customers 38005 38005 0 0.00 0 66 4 0 0
v_all_suppliers 31032 31032 0 0.00 0 84 5 0 0
v_all_wares 41490 41490 0 0.00 0 133 5 0 0
v_cancel_adding_invoice 890 890 0 0.00 0 1365 20 0 0
v_cancel_client_order 3764 3764 0 0.00 0 5714 20 0 0
v_cancel_customer_prepayment 1448 1448 0 0.00 0 3951 14 0 0
v_cancel_customer_reserve 1456 1456 0 0.00 0 799 12 0 0
v_cancel_payment_to_supplier 882 882 0 0.00 0 2475 18 0 0
v_cancel_supplier_invoice 876 858 18 2.05 0 4005 29 0 18
v_cancel_supplier_order 784 784 0 0.00 0 517 14 0 0
v_cancel_write_off 2046 2046 0 0.00 0 4901 29 0 0
v_max_id_clo_ord 5532 5532 0 0.00 0 5232 7 0 0
v_max_id_clo_res 1015 1015 0 0.00 0 5630 22 0 0
v_max_id_ord_sup 5552 5552 0 0.00 0 2723 7 0 0
v_max_non_paid_invoice 1581 1581 0 0.00 0 2287 11 0 0
v_max_non_paid_realizn 3841 3841 0 0.00 0 4509 9 0 0
v_min_id_clo_ord 5532 5532 0 0.00 0 5516 11 0 0
v_min_id_clo_res 1015 1015 0 0.00 0 8696 20 0 0
v_min_id_ord_sup 5552 5552 0 0.00 0 5110 8 0 0
v_min_non_paid_invoice 6012 6012 0 0.00 0 7920 18 0 0
v_min_non_paid_realizn 7295 7295 0 0.00 0 7265 18 0 0
v_our_firm 10392 10392 0 0.00 0 61 5 0 0
v_reserve_write_off 20630 20630 0 0.00 0 6564 14 0 0
x_lock_depdocs_on_canc_invoice 10220 10220 0 0.00 0 14531 137 0 0
x_lock_depdocs_on_canc_sup_ord 392 392 0 0.00 0 8178 393 0 0
x_make_qstorno_1000_1200 5537 5537 0 0.00 2 24325 1210 0 0
x_make_qstorno_1200_2000 5568 5568 0 0.00 3 78268 3210 0 0
x_make_qstorno_2100_3300 473225 473225 0 0.00 0 120655 224 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 26-12-2022 13:39:15 to 26-12-2022 13:39:19.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
11:07:27 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
11:08:28 39491 8192000000 9715555824 10014654464 789030656 8.785 100 91 182 0 820120112 7357104 23732352
11:10:11 13465 8192000000 9769916368 10076614656 842794368 9.328 100 87 174 0 875057024 11526896 20735760
11:11:31 23979 8192000000 9813344448 10134421504 876071520 9.661 100 80 160 0 911864464 17569952 18222992
11:13:09 7852 8192000000 9871034944 10206752768 924286384 10.138 100 83 166 0 983792672 41277936 18228352
11:14:20 3467 8192000000 9901788256 10241814528 947721520 10.369 100 81 160 0 1012876736 48442224 16712992
11:15:27 2387 8192000000 9964083728 10313162752 982117280 10.705 100 94 185 0 1082538416 80415488 20005648
11:16:32 2155 8192000000 9985929280 10346074112 1010218176 10.977 100 92 181 1 1105132048 77202672 17711200
11:17:36 1375 8192000000 10034044704 10398920704 1036550272 11.231 100 96 181 2 1147992960 93528032 17914656
11:18:39 818 8192000000 10028706624 10404147200 1058731808 11.444 100 96 186 0 1151239056 73005920 19501328
11:19:41 1074 8192000000 10044855568 10421198848 1065499264 11.509 100 100 190 0 1167312160 82211824 19601072
11:20:46 1522 8192000000 10030864816 10410782720 1074946928 11.599 100 85 160 1 1156700736 65265024 16488784
11:21:49 1095 8192000000 10056085440 10436775936 1077590736 11.625 100 94 185 0 1174499584 78096048 18812800
11:22:51 1218 8192000000 10069983456 10460352512 1085188176 11.697 100 91 177 3 1191604816 88307616 18109024
11:23:54 1103 8192000000 10071201200 10458288128 1092672352 11.768 100 89 174 0 1189236272 79322592 17241328
11:25:01 3635 8192000000 10041530640 10431418368 1094533776 11.786 100 77 144 3 1161582816 52787968 14261072
11:26:06 1256 8192000000 10073688016 10464014336 1098568912 11.824 100 94 185 0 1194763744 77471920 18722912
11:27:09 1251 8192000000 10067271936 10451693568 1093791008 11.779 99 96 183 3 1188187376 76033200 18363168
11:28:12 1104 8192000000 10069754512 10459799552 1094264320 11.783 100 93 173 1 1194401248 82183856 17953072
11:29:14 1134 8192000000 10065864800 10450890752 1103792800 11.874 100 96 188 1 1188458128 65964672 18700656
11:30:17 1654 8192000000 10027010944 10413191168 1089419456 11.737 100 81 151 3 1150823712 44579168 16825088
11:31:20 6791 8192000000 10018752240 10421600256 1074717664 11.597 99 45 88 1 1135910608 52565264 8627680
11:32:27 3030 8192000000 10032067136 10414206976 1066993680 11.523 100 66 124 2 1151176736 71954496 12228560
11:33:32 1065 8192000000 10032027856 10417221632 1066095424 11.515 100 81 153 1 1154479856 72345472 16038960
11:34:34 892 8192000000 9980861808 10352586752 1031002304 11.178 99 90 172 2 1105584640 56738352 17843984
11:35:37 1420 8192000000 10001444944 10372481024 1034578080 11.213 99 92 184 1 1121900592 68225808 19096704
11:36:40 1084 8192000000 10019186672 10395041792 1039756704 11.262 100 92 177 1 1144098864 84929808 19412352
11:37:45 42273 8192000000 9986224816 10377314304 1054914480 11.408 100 54 106 1 1110447280 44276944 11255856
11:39:33 4965 8192000000 10010446048 10389700608 1044219696 11.305 98 85 157 3 1118135664 56532192 17383776
11:40:42 1536 8192000000 10053279904 10429689856 1066816640 11.522 99 86 149 3 1163600624 81671232 15112752
11:41:48 2144 8192000000 10077011696 10469912576 1090730064 11.750 100 92 181 1 1198679120 88382416 19566640
11:42:54 5723 8192000000 10091105824 10484101120 1100401792 11.841 100 69 137 0 1213840448 99808704 13629952
11:44:05 9502 8192000000 10103650640 10504695808 1111776512 11.949 100 59 112 0 1228786720 105821568 11188640
11:45:21 1566 8192000000 10131039296 10524594176 1120346288 12.030 100 85 158 0 1245393296 108105360 16941648
11:46:26 1536 8192000000 10163121840 10554368000 1127196640 12.095 100 93 180 0 1278917984 131932576 19788768
11:47:32 3522 8192000000 10195310784 10595213312 1142113936 12.235 100 73 144 1 1310940016 153169168 15656912
11:48:38 2609 8192000000 10222233456 10624909312 1149737696 12.307 99 81 155 1 1344076864 178379280 15959888
11:49:44 1301 8192000000 10255400464 10660700160 1168478912 12.483 100 91 175 0 1367421088 180821968 18120208
11:50:51 1611 8192000000 10283908704 10686824448 1177576080 12.568 100 92 179 1 1398619056 200523280 20519696
11:51:57 911 8192000000 10291500144 10709200896 1184696016 12.634 100 99 190 0 1399641856 194738640 20207200
11:53:00 1056 8192000000 10310650544 10721021952 1177855616 12.570 100 98 182 0 1416254400 218671984 19726800
11:54:05 2066 8192000000 10328929552 10738946048 1184381136 12.631 100 91 178 0 1433192880 228653168 20158576
11:55:14 1286 8192000000 10385108112 10789113856 1188720480 12.671 100 96 186 0 1495184160 285087392 21376288
11:56:21 1613 8192000000 10376894096 10783059968 1168765760 12.485 98 94 188 1 1486115888 294118736 23231392
11:57:27 1453 8192000000 10397805456 10819735552 1183622288 12.624 100 93 178 0 1502184624 297773680 20788656
11:58:32 977 8192000000 10405955776 10823028736 1173465968 12.529 100 92 180 2 1512179632 317726256 20987408
11:59:35 868 8192000000 10402831008 10840940544 1163620496 12.437 100 100 187 2 1507410672 321949168 21841008
12:00:39 882 8192000000 10373561568 10830233600 1163047360 12.432 100 93 179 0 1482873072 297922208 21903504
12:01:46 1208 8192000000 10404340336 10834608128 1178784224 12.579 100 95 188 1 1507618336 306230592 22603520
12:02:54 811 8192000000 10420263040 10822762496 1175335584 12.547 100 96 186 0 1520890192 324062960 21491648
12:03:58 778 8192000000 10443946768 10868637696 1177784016 12.570 100 100 195 0 1549183120 347776736 23622368
12:05:02 796 8192000000 10460915728 10900459520 1169262960 12.490 100 91 180 0 1562467888 371795280 21409648
12:06:07 6529 8192000000 10484000512 10902056960 1160887200 12.412 100 62 124 0 1580572512 403661824 16023488
12:07:18 10204 8192000000 10481393968 10919235584 1172760224 12.523 100 50 98 0 1581321728 395271808 13289696
12:08:32 14998 8192000000 10516117792 10954342400 1160040224 12.404 99 52 98 2 1606699904 433168800 13490880
12:09:52 29880 8192000000 10526604688 11013476352 1161698208 12.419 100 35 70 0 1643280640 472388160 9194272
12:11:44 8017 8192000000 10488231360 11005775872 1158965152 12.394 100 45 90 0 1609492560 438783504 11743904
12:13:02 13861 8192000000 10432963072 10911682560 1160442144 12.407 100 41 82 0 1554665056 383839040 10383872
12:14:20 27234 8192000000 10323184032 10848002048 1168978960 12.487 100 35 69 0 1446643536 268528640 9135936
12:15:53 31152 8192000000 10224966272 10730430464 1172234352 12.518 100 19 36 0 1366474416 189649968 4590096
12:17:42 41545 8192000000 10148229072 10601877504 1134304592 12.162 98 40 80 0 1252175296 108528336 9342368
12:19:36 19621 8192000000 10168668704 10639413248 1154438064 12.351 100 22 43 0 1259247216 100066752 4742400
12:21:30 104404 8192000000 10118913104 10600521728 1159496288 12.399 100 15 31 1 1232435072 69809440 3129344
12:24:29 44973 8192000000 10110570288 10532024320 1130098896 12.122 97 58 99 1 1167333792 25777952 11456944
12:26:20 71596 8192000000 10082742240 10524561408 1118837712 12.016 97 26 53 0 1170907808 46280272 5789824
12:28:39 69949 8192000000 10124191488 10599809024 1138481152 12.201 99 13 26 0 1223916560 82692720 2742688
12:30:50 171777 8192000000 10080481344 10577498112 1103601168 11.872 96 7 14 0 1143978576 38984144 1393264
12:35:08 43551 8192000000 10111929440 10539184128 1137552912 12.193 99 59 114 1 1199417920 50197056 11667952
12:36:54 86963 8192000000 10094164912 10569637888 1135925584 12.177 99 40 75 0 1216784784 72858528 8000672
12:39:49 38402 8192000000 10076658944 10515136512 1126195040 12.085 99 28 57 0 1167909904 35878656 5836208
12:41:29 6303 8192000000 10092620208 10522365952 1132624544 12.146 99 60 110 1 1189581344 45484960 11471840
12:42:36 25547 8192000000 10123887696 10576863232 1144704160 12.260 100 35 66 1 1225480512 73683280 7093072
12:44:11 16115 8192000000 10149642560 10578247680 1135028256 12.169 99 48 85 2 1232657488 87603984 10025248
12:45:31 147154 8192000000 10113350224 10584977408 1104029232 11.876 96 32 66 0 1245286016 133689744 7567040
12:49:09 87035 8192000000 10066112896 10588565504 1109706240 11.930 98 8 16 0 1202433568 90947936 1779392
12:51:37 64175 8192000000 10075132528 10522697728 1105456096 11.889 98 14 28 0 1148337520 39904096 2977328
12:54:10 61597 8192000000 10065246704 10514051072 1089970240 11.742 96 14 26 0 1118734688 25533856 3230592
12:56:14 22886 8192000000 10098905360 10534608896 1119707680 12.024 99 48 90 0 1175453472 46817760 8928032
12:57:38 38051 8192000000 10088664896 10517532672 1099773168 11.835 98 25 50 2 1165285264 60171552 5340544
12:59:19 32139 8192000000 10082035712 10520178688 1126700416 12.090 100 28 53 0 1166539136 34750384 5088336
13:01:00 52191 8192000000 10116022352 10561126400 1126346000 12.087 100 35 70 0 1221583824 87589488 7648336
13:02:56 34024 8192000000 10132924048 10579529728 1112297200 11.954 99 48 96 0 1222744880 100942896 9504784
13:04:31 59870 8192000000 10047225328 10469941248 1116039728 11.990 99 26 52 0 1161896288 40394256 5462304
13:06:31 90024 8192000000 10118275952 10565947392 1124141696 12.066 99 40 80 0 1170776480 38223392 8411392
13:09:02 57412 8192000000 10048472992 10484654080 1118605664 12.014 99 32 61 0 1174242912 48955952 6681296
13:11:00 58012 8192000000 10094213184 10573312000 1114635760 11.976 98 23 46 0 1237130240 117425200 5069280
13:13:08 68694 8192000000 10123484128 10582536192 1141218016 12.227 100 19 38 0 1220413152 74959840 4235296
13:15:18 73683 8192000000 10057348208 10540101632 1124340544 12.068 99 19 35 1 1186054560 57433968 4280048
13:17:47 25757 8192000000 10091134144 10527678464 1125773840 12.082 99 46 88 1 1181642032 46360752 9507440
13:19:15 58285 8192000000 10102473632 10551988224 1104351120 11.879 98 26 52 0 1180960672 71109072 5500480
13:21:13 45191 8192000000 10079616096 10521239552 1110267424 11.935 98 27 54 1 1174732896 58763456 5702016
13:23:00 33222 8192000000 10090599648 10549596160 1112928304 11.960 99 44 88 0 1192200288 70785408 8486576
13:24:36 30838 8192000000 10155861840 10589749248 1136293136 12.181 100 44 82 1 1221959488 77091648 8574704
13:26:18 73402 8192000000 10069443552 10578038784 1073947840 11.590 95 27 54 0 1152874944 73817456 5109648
13:28:33 73092 8192000000 10084076928 10560315392 1103221472 11.868 97 16 32 0 1190829264 84150896 3456896
13:30:52 105820 8192000000 10091000864 10551287808 1128523920 12.107 99 23 46 0 1186372512 52788528 5060064
13:33:41 2399 8192000000 10069457632 10503749632 1132249872 12.143 100 47 86 2 1174049296 32908400 8891024
13:34:44 78106 8192000000 10054228416 10548178944 1102665136 11.863 97 10 21 0 1151671328 46478912 2527280

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 26-12-2022 13:39:21 to 26-12-2022 13:39:37.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 109 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 26-12-2022 13:39:38 to 26-12-2022 13:39:40.

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          221517
MON$OLDEST_ACTIVE               223430
MON$OLDEST_SNAPSHOT             223430
MON$NEXT_TRANSACTION            223430
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                       2114560
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             782
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2885 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2885 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2885 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2885 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 ---
:540: 
:541: mscs-test-161.budzdorov.ru    Mon Dec 26 13:37:31 2022
:542:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:543: 
:544: 
:545: mscs-test-161.budzdorov.ru    Mon Dec 26 13:37:32 2022
:546:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:547: 
--- end of diff output ---

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

26.12.22 13:39:41. 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-26 13:39:44 No severe PSQL-related problems occured
26.12.22 13:39:45. End of report.