Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 10.03.2023 07:48
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 15221396 kB
MemAvailable 30466996 kB
Buffers 0 kB
Cached 16509008 kB
SwapCached 3032 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-03-10 07:46:20
DTS_END                         2023-03-10 07:46:20.9380
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/50846
TRN_ID                          219001
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: 8677.83 24362 1041340
customer order: creation 170.43 509 20451
customer order: refuse 14.48 437 1738
order to supplier: creation 45.01 1279 5401
order to supplier: removal 3.16 173324 379
invoice (draft): creation 44.83 3402 5379
invoice (draft): removal 46.58 992 5590
invoice accept: apply 41.07 25466 4928
invoice accept: cancel 41.97 1254 5036
customer reserve: creation 3843.64 237 461237
customer reserve: removal 4145.87 103 497504
realization accept: apply 81.29 304 9755
realization accept: cancel 72.18 165 8661
payment from customer: creation 58.02 195 6962
payment from customer: removal 5.20 121 624
payment to supplier: creation 48.36 248 5803
payment to supplier: removal 3.53 114 423
service: total inventory turnovers 3.93 4335 472
service: total monetary turnovers 7.35 412 882
service: aggregate perf. data 0.92 15842 110
service: refresh index statistics 0.04 258497 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 10-03-2023 07:48:35 to 10-03-2023 07:48:38.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9271
TEST_TIME 2 8434
TEST_TIME 3 7730
TEST_TIME 4 8108
TEST_TIME 5 7325
TEST_TIME 6 7048
TEST_TIME 7 7162
TEST_TIME 8 7065
TEST_TIME 9 6987
TEST_TIME 10 7038
TEST_TIME 11 7381
TEST_TIME 12 7410
TEST_TIME 13 7543
TEST_TIME 14 7490
TEST_TIME 15 7471
TEST_TIME 16 7709
TEST_TIME 17 7706
TEST_TIME 18 7663
TEST_TIME 19 7607
TEST_TIME 20 7709
TEST_TIME 21 7594
TEST_TIME 22 7802
TEST_TIME 23 8006
TEST_TIME 24 8369
TEST_TIME 25 9211
TEST_TIME 26 9275
TEST_TIME 27 9212
TEST_TIME 28 9278
TEST_TIME 29 9788
TEST_TIME 30 10194
TEST_TIME 31 10423
TEST_TIME 32 10896
TEST_TIME 33 11172
TEST_TIME 34 10971
TEST_TIME 35 10919
TEST_TIME 36 10727
TEST_TIME 37 10518
TEST_TIME 38 10416
TEST_TIME 39 10165
TEST_TIME 40 10129
TEST_TIME 41 10124
TEST_TIME 42 10072
TEST_TIME 43 9948
TEST_TIME 44 9859
TEST_TIME 45 9807
TEST_TIME 46 9677
TEST_TIME 47 9621
TEST_TIME 48 9520
TEST_TIME 49 9484
TEST_TIME 50 9358
TEST_TIME 51 9218
TEST_TIME 52 9138
TEST_TIME 53 9123
TEST_TIME 54 9147
TEST_TIME 55 9048
TEST_TIME 56 9032
TEST_TIME 57 8964
TEST_TIME 58 8935
TEST_TIME 59 8879
TEST_TIME 60 8915
TEST_TIME 61 8971
TEST_TIME 62 9064
TEST_TIME 63 9173
TEST_TIME 64 9236
TEST_TIME 65 9210
TEST_TIME 66 9225
TEST_TIME 67 9198
TEST_TIME 68 9222
TEST_TIME 69 9304
TEST_TIME 70 9296
TEST_TIME 71 9238
TEST_TIME 72 9173
TEST_TIME 73 9136
TEST_TIME 74 9062
TEST_TIME 75 9068
TEST_TIME 76 9065
TEST_TIME 77 9051
TEST_TIME 78 9008
TEST_TIME 79 8950
TEST_TIME 80 8874
TEST_TIME 81 8878
TEST_TIME 82 8832
TEST_TIME 83 8833
TEST_TIME 84 8771
TEST_TIME 85 8740
TEST_TIME 86 8725
TEST_TIME 87 8697
TEST_TIME 88 8703
TEST_TIME 89 8701
TEST_TIME 90 8688
TEST_TIME 91 8703
TEST_TIME 92 8694
TEST_TIME 93 8720
TEST_TIME 94 8787
TEST_TIME 95 8899
TEST_TIME 96 8913
TEST_TIME 97 8898
TEST_TIME 98 8894
TEST_TIME 99 8840
TEST_TIME 100 8790
TEST_TIME 101 8788
TEST_TIME 102 8743
TEST_TIME 103 8721
TEST_TIME 104 8713
TEST_TIME 105 8696
TEST_TIME 106 8690
TEST_TIME 107 8658
TEST_TIME 108 8629
TEST_TIME 109 8616
TEST_TIME 110 8673
TEST_TIME 111 8667
TEST_TIME 112 8702
TEST_TIME 113 8718
TEST_TIME 114 8735
TEST_TIME 115 8789
TEST_TIME 116 8785
TEST_TIME 117 8761
TEST_TIME 118 8804
TEST_TIME 119 8746
TEST_TIME 120 8706

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 10-03-2023 07:48:39 to 10-03-2023 07:48:43.

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 1465239 1465239 0 0.00 0 21945 13 0 0
doc_list_biud 1465229 1465229 0 0.00 0 29662 41 0 0
sp_add_invoice_to_stock 4984 4928 56 1.12 41 226158 25466 0 56
sp_cancel_adding_invoice 5036 5036 0 0.00 5 80861 1254 0 0
sp_cancel_client_order 1738 1738 0 0.00 6 22908 437 0 0
sp_cancel_customer_reserve 497504 497504 0 0.00 1 29665 104 0 0
sp_cancel_pay_from_customer 624 624 0 0.00 4 3957 121 0 0
sp_cancel_pay_to_supplier 423 423 0 0.00 3 2074 115 0 0
sp_cancel_supplier_invoice 5591 5590 1 0.01 2 21455 993 0 1
sp_cancel_supplier_order 379 379 0 0.00 16 1393020 173325 0 0
sp_cancel_write_off 8661 8661 0 0.00 2 11719 165 0 0
sp_client_order 20451 20451 0 0.00 5 30403 509 0 0
sp_customer_reserve 461237 461237 0 0.00 0 90411 237 0 0
sp_fill_shopping_cart 31224 31224 0 0.00 1 21379 91 0 0
sp_fill_shopping_cart_clo_res 461200 461200 0 0.00 0 22747 21 0 0
sp_get_clo_for_invoice 4879 4879 0 0.00 0 26803 374 0 0
sp_kill_cost_storno 14744 14744 0 0.00 0 9263 74 0 0
sp_kill_qstorno_ret_qs2qd 1009638 1009638 0 0.00 0 29661 45 0 0
sp_kill_qty_storno 533541 533541 0 0.00 0 29662 104 0 0
sp_lock_selected_doc 20163 20163 0 0.00 0 7437 8 0 0
sp_make_cost_storno 27399 27399 0 0.00 0 20132 89 0 0
sp_multiply_rows_for_pdistr 27398 27398 0 0.00 0 19279 40 0 0
sp_multiply_rows_for_qdistr 20450 20450 0 0.00 0 15333 183 0 0
sp_pay_from_customer 6962 6962 0 0.00 5 17613 195 0 0
sp_pay_to_supplier 5803 5803 0 0.00 6 19301 248 0 0
sp_payment_common 12764 12764 0 0.00 2 11247 55 0 0
sp_qd_handle_on_cancel_clo 1738 1738 0 0.00 0 22897 358 0 0
sp_qd_handle_on_invoice_upd_sts 9915 9915 0 0.00 1 15513 554 0 0
sp_qd_handle_on_reserve_upd_sts 9754 9754 0 0.00 0 11572 84 0 0
sp_reserve_write_off 9755 9755 0 0.00 8 18972 305 0 0
sp_supplier_invoice 5379 5379 0 0.00 9 99321 3402 0 0
sp_supplier_order 5401 5401 0 0.00 7 42765 1279 0 0
srv_aggregate_perf_data 110 110 0 0.00 2001 184730 15842 0 0
srv_fill_mon_cache_memory 68 68 0 0.00 728 120139 39013 0 0
srv_find_qd_qs_mism 992489 992489 0 0.00 0 18315 9 0 0
srv_make_invnt_saldo 472 472 0 0.00 32 136687 4335 0 0
srv_make_money_saldo 882 882 0 0.00 1 26138 412 0 0
srv_recalc_idx_stat 5 5 0 0.00 41341 525967 258497 0 0
t$perf-abend:sp_add_invoice_to_stock 56 56 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4930 4930 0 0.00 1 36586 979 0 0
t$perf-norm:sp_cancel_adding_invoice 404 404 0 0.00 2 8085 437 0 0
t$perf-norm:sp_cancel_client_order 1738 1738 0 0.00 0 1556 27 0 0
t$perf-norm:sp_cancel_customer_reserve 646 646 0 0.00 0 1348 25 0 0
t$perf-norm:sp_cancel_pay_from_customer 624 624 0 0.00 0 481 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 423 423 0 0.00 0 3251 19 0 0
t$perf-norm:sp_cancel_supplier_invoice 376 376 0 0.00 0 1997 43 0 0
t$perf-norm:sp_cancel_supplier_order 379 379 0 0.00 0 118026 7393 0 0
t$perf-norm:sp_cancel_write_off 942 942 0 0.00 0 3170 20 0 0
t$perf-norm:sp_client_order 20451 20451 0 0.00 0 18107 37 0 0
t$perf-norm:sp_customer_reserve 961 961 0 0.00 0 6599 20 0 0
t$perf-norm:sp_pay_from_customer 6963 6963 0 0.00 0 13155 23 0 0
t$perf-norm:sp_pay_to_supplier 5803 5803 0 0.00 0 5879 22 0 0
t$perf-norm:sp_reserve_write_off 9755 9755 0 0.00 0 3721 23 0 0
t$perf-norm:sp_supplier_invoice 5379 5379 0 0.00 0 7647 34 0 0
t$perf-norm:sp_supplier_order 5401 5401 0 0.00 0 5494 31 0 0
t$perf-norm:srv_aggregate_perf_data 110 110 0 0.00 0 293 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 68 68 0 0.00 0 2779 51 0 0
t$perf-norm:srv_make_invnt_saldo 472 472 0 0.00 0 784 14 0 0
t$perf-norm:srv_make_money_saldo 882 882 0 0.00 0 403 6 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 4 183 56 0 0
v_add_invoice_to_stock 9866 9754 112 1.13 0 5142 23 0 112
v_all_customers 37342 37342 0 0.00 0 126 4 0 0
v_all_suppliers 30164 30164 0 0.00 0 79 4 0 0
v_all_wares 40898 40898 0 0.00 0 79 4 0 0
v_cancel_adding_invoice 808 808 0 0.00 0 1792 20 0 0
v_cancel_client_order 3476 3476 0 0.00 0 2727 10 0 0
v_cancel_customer_prepayment 1248 1248 0 0.00 0 2688 13 0 0
v_cancel_customer_reserve 1292 1292 0 0.00 0 2041 12 0 0
v_cancel_payment_to_supplier 846 846 0 0.00 0 1936 12 0 0
v_cancel_supplier_invoice 754 752 2 0.26 0 6127 28 0 2
v_cancel_supplier_order 758 758 0 0.00 0 469 11 0 0
v_cancel_write_off 1884 1884 0 0.00 0 7307 28 0 0
v_max_id_clo_ord 5400 5400 0 0.00 0 1030 5 0 0
v_max_id_clo_res 961 961 0 0.00 0 2646 14 0 0
v_max_id_ord_sup 5375 5375 0 0.00 0 4062 7 0 0
v_max_non_paid_invoice 1496 1496 0 0.00 0 73 7 0 0
v_max_non_paid_realizn 3571 3571 0 0.00 0 2560 8 0 0
v_min_id_clo_ord 5400 5400 0 0.00 0 4288 12 0 0
v_min_id_clo_res 961 961 0 0.00 0 6219 19 0 0
v_min_id_ord_sup 5375 5375 0 0.00 0 4971 10 0 0
v_min_non_paid_invoice 5803 5803 0 0.00 0 9669 29 0 0
v_min_non_paid_realizn 6961 6961 0 0.00 0 11236 23 0 0
v_our_firm 10336 10336 0 0.00 0 102 5 0 0
v_reserve_write_off 19508 19508 0 0.00 0 7493 12 0 0
x_lock_depdocs_on_canc_invoice 10250 10250 0 0.00 0 13971 135 0 0
x_lock_depdocs_on_canc_sup_ord 379 379 0 0.00 0 32311 470 0 0
x_make_qstorno_1000_1200 5401 5401 0 0.00 2 42668 1109 0 0
x_make_qstorno_1200_2000 5379 5379 0 0.00 5 99300 3137 0 0
x_make_qstorno_2100_3300 461236 461236 0 0.00 0 90385 204 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 10-03-2023 07:48:44 to 10-03-2023 07:48:48.

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
05:16:24 11 8192000000 8814341056 8831426560 16409664 0.199 8 3 2 0 16528784 83344 35776
05:17:25 24044 8192000000 9730671760 10029219840 809913168 8.997 100 91 182 0 838729824 7803440 21013216
05:18:54 23595 8192000000 9763780352 10069770240 838789216 9.288 100 93 186 0 872722976 12255232 21678528
05:20:21 16751 8192000000 9795624240 10111045632 864035232 9.540 100 88 175 0 902880352 17997232 20847888
05:21:47 8124 8192000000 9845870880 10174791680 909768928 9.995 100 77 154 0 961662496 35349536 16544032
05:22:58 5760 8192000000 9898820112 10237607936 943490240 10.327 100 81 160 0 1008867936 48728832 16648864
05:24:07 2046 8192000000 9953960832 10301734912 981136320 10.695 100 90 175 0 1068826016 69925728 17763968
05:25:13 1599 8192000000 9978244032 10338639872 1011203696 10.987 100 86 166 0 1095580080 67469856 16906528
05:26:17 872 8192000000 10010622816 10381000704 1035898144 11.225 100 93 179 0 1134316560 80628976 17789440
05:27:19 1214 8192000000 10046661424 10425905152 1057110144 11.429 100 97 190 1 1171305712 96036848 18158720
05:28:22 4689 8192000000 10023165424 10396712960 1065439792 11.509 100 87 166 0 1141212464 59925392 15847280
05:29:27 915 8192000000 10064606864 10447421440 1074152000 11.592 100 93 182 1 1183497568 91457232 17888336
05:30:29 1722 8192000000 10040557808 10436321280 1081518896 11.662 100 77 152 2 1167730912 71356176 14855840
05:31:33 996 8192000000 10063234432 10449141760 1088687216 11.730 100 90 169 2 1186331712 79417744 18226752
05:32:35 1609 8192000000 10047624368 10435837952 1093738864 11.778 100 81 158 0 1166609136 57202016 15668256
05:33:38 1070 8192000000 10085314928 10472673280 1099317200 11.831 100 91 176 2 1211298688 94271968 17709520
05:34:40 1330 8192000000 10080871888 10470215680 1101008736 11.847 100 85 161 1 1199935904 83744992 15182176
05:35:43 1223 8192000000 10062222064 10452365312 1104366704 11.879 100 89 172 1 1179717840 57531824 17819312
05:36:47 1111 8192000000 10061137504 10452090880 1107721024 11.911 100 91 165 3 1181357696 56202704 17433968
05:37:49 5124 8192000000 10027575792 10423775232 1089534704 11.738 100 65 125 1 1163541632 60890272 13116656
05:38:55 1008 8192000000 10055253232 10438946816 1099473536 11.833 100 97 179 1 1180068864 61730800 18864528
05:39:57 1079 8192000000 10042116432 10428239872 1086660048 11.711 100 91 178 3 1162968528 57715152 18593328
05:40:59 956 8192000000 10038060464 10420580352 1071842112 11.570 100 98 180 0 1162382928 71481392 19059424
05:42:01 1265 8192000000 9971190528 10338594816 1025742400 11.127 100 94 178 0 1097258496 53137040 18379056
05:43:03 2112 8192000000 9975155888 10356129792 1030966832 11.178 99 53 99 1 1095524512 53769008 10788672
05:44:06 765 8192000000 9965465792 10335629312 1033482064 11.202 99 91 175 2 1089075536 36271376 19322096
05:45:08 1030 8192000000 9980076192 10355826688 1038573744 11.251 100 92 176 0 1104426480 45142320 20710416
05:46:10 1211 8192000000 10000998688 10374594560 1045123488 11.314 100 97 188 3 1127436432 62550416 19762528
05:47:18 8230 8192000000 10023885360 10405683200 1068722000 11.540 100 70 133 0 1150497952 69148720 12627232
05:48:33 12236 8192000000 10041489760 10430590976 1077209312 11.621 100 71 136 2 1165489696 74731472 13548912
05:49:49 983 8192000000 10085530720 10469732352 1096209696 11.802 100 95 183 1 1201788464 87226544 18352224
05:50:52 1218 8192000000 10115544480 10502926336 1112731776 11.958 100 89 170 4 1229235728 99512192 16991760
05:51:59 1148 8192000000 10135610528 10528624640 1121824592 12.044 100 80 144 1 1252476256 115611392 15040272
05:53:04 986 8192000000 10159046560 10552385536 1126035808 12.084 100 89 166 2 1278203136 134050672 18116656
05:54:07 2498 8192000000 10180877280 10573828096 1136931200 12.187 100 86 165 1 1290531344 135862816 17737328
05:55:17 799 8192000000 10222326528 10614042624 1147974960 12.290 100 93 178 0 1335766144 168570736 19220448
05:56:22 1515 8192000000 10273212752 10669023232 1153869600 12.346 100 91 172 2 1385436320 210990496 20576224
05:57:26 1069 8192000000 10310155968 10717954048 1167371440 12.472 100 97 191 1 1427622272 238301616 21949216
05:58:32 5090 8192000000 10306532400 10724421632 1168449248 12.482 99 74 146 1 1420148928 235367088 16332592
05:59:40 3088 8192000000 10312083008 10718175232 1175448832 12.548 99 76 152 0 1428785936 236258368 17078736
06:00:48 758 8192000000 10378807280 10776518656 1178514960 12.576 99 96 188 1 1485391280 286268256 20608064
06:01:52 928 8192000000 10421957600 10836426752 1184913744 12.636 100 93 183 2 1533021696 325839504 22268448
06:02:57 2539 8192000000 10432122912 10848731136 1187991920 12.665 100 82 160 0 1541536816 334887616 18657280
06:04:03 1221 8192000000 10474858624 10883162112 1187919360 12.664 100 95 188 0 1584329472 374298144 22111968
06:05:09 2006 8192000000 10496836896 10894565376 1162097712 12.423 100 99 191 0 1601059552 416522752 22439088
06:06:15 912 8192000000 10545004352 10962812928 1172707632 12.522 100 97 190 0 1652319648 455245520 24366496
06:07:20 1514 8192000000 10551045616 10958426112 1158423984 12.388 100 86 172 0 1658044896 478210528 21410384
06:08:24 1521 8192000000 10579340864 11047280640 1172841584 12.523 100 98 193 3 1683966384 486264528 24860272
06:09:29 714 8192000000 10564568096 11007188992 1179245456 12.583 100 94 177 0 1671881712 467636176 25000080
06:10:33 7275 8192000000 10508091424 11009028096 1164769472 12.448 99 71 140 0 1613960528 431452208 17738848
06:11:43 15414 8192000000 10424886688 10915889152 1167558352 12.474 100 50 98 1 1543373040 361786176 14028512
06:13:02 12071 8192000000 10462040080 10912604160 1169411456 12.491 100 44 85 0 1552674272 372355408 10907408
06:14:15 12567 8192000000 10459725024 10928648192 1172062752 12.516 100 41 82 0 1566267392 383362880 10841760
06:15:31 11943 8192000000 10400488896 10896691200 1171242864 12.508 100 38 74 1 1516448624 335240176 9965584
06:16:44 35681 8192000000 10333427776 10792181760 1154853072 12.355 99 22 44 0 1439603792 279148304 5602416
06:18:20 31101 8192000000 10163116592 10631569408 1130232160 12.124 98 13 27 0 1287389232 154187904 2969168
06:19:51 20770 8192000000 10089345328 10552737792 1140421392 12.219 100 52 84 1 1190374608 41789680 8163536
06:21:35 22324 8192000000 10110759664 10569695232 1144518624 12.258 100 34 66 1 1229332480 77903296 6910560
06:23:04 92538 8192000000 10097502672 10576826368 1124729280 12.072 98 22 41 1 1179099264 49790752 4579232
06:25:40 35387 8192000000 10139732400 10568605696 1148954208 12.300 100 58 111 1 1228533936 66882016 12697712
06:27:16 52872 8192000000 10074344176 10535936000 1144630432 12.259 99 25 50 0 1180444304 30432720 5381152
06:29:11 52258 8192000000 10097124656 10530619392 1121858928 12.045 97 31 55 0 1159772016 32635664 5277424
06:31:08 18906 8192000000 10085820880 10516226048 1153706720 12.344 100 43 76 1 1178936064 17413408 7815936
06:32:33 29559 8192000000 10071783888 10507632640 1135468512 12.173 99 28 56 0 1151232704 10469040 5295152
06:34:12 66896 8192000000 10079903456 10543816704 1121365120 12.040 98 14 28 0 1190607520 66178800 3063600
06:36:19 111072 8192000000 10131432000 10588233728 1131164640 12.132 99 19 38 1 1195475616 60145728 4165248
06:40:51 120137 8192000000 10093514208 10552233984 1102226144 11.859 96 25 48 0 1147446432 40117504 5102784
06:43:52 75586 8192000000 10139674432 10608050176 1137306352 12.190 99 19 38 0 1226752288 85427104 4018832
06:46:15 62864 8192000000 10123608320 10625912832 1143934720 12.253 99 24 46 0 1267441520 118307024 5199776
06:48:18 27596 8192000000 10160388752 10598461440 1118936896 12.017 98 39 76 3 1227861184 100721056 8203232
06:49:52 81216 8192000000 10093300544 10582392832 1145269552 12.265 100 21 43 1 1218133920 68834384 4029984
06:52:14 59064 8192000000 10094682800 10543349760 1121096144 12.037 98 24 48 0 1158349040 32845984 4406912
06:54:20 65331 8192000000 10092058432 10558300160 1107486464 11.909 97 24 48 2 1170492720 58321456 4684800
06:56:31 100760 8192000000 10094375808 10550415360 1110916016 11.941 97 21 42 3 1146981232 31825376 4239840
06:59:19 48332 8192000000 10080659872 10513547264 1133372864 12.153 100 35 69 0 1194882576 54806496 6703216
07:01:08 55160 8192000000 10121965808 10604814336 1111891440 11.950 98 17 35 0 1196681040 81195936 3593664
07:04:28 61716 8192000000 10058063088 10511151104 1116026912 11.989 98 11 22 0 1156483872 37093104 3363856
07:06:31 95449 8192000000 10084514768 10587430912 1112177120 11.953 98 19 38 0 1194569072 77690784 4701168
07:09:11 62034 8192000000 10054102224 10534842368 1110348240 11.936 98 21 42 0 1185833520 70490448 4994832
07:11:16 80554 8192000000 10063421856 10528096256 1098125808 11.820 97 8 16 0 1127208448 27495808 1586832
07:13:55 43672 8192000000 10072753264 10508546048 1105886192 11.893 99 21 38 1 1153401568 43665472 3849904
07:15:45 31224 8192000000 10111658480 10551054336 1123450416 12.060 100 25 51 0 1205754368 77066960 5236992
07:17:33 40984 8192000000 10174525520 10614419456 1126207168 12.086 99 37 70 0 1252384112 117886768 8290176
07:19:23 82326 8192000000 10145527664 10648084480 1117637632 12.005 98 23 46 0 1278128752 155541552 4949568
07:21:45 69561 8192000000 10075079824 10508591104 1082877840 11.675 96 17 34 0 1128659616 42346288 3435488
07:23:58 24779 8192000000 10096508304 10530594816 1108957488 11.923 99 52 105 3 1180132176 60743920 10430768
07:25:23 66645 8192000000 10064503520 10518732800 1115466720 11.984 99 50 90 2 1169073408 44678832 8927856
07:27:33 39376 8192000000 10048306384 10504450048 1103183856 11.868 98 10 20 0 1163112304 57745520 2182928
07:29:13 77707 8192000000 10060971952 10540404736 1128084336 12.103 100 3 6 0 1173615952 44942480 589136
07:31:38 100157 8192000000 10074719904 10541379584 1102987184 11.866 97 16 28 2 1126710960 20743216 2980560
07:34:18 45040 8192000000 10117748112 10556297216 1096381888 11.803 97 26 52 0 1190818960 88765312 5671760
07:36:17 60657 8192000000 10114331760 10558525440 1144419968 12.257 100 27 54 0 1251279904 101162960 5696976
07:38:24 78803 8192000000 10123500912 10603606016 1134470288 12.163 99 27 53 0 1223656544 83279808 5906448
07:40:44 57783 8192000000 10144220656 10600083456 1109306144 11.926 97 34 66 0 1202866704 86555888 7004672
07:42:42 66741 8192000000 10112347168 10567438336 1106172112 11.896 98 26 52 1 1188749840 77388256 5189472
07:44:50 91757 8192000000 10050001728 10508210176 1120387744 12.031 99 32 63 1 1165649744 38681744 6580256

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 10-03-2023 07:48:50 to 10-03-2023 07:49:06.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 56 v_add_invoice_to_stock 335544517
except 1 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 10-03-2023 07:49:07 to 10-03-2023 07:49:09.

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          218887
MON$OLDEST_ACTIVE               219373
MON$OLDEST_SNAPSHOT             219373
MON$NEXT_TRANSACTION            219373
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                       2046944
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             774
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2907 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2907 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2907 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2907 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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Fri Mar 10 07:46:32 2023
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Fri Mar 10 07:46:34 2023
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:13: 
--- end of diff output ---

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

10.03.23 07:49:10. 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-03-10 07:49:13 No severe PSQL-related problems occured
10.03.23 07:49:14. End of report.