Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 26.02.2023 10: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 15211312 kB
MemAvailable 30359812 kB
Buffers 0 kB
Cached 16458112 kB
SwapCached 3144 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.3
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3400
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2023-02-26 10:37:26
DTS_END                         2023-02-26 10:37:26.4670
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/37450
TRN_ID                          218175
ATT_ID                          647
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: 8644.83 25611 1037380
customer order: creation 166.33 501 19959
customer order: refuse 14.54 398 1745
order to supplier: creation 45.97 1323 5516
order to supplier: removal 3.47 156293 416
invoice (draft): creation 45.15 2977 5418
invoice (draft): removal 48.97 964 5876
invoice accept: apply 40.23 27541 4828
invoice accept: cancel 43.94 1231 5273
customer reserve: creation 3699.63 268 443955
customer reserve: removal 4248.85 100 509862
realization accept: apply 81.02 259 9722
realization accept: cancel 81.38 165 9765
payment from customer: creation 56.80 173 6816
payment from customer: removal 5.78 122 694
payment to supplier: creation 47.30 191 5676
payment to supplier: removal 3.39 105 407
service: total inventory turnovers 3.93 3834 471
service: total monetary turnovers 7.26 369 871
service: aggregate perf. data 0.87 16923 104
service: refresh index statistics 0.05 298490 6

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 26-02-2023 10:38:40 to 26-02-2023 10:38:42.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3445
TEST_TIME 2 4722
TEST_TIME 3 5076
TEST_TIME 4 5451
TEST_TIME 5 5631
TEST_TIME 6 6153
TEST_TIME 7 6653
TEST_TIME 8 7061
TEST_TIME 9 7083
TEST_TIME 10 7082
TEST_TIME 11 7385
TEST_TIME 12 7310
TEST_TIME 13 7077
TEST_TIME 14 6892
TEST_TIME 15 6759
TEST_TIME 16 7045
TEST_TIME 17 7197
TEST_TIME 18 7252
TEST_TIME 19 7622
TEST_TIME 20 7492
TEST_TIME 21 7703
TEST_TIME 22 7643
TEST_TIME 23 8146
TEST_TIME 24 8499
TEST_TIME 25 8482
TEST_TIME 26 8798
TEST_TIME 27 9311
TEST_TIME 28 9776
TEST_TIME 29 10299
TEST_TIME 30 10814
TEST_TIME 31 10920
TEST_TIME 32 11120
TEST_TIME 33 10802
TEST_TIME 34 10551
TEST_TIME 35 10358
TEST_TIME 36 10285
TEST_TIME 37 10192
TEST_TIME 38 10110
TEST_TIME 39 10039
TEST_TIME 40 9899
TEST_TIME 41 9807
TEST_TIME 42 9722
TEST_TIME 43 9629
TEST_TIME 44 9425
TEST_TIME 45 9452
TEST_TIME 46 9387
TEST_TIME 47 9396
TEST_TIME 48 9529
TEST_TIME 49 9542
TEST_TIME 50 9486
TEST_TIME 51 9391
TEST_TIME 52 9368
TEST_TIME 53 9552
TEST_TIME 54 9456
TEST_TIME 55 9406
TEST_TIME 56 9480
TEST_TIME 57 9611
TEST_TIME 58 9597
TEST_TIME 59 9596
TEST_TIME 60 9587
TEST_TIME 61 9559
TEST_TIME 62 9427
TEST_TIME 63 9313
TEST_TIME 64 9299
TEST_TIME 65 9210
TEST_TIME 66 9214
TEST_TIME 67 9204
TEST_TIME 68 9204
TEST_TIME 69 9134
TEST_TIME 70 9115
TEST_TIME 71 9105
TEST_TIME 72 9100
TEST_TIME 73 9118
TEST_TIME 74 9109
TEST_TIME 75 9168
TEST_TIME 76 9147
TEST_TIME 77 9121
TEST_TIME 78 9087
TEST_TIME 79 9117
TEST_TIME 80 9175
TEST_TIME 81 9141
TEST_TIME 82 9109
TEST_TIME 83 9137
TEST_TIME 84 9096
TEST_TIME 85 9043
TEST_TIME 86 9004
TEST_TIME 87 8973
TEST_TIME 88 8946
TEST_TIME 89 8895
TEST_TIME 90 8879
TEST_TIME 91 8804
TEST_TIME 92 8810
TEST_TIME 93 8789
TEST_TIME 94 8771
TEST_TIME 95 8810
TEST_TIME 96 8777
TEST_TIME 97 8779
TEST_TIME 98 8781
TEST_TIME 99 8787
TEST_TIME 100 8874
TEST_TIME 101 8876
TEST_TIME 102 8914
TEST_TIME 103 8919
TEST_TIME 104 8893
TEST_TIME 105 8882
TEST_TIME 106 8938
TEST_TIME 107 8862
TEST_TIME 108 8847
TEST_TIME 109 8815
TEST_TIME 110 8770
TEST_TIME 111 8764
TEST_TIME 112 8731
TEST_TIME 113 8704
TEST_TIME 114 8716
TEST_TIME 115 8695
TEST_TIME 116 8707
TEST_TIME 117 8687
TEST_TIME 118 8700
TEST_TIME 119 8687
TEST_TIME 120 8667

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 26-02-2023 10:38:44 to 26-02-2023 10:38:47.

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 1448388 1448388 0 0.00 0 13751 14 0 0
doc_list_biud 1448372 1448372 0 0.00 0 40332 41 0 0
sp_add_invoice_to_stock 4982 4828 154 3.09 46 226244 27541 0 154
sp_cancel_adding_invoice 5273 5273 0 0.00 6 131801 1232 0 0
sp_cancel_client_order 1745 1745 0 0.00 6 11324 398 0 0
sp_cancel_customer_reserve 509862 509862 0 0.00 1 27634 101 0 0
sp_cancel_pay_from_customer 694 694 0 0.00 4 3043 123 0 0
sp_cancel_pay_to_supplier 407 407 0 0.00 3 2488 105 0 0
sp_cancel_supplier_invoice 5880 5876 4 0.06 2 40339 964 0 4
sp_cancel_supplier_order 416 416 0 0.00 11 1559736 156293 0 0
sp_cancel_write_off 9765 9765 0 0.00 2 7971 165 0 0
sp_client_order 19959 19959 0 0.00 5 35191 501 0 0
sp_customer_reserve 443955 443955 0 0.00 0 132873 269 0 0
sp_fill_shopping_cart 30878 30878 0 0.00 1 8412 80 0 0
sp_fill_shopping_cart_clo_res 443922 443922 0 0.00 0 18398 20 0 0
sp_get_clo_for_invoice 4780 4780 0 0.00 0 27082 316 0 0
sp_kill_cost_storno 16139 16139 0 0.00 0 9679 69 0 0
sp_kill_qstorno_ret_qs2qd 1035781 1035781 0 0.00 0 40330 44 0 0
sp_kill_qty_storno 547436 547436 0 0.00 0 40332 101 0 0
sp_lock_selected_doc 20209 20209 0 0.00 0 6834 8 0 0
sp_make_cost_storno 26994 26994 0 0.00 0 11225 71 0 0
sp_multiply_rows_for_pdistr 26992 26992 0 0.00 0 12876 38 0 0
sp_multiply_rows_for_qdistr 19959 19959 0 0.00 0 14106 200 0 0
sp_pay_from_customer 6816 6816 0 0.00 5 15454 173 0 0
sp_pay_to_supplier 5676 5676 0 0.00 6 15441 191 0 0
sp_payment_common 12491 12491 0 0.00 2 9964 45 0 0
sp_qd_handle_on_cancel_clo 1745 1745 0 0.00 0 11313 324 0 0
sp_qd_handle_on_invoice_upd_sts 10052 10052 0 0.00 0 33904 504 0 0
sp_qd_handle_on_reserve_upd_sts 9720 9720 0 0.00 0 13061 61 0 0
sp_reserve_write_off 9722 9722 0 0.00 8 13153 259 0 0
sp_supplier_invoice 5418 5418 0 0.00 9 75740 2977 0 0
sp_supplier_order 5516 5516 0 0.00 8 41341 1323 0 0
srv_aggregate_perf_data 104 104 0 0.00 2516 148684 16923 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 871 161969 35976 0 0
srv_find_qd_qs_mism 991129 991129 0 0.00 0 13736 12 0 0
srv_make_invnt_saldo 471 471 0 0.00 58 59166 3834 0 0
srv_make_money_saldo 871 871 0 0.00 2 10018 369 0 0
srv_recalc_idx_stat 6 6 0 0.00 68812 575874 298491 0 0
t$perf-abend:sp_add_invoice_to_stock 154 154 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 4 4 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4830 4830 0 0.00 1 18872 829 0 0
t$perf-norm:sp_cancel_adding_invoice 405 405 0 0.00 1 5870 418 0 0
t$perf-norm:sp_cancel_client_order 1745 1745 0 0.00 0 814 27 0 0
t$perf-norm:sp_cancel_customer_reserve 676 676 0 0.00 0 651 19 0 0
t$perf-norm:sp_cancel_pay_from_customer 694 694 0 0.00 0 825 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 407 407 0 0.00 0 853 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 390 390 0 0.00 0 1300 45 0 0
t$perf-norm:sp_cancel_supplier_order 416 416 0 0.00 0 73183 5927 0 0
t$perf-norm:sp_cancel_write_off 978 978 0 0.00 0 5483 29 0 0
t$perf-norm:sp_client_order 19959 19959 0 0.00 0 14863 34 0 0
t$perf-norm:sp_customer_reserve 950 950 0 0.00 0 976 14 0 0
t$perf-norm:sp_pay_from_customer 6816 6816 0 0.00 0 1987 16 0 0
t$perf-norm:sp_pay_to_supplier 5676 5676 0 0.00 0 7914 19 0 0
t$perf-norm:sp_reserve_write_off 9722 9722 0 0.00 0 10012 22 0 0
t$perf-norm:sp_supplier_invoice 5418 5418 0 0.00 0 9345 27 0 0
t$perf-norm:sp_supplier_order 5516 5516 0 0.00 0 23200 32 0 0
t$perf-norm:srv_aggregate_perf_data 104 104 0 0.00 0 142 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 990 34 0 0
t$perf-norm:srv_make_invnt_saldo 471 471 0 0.00 0 511 12 0 0
t$perf-norm:srv_make_money_saldo 871 871 0 0.00 0 2873 8 0 0
t$perf-norm:srv_recalc_idx_stat 6 6 0 0.00 3 197 70 0 0
v_add_invoice_to_stock 9864 9556 308 3.12 0 6853 27 0 308
v_all_customers 36388 36388 0 0.00 0 70 4 0 0
v_all_suppliers 30190 30190 0 0.00 0 68 4 0 0
v_all_wares 39914 39914 0 0.00 0 59 4 0 0
v_cancel_adding_invoice 810 810 0 0.00 0 1053 14 0 0
v_cancel_client_order 3490 3490 0 0.00 0 1868 9 0 0
v_cancel_customer_prepayment 1388 1388 0 0.00 0 3034 13 0 0
v_cancel_customer_reserve 1352 1352 0 0.00 0 10652 19 0 0
v_cancel_payment_to_supplier 814 814 0 0.00 0 1942 12 0 0
v_cancel_supplier_invoice 788 780 8 1.01 0 1751 28 0 8
v_cancel_supplier_order 832 832 0 0.00 0 677 13 0 0
v_cancel_write_off 1956 1956 0 0.00 0 2436 25 0 0
v_max_id_clo_ord 5515 5515 0 0.00 0 1164 5 0 0
v_max_id_clo_res 950 950 0 0.00 0 1104 12 0 0
v_max_id_ord_sup 5406 5406 0 0.00 0 3681 7 0 0
v_max_non_paid_invoice 1501 1501 0 0.00 0 239 8 0 0
v_max_non_paid_realizn 3641 3641 0 0.00 0 2468 7 0 0
v_min_id_clo_ord 5515 5515 0 0.00 0 7997 9 0 0
v_min_id_clo_res 950 950 0 0.00 0 5784 25 0 0
v_min_id_ord_sup 5406 5406 0 0.00 0 4147 8 0 0
v_min_non_paid_invoice 5676 5676 0 0.00 0 9882 18 0 0
v_min_non_paid_realizn 6815 6815 0 0.00 0 9930 22 0 0
v_our_firm 9872 9872 0 0.00 0 132 4 0 0
v_reserve_write_off 19440 19440 0 0.00 0 10656 11 0 0
x_lock_depdocs_on_canc_invoice 10759 10759 0 0.00 0 18931 141 0 0
x_lock_depdocs_on_canc_sup_ord 416 416 0 0.00 0 14113 345 0 0
x_make_qstorno_1000_1200 5516 5516 0 0.00 2 41328 1164 0 0
x_make_qstorno_1200_2000 5418 5418 0 0.00 4 75617 2755 0 0
x_make_qstorno_2100_3300 443954 443954 0 0.00 0 132864 237 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 26-02-2023 10:38:48 to 26-02-2023 10:38:52.

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:30 3 8192000000 8800555744 8812548096 5567648 0.067 2 2 3 0 5701376 70688 63040
08:08:31 31413 8192000000 9732778800 10033238016 802581824 8.922 100 86 172 0 831489888 7538976 21369088
08:10:06 24925 8192000000 9775358368 10085109760 846840064 9.368 100 89 176 0 880211968 12686880 20685024
08:11:36 16384 8192000000 9813774800 10135187456 878525968 9.685 100 81 162 0 921149040 24315024 18308048
08:13:05 5887 8192000000 9876185408 10213892096 921511984 10.111 100 79 154 0 982102656 43127840 17462832
08:14:16 4423 8192000000 9933140096 10278629376 971146288 10.598 100 89 173 3 1048015984 58440672 18429024
08:15:22 1227 8192000000 9986302272 10344288256 1003230688 10.910 100 89 178 0 1104535552 82707856 18597008
08:16:25 1249 8192000000 10003800624 10371686400 1027575376 11.145 100 92 178 0 1124676496 79534160 17566960
08:17:28 1092 8192000000 10021296656 10393501696 1048075984 11.342 100 90 176 0 1145733424 79895088 17762352
08:18:31 1569 8192000000 10027580496 10405556224 1067918304 11.532 100 95 178 1 1148630496 61621984 19090208
08:19:33 1666 8192000000 10053101968 10427691008 1066137056 11.515 100 94 184 0 1169248960 84723040 18388864
08:20:36 2598 8192000000 10018461744 10398531584 1072235584 11.573 100 77 149 0 1143720800 57692000 13793216
08:21:40 909 8192000000 10042221952 10433957888 1079140816 11.639 100 90 172 0 1167666864 69823200 18702848
08:22:42 1083 8192000000 10068248976 10451980288 1086835200 11.713 100 95 186 0 1190142288 83874272 19432816
08:23:44 1288 8192000000 10055381696 10441121792 1086560528 11.710 100 92 183 1 1176328288 71185840 18581920
08:24:47 1649 8192000000 10032115088 10428174336 1090291104 11.745 100 77 153 1 1157146416 51692656 15162656
08:25:49 1060 8192000000 10046965584 10441576448 1094864304 11.789 100 89 172 2 1169687856 56028016 18795536
08:26:51 1231 8192000000 10096060736 10488475648 1093944352 11.780 100 96 184 2 1213436624 101548560 17943712
08:27:53 1134 8192000000 10045946928 10434732032 1077349904 11.622 99 93 178 0 1165691136 70221680 18119552
08:28:55 1207 8192000000 10080832592 10471829504 1089500624 11.738 100 97 192 0 1205488432 96786832 19200976
08:29:57 771 8192000000 10035744256 10428043264 1070382528 11.556 100 73 137 2 1165084160 80663712 14037920
08:30:59 1403 8192000000 10010070608 10388660224 1055561296 11.414 100 99 188 1 1133923584 59231328 19130960
08:32:01 824 8192000000 9998169584 10378661888 1059656560 11.453 100 84 159 1 1118504592 42041344 16806688
08:33:05 1130 8192000000 10008752240 10380951552 1035638864 11.223 100 95 187 2 1133031040 78297120 19095056
08:34:07 1174 8192000000 9997660400 10369458176 1031629360 11.184 100 98 182 1 1120842048 70433216 18779472
08:35:11 1768 8192000000 9942835792 10321842176 1024920784 11.119 98 58 101 2 1061985104 26024416 11039904
08:36:14 924 8192000000 9993567584 10373767168 1048178304 11.343 100 84 161 0 1116494768 51366464 16950000
08:37:16 1747 8192000000 9997735056 10381922304 1063701056 11.492 100 68 132 1 1123183328 45681120 13801152
08:38:25 10602 8192000000 10024944416 10402172928 1065717120 11.511 99 88 157 1 1138899728 57931440 15251168
08:39:46 2865 8192000000 10079588912 10461335552 1089955824 11.742 100 87 160 3 1196210720 91561312 14693584
08:40:52 1885 8192000000 10088686432 10476560384 1102754528 11.864 100 76 145 2 1206476144 89268832 14452784
08:41:56 1826 8192000000 10113521552 10509180928 1113106496 11.962 100 80 151 1 1223964704 95961744 14896464
08:43:03 3214 8192000000 10131546800 10536124416 1118252016 12.010 100 87 156 2 1247968560 113754304 15962240
08:44:09 953 8192000000 10163176112 10557337600 1127376016 12.097 100 93 181 1 1279310160 132131904 19802240
08:45:13 1530 8192000000 10172383360 10579943424 1127884688 12.101 99 81 143 3 1286402448 143890784 14626976
08:46:18 1405 8192000000 10195851056 10595971072 1146830048 12.280 100 88 159 0 1308066320 144913824 16322448
08:47:23 2701 8192000000 10217465296 10616102912 1158581360 12.390 100 90 177 1 1329663936 151903024 19179552
08:48:30 1039 8192000000 10241576272 10652450816 1181914944 12.608 100 92 174 2 1359406208 156572416 20918848
08:49:34 3863 8192000000 10299835856 10703323136 1195678224 12.736 100 79 153 2 1407719360 194946832 17094304
08:50:47 1388 8192000000 10338642544 10743934976 1203037808 12.805 100 89 169 0 1450103584 227258160 19807616
08:51:51 1542 8192000000 10388996080 10795094016 1196060720 12.740 100 85 166 0 1499574304 283550768 19962816
08:52:58 1125 8192000000 10408905120 10842394624 1196992624 12.748 99 88 172 2 1519471984 299944032 22535328
08:54:03 1235 8192000000 10438013136 10857517056 1206195072 12.834 100 90 176 0 1543334336 316626048 20513216
08:55:07 1797 8192000000 10477844080 10898776064 1202032752 12.795 100 92 176 0 1584892416 361388128 21471536
08:56:12 1477 8192000000 10506626336 10933755904 1211032336 12.879 100 93 182 1 1616875152 382860160 22982656
08:57:15 951 8192000000 10521832000 10930712576 1206132736 12.833 100 93 180 1 1626107808 399262976 20712096
08:58:18 1039 8192000000 10532071008 10957524992 1198871792 12.766 100 97 192 0 1637569232 415976592 22720848
08:59:26 865 8192000000 10538663312 10985283584 1193672704 12.718 100 86 164 0 1641961808 428818080 19471024
09:00:30 1545 8192000000 10502171664 10948689920 1171142544 12.508 99 77 146 1 1605940736 416567136 18231056
09:01:33 8161 8192000000 10499049920 10937069568 1177293536 12.565 100 57 113 0 1612458288 420327824 14836928
09:02:44 13391 8192000000 10502425008 11003772928 1173193200 12.527 99 54 108 0 1619701696 432555584 13952912
09:04:05 5814 8192000000 10498104368 10957479936 1166388512 12.463 99 51 100 1 1589741648 409845296 13507840
09:05:17 28931 8192000000 10345033152 10855075840 1165452192 12.454 100 32 62 1 1472730208 298828688 8449328
09:06:52 41617 8192000000 10208968192 10722766848 1122547488 12.051 98 17 34 0 1331513392 204697264 4268640
09:09:45 86134 8192000000 10076002672 10507792384 1123580624 12.061 98 39 76 0 1152105728 20108368 8416736
09:12:12 20262 8192000000 10093293328 10531520512 1142562128 12.240 100 42 77 1 1196560128 45178064 8819936
09:13:34 23382 8192000000 10088772352 10531155968 1119846784 12.026 98 22 44 0 1184971408 60908432 4216192
09:15:04 53258 8192000000 10071851552 10540544000 1112706272 11.958 98 21 40 0 1157659088 41081216 3871600
09:16:58 80718 8192000000 10116267424 10557943808 1133752560 12.157 99 34 63 1 1195742288 54599152 7390576
09:19:29 81172 8192000000 10136767072 10586574848 1093813152 11.779 95 13 26 0 1193933040 97051664 3068224
09:23:48 76928 8192000000 10154969232 10645020672 1106539520 11.900 97 10 20 0 1218194768 109248224 2407024
09:26:06 43138 8192000000 10075940816 10541035520 1125740640 12.081 100 32 58 0 1188413696 56243280 6429776
09:27:49 58216 8192000000 10163798320 10609627136 1145537152 12.268 100 22 44 0 1232312160 82424800 4350208
09:29:48 94154 8192000000 10068445904 10541023232 1096143328 11.801 96 14 28 0 1148561728 49049744 3368656
09:32:31 64264 8192000000 10137911648 10617487360 1100970272 11.847 97 27 54 0 1202170752 95513792 5686688
09:34:38 85334 8192000000 10055748848 10504118272 1093748896 11.778 97 24 44 0 1133642688 35529632 4364160
09:37:04 33572 8192000000 10104562144 10565435392 1113556880 11.966 99 37 74 0 1162446064 40736368 8152816
09:38:38 161969 8192000000 10049313776 10512101376 1079089536 11.639 96 11 20 0 1128393184 47095440 2208208
09:42:38 62862 8192000000 10138247600 10631077888 1130823440 12.129 99 25 50 0 1249879392 113944720 5111232
09:44:41 50155 8192000000 10084457056 10566402048 1092200832 11.764 96 11 23 0 1158135376 63520800 2413744
09:46:31 62617 8192000000 10073602464 10555748352 1120586512 12.033 99 15 28 1 1189263696 65592768 3084416
09:49:52 40067 8192000000 10091547488 10538627072 1128762912 12.110 98 18 34 0 1186658544 54180064 3715568
09:51:32 35971 8192000000 10120540944 10568249344 1151734848 12.326 100 28 56 0 1215531632 57914016 5882768
09:53:11 46974 8192000000 10099024144 10524700672 1127693664 12.100 98 50 98 0 1177364592 38915984 10754944
09:54:58 6803 8192000000 10110759136 10534625280 1145329248 12.266 100 59 116 0 1200618496 44068784 11220464
09:56:06 26884 8192000000 10154053008 10584264704 1128582512 12.108 98 40 80 0 1218841456 82047472 8211472
09:57:38 66284 8192000000 10112036784 10558492672 1094803104 11.788 96 32 64 1 1182052896 80944240 6305552
09:59:46 12535 8192000000 10085538592 10513084416 1096307600 11.803 97 50 98 0 1158848688 51824432 10716656
10:00:59 37698 8192000000 10090144832 10512494592 1122594176 12.051 100 40 77 0 1189907360 59337648 7975536
10:02:57 69724 8192000000 10058582128 10548092928 1121371104 12.040 99 25 50 1 1181225712 54752656 5101952
10:05:08 57729 8192000000 10040851360 10492030976 1117605680 12.004 99 15 30 0 1149925472 29184688 3135104
10:07:08 56287 8192000000 10063621232 10527854592 1121405968 12.040 99 15 30 0 1176430208 51681920 3342320
10:09:44 90778 8192000000 10076702704 10537627648 1119186128 12.019 98 24 48 0 1160627360 36674864 4766368
10:12:27 51519 8192000000 10096885712 10544979968 1131642688 12.137 99 21 42 0 1183977504 48273840 4060976
10:14:25 29531 8192000000 10112686176 10571497472 1122687040 12.052 98 34 64 0 1193210688 63882352 6641296
10:15:59 23969 8192000000 10144825168 10584686592 1146414304 12.276 99 39 77 0 1251270528 96942560 7913664
10:17:28 61414 8192000000 10079358528 10541522944 1094076032 11.781 95 19 36 0 1140032480 42559216 3397232
10:19:32 47580 8192000000 10094565520 10541125632 1113668976 11.967 98 33 64 0 1180690784 60181088 6840720
10:21:22 16359 8192000000 10107059872 10534162432 1127021248 12.093 99 55 109 0 1189570656 52396048 10153360
10:22:46 41624 8192000000 10142960032 10620289024 1149423728 12.304 100 38 76 0 1265068160 108006064 7638368
10:24:30 109244 8192000000 10086202480 10551701504 1136727456 12.185 99 23 45 1 1178123344 36803792 4592096
10:27:25 51320 8192000000 10059476288 10538229760 1138969856 12.206 99 5 10 0 1182395168 42349200 1076112
10:29:16 40012 8192000000 10102768752 10548948992 1116791552 11.997 98 23 47 1 1163585856 41835840 4958464
10:30:57 63746 8192000000 10077284576 10515861504 1072128880 11.572 94 26 52 0 1133591680 55840496 5622304
10:33:01 40708 8192000000 10066837872 10526851072 1082920640 11.675 96 34 68 0 1149630672 59802224 6907808
10:34:51 28096 8192000000 10095880320 10527735808 1078261616 11.631 96 51 100 0 1169545504 80245904 11037984
10:36:31 48307 8192000000 10120008640 10579697664 1128849232 12.111 100 30 60 1 1220791072 85476112 6465728

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 26-02-2023 10:38:54 to 26-02-2023 10:39:11.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 154 v_add_invoice_to_stock 335544517
except 4 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 26-02-2023 10:39:12 to 26-02-2023 10:39:14.

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          217482
MON$OLDEST_ACTIVE               221066
MON$OLDEST_SNAPSHOT             221066
MON$NEXT_TRANSACTION            221066
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                       2037344
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             777
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2903 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2903 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2903 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2903 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 ---
:279: 
:280: mscs-test-161.budzdorov.ru    Sun Feb 26 10:37:30 2023
:281:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:282: 
:283: 
:284: mscs-test-161.budzdorov.ru    Sun Feb 26 10:37:31 2023
:285:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:286: 
:287: 
:288: mscs-test-161.budzdorov.ru    Sun Feb 26 10:37:36 2023
:289:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:290: 
:291: 
:292: mscs-test-161.budzdorov.ru    Sun Feb 26 10:37:38 2023
:293:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:294: 
:295: 
:296: mscs-test-161.budzdorov.ru    Sun Feb 26 10:37:40 2023
:297:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:298: 
--- end of diff output ---

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

26.02.23 10:39:15. There are 3 logs in /var/tmp/logs-oltp40 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2023-02-26 10:39:17 No severe PSQL-related problems occured
26.02.23 10:39:19. End of report.