Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 10.02.2023 22: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 15128116 kB
MemAvailable 30405024 kB
Buffers 0 kB
Cached 16551568 kB
SwapCached 5064 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 3.0.11
DB_NAME                         /home/bases/oltp30-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/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/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=3333
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/oltp30-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-oltp30
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-10 22:38:36
DTS_END                         2023-02-10 22:38:36.1890
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/38696
TRN_ID                          247570
ATT_ID                          740
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: 9719.08 18248 1166290
customer order: creation 181.13 342 21736
customer order: refuse 15.23 336 1827
order to supplier: creation 47.38 1181 5685
order to supplier: removal 3.59 84743 431
invoice (draft): creation 47.55 3347 5706
invoice (draft): removal 55.87 699 6704
invoice accept: apply 43.89 17671 5267
invoice accept: cancel 51.03 741 6123
customer reserve: creation 4041.39 176 484967
customer reserve: removal 4919.48 48 590338
realization accept: apply 85.73 169 10288
realization accept: cancel 93.95 95 11274
payment from customer: creation 60.56 127 7267
payment from customer: removal 6.01 87 721
payment to supplier: creation 50.28 140 6034
payment to supplier: removal 3.50 82 420
service: total inventory turnovers 5.11 939 613
service: total monetary turnovers 6.61 29 793
service: aggregate perf. data 0.77 24551 92
service: refresh index statistics 0.03 229449 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 10-02-2023 22:39:27 to 10-02-2023 22:39:29.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2196
TEST_TIME 2 2529
TEST_TIME 3 3257
TEST_TIME 4 3822
TEST_TIME 5 4244
TEST_TIME 6 4318
TEST_TIME 7 4641
TEST_TIME 8 5221
TEST_TIME 9 5323
TEST_TIME 10 5830
TEST_TIME 11 6467
TEST_TIME 12 6381
TEST_TIME 13 6373
TEST_TIME 14 6551
TEST_TIME 15 6733
TEST_TIME 16 7264
TEST_TIME 17 7675
TEST_TIME 18 7733
TEST_TIME 19 8067
TEST_TIME 20 8172
TEST_TIME 21 8759
TEST_TIME 22 9510
TEST_TIME 23 9386
TEST_TIME 24 9685
TEST_TIME 25 9756
TEST_TIME 26 9912
TEST_TIME 27 9893
TEST_TIME 28 10282
TEST_TIME 29 10365
TEST_TIME 30 10109
TEST_TIME 31 10602
TEST_TIME 32 10735
TEST_TIME 33 10531
TEST_TIME 34 10654
TEST_TIME 35 10556
TEST_TIME 36 10392
TEST_TIME 37 10325
TEST_TIME 38 10188
TEST_TIME 39 10180
TEST_TIME 40 10225
TEST_TIME 41 10074
TEST_TIME 42 10033
TEST_TIME 43 9997
TEST_TIME 44 9940
TEST_TIME 45 10145
TEST_TIME 46 10033
TEST_TIME 47 9998
TEST_TIME 48 9910
TEST_TIME 49 9750
TEST_TIME 50 9775
TEST_TIME 51 9739
TEST_TIME 52 9748
TEST_TIME 53 9712
TEST_TIME 54 9624
TEST_TIME 55 9592
TEST_TIME 56 9535
TEST_TIME 57 9555
TEST_TIME 58 9686
TEST_TIME 59 9617
TEST_TIME 60 9789
TEST_TIME 61 9762
TEST_TIME 62 9871
TEST_TIME 63 9912
TEST_TIME 64 9940
TEST_TIME 65 9944
TEST_TIME 66 10018
TEST_TIME 67 10012
TEST_TIME 68 9971
TEST_TIME 69 10045
TEST_TIME 70 10119
TEST_TIME 71 10090
TEST_TIME 72 9963
TEST_TIME 73 9906
TEST_TIME 74 9945
TEST_TIME 75 9870
TEST_TIME 76 9906
TEST_TIME 77 9857
TEST_TIME 78 9817
TEST_TIME 79 9784
TEST_TIME 80 9753
TEST_TIME 81 9735
TEST_TIME 82 9664
TEST_TIME 83 9674
TEST_TIME 84 9605
TEST_TIME 85 9586
TEST_TIME 86 9626
TEST_TIME 87 9609
TEST_TIME 88 9596
TEST_TIME 89 9659
TEST_TIME 90 9617
TEST_TIME 91 9681
TEST_TIME 92 9731
TEST_TIME 93 9733
TEST_TIME 94 9697
TEST_TIME 95 9796
TEST_TIME 96 9789
TEST_TIME 97 9862
TEST_TIME 98 9844
TEST_TIME 99 9823
TEST_TIME 100 9888
TEST_TIME 101 9811
TEST_TIME 102 9766
TEST_TIME 103 9696
TEST_TIME 104 9689
TEST_TIME 105 9695
TEST_TIME 106 9648
TEST_TIME 107 9686
TEST_TIME 108 9660
TEST_TIME 109 9629
TEST_TIME 110 9689
TEST_TIME 111 9690
TEST_TIME 112 9655
TEST_TIME 113 9655
TEST_TIME 114 9671
TEST_TIME 115 9748
TEST_TIME 116 9722
TEST_TIME 117 9737
TEST_TIME 118 9693
TEST_TIME 119 9758
TEST_TIME 120 9721

Performance per minute, chart


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

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1614832 1614832 0 0.00 0 13580 6 0 0
doc_list_biud 1614803 1614803 0 0.00 0 56270 23 0 0
sp_add_invoice_to_stock 5430 5267 163 3.00 27 173638 17672 0 163
sp_cancel_adding_invoice 6123 6123 0 0.00 4 63335 741 0 0
sp_cancel_client_order 1827 1827 0 0.00 4 13914 336 0 0
sp_cancel_customer_reserve 590338 590338 0 0.00 0 56272 48 0 0
sp_cancel_pay_from_customer 721 721 0 0.00 2 5063 87 0 0
sp_cancel_pay_to_supplier 420 420 0 0.00 3 4162 82 0 0
sp_cancel_supplier_invoice 6717 6704 13 0.19 1 17236 699 0 13
sp_cancel_supplier_order 431 431 0 0.00 6 1087942 84743 0 0
sp_cancel_write_off 11274 11274 0 0.00 1 6314 95 0 0
sp_client_order 21736 21736 0 0.00 3 18560 342 0 0
sp_customer_reserve 484967 484967 0 0.00 0 91789 177 0 0
sp_fill_shopping_cart 33127 33127 0 0.00 1 12265 35 0 0
sp_fill_shopping_cart_clo_res 484967 484967 0 0.00 0 24933 13 0 0
sp_get_clo_for_invoice 5263 5263 0 0.00 0 26204 259 0 0
sp_kill_cost_storno 18538 18538 0 0.00 0 7215 47 0 0
sp_kill_qstorno_ret_qs2qd 1199085 1199085 0 0.00 0 56267 26 0 0
sp_kill_qty_storno 632239 632239 0 0.00 0 56270 58 0 0
sp_lock_selected_doc 21540 21540 0 0.00 0 9973 6 0 0
sp_make_cost_storno 28852 28852 0 0.00 0 13571 60 0 0
sp_multiply_rows_for_pdistr 28823 28823 0 0.00 0 7080 25 0 0
sp_multiply_rows_for_qdistr 21736 21736 0 0.00 0 18536 155 0 0
sp_pay_from_customer 7267 7267 0 0.00 4 14794 127 0 0
sp_pay_to_supplier 6034 6034 0 0.00 4 14802 141 0 0
sp_payment_common 13281 13281 0 0.00 2 8982 33 0 0
sp_qd_handle_on_cancel_clo 1827 1827 0 0.00 0 13906 306 0 0
sp_qd_handle_on_invoice_upd_sts 11381 11381 0 0.00 0 21050 316 0 0
sp_qd_handle_on_reserve_upd_sts 10284 10284 0 0.00 0 13853 56 0 0
sp_reserve_write_off 10288 10288 0 0.00 6 13875 169 0 0
sp_supplier_invoice 5706 5706 0 0.00 7 97197 3347 0 0
sp_supplier_order 5685 5685 0 0.00 5 31660 1181 0 0
srv_aggregate_perf_data 92 92 0 0.00 2922 432722 24551 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1587 128078 42834 0 0
srv_find_qd_qs_mism 1116310 1116310 0 0.00 0 13422 5 0 0
srv_make_invnt_saldo 613 613 0 0.00 30 36521 939 0 0
srv_make_money_saldo 793 793 0 0.00 1 4310 30 0 0
srv_recalc_idx_stat 4 4 0 0.00 192504 329310 229450 0 0
t$perf-abend:sp_add_invoice_to_stock 163 163 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5267 5267 0 0.00 0 28385 183 0 0
t$perf-norm:sp_cancel_adding_invoice 445 445 0 0.00 0 23720 138 0 0
t$perf-norm:sp_cancel_client_order 1827 1827 0 0.00 0 3862 13 0 0
t$perf-norm:sp_cancel_customer_reserve 704 704 0 0.00 0 1769 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 721 721 0 0.00 0 4873 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 420 420 0 0.00 0 2379 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 438 438 0 0.00 0 1897 17 0 0
t$perf-norm:sp_cancel_supplier_order 431 431 0 0.00 0 24916 1548 0 0
t$perf-norm:sp_cancel_write_off 1012 1012 0 0.00 0 4835 9 0 0
t$perf-norm:sp_client_order 21736 21736 0 0.00 0 9644 15 0 0
t$perf-norm:sp_customer_reserve 1078 1078 0 0.00 0 1424 6 0 0
t$perf-norm:sp_pay_from_customer 7267 7267 0 0.00 0 12320 10 0 0
t$perf-norm:sp_pay_to_supplier 6034 6034 0 0.00 0 9650 14 0 0
t$perf-norm:sp_reserve_write_off 10288 10288 0 0.00 0 5694 12 0 0
t$perf-norm:sp_supplier_invoice 5706 5706 0 0.00 0 4499 16 0 0
t$perf-norm:sp_supplier_order 5685 5685 0 0.00 0 7531 18 0 0
t$perf-norm:srv_aggregate_perf_data 92 92 0 0.00 0 376 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 1914 53 0 0
t$perf-norm:srv_make_invnt_saldo 613 613 0 0.00 0 1617 26 0 0
t$perf-norm:srv_make_money_saldo 793 793 0 0.00 0 2219 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 5 4 0 0
v_add_invoice_to_stock 10842 10516 326 3.00 0 5188 17 0 326
v_all_customers 39450 39450 0 0.00 0 143 1 0 0
v_all_suppliers 31262 31262 0 0.00 0 143 1 0 0
v_all_wares 43472 43472 0 0.00 0 19 1 0 0
v_cancel_adding_invoice 890 890 0 0.00 0 414 7 0 0
v_cancel_client_order 3654 3654 0 0.00 0 3030 4 0 0
v_cancel_customer_prepayment 1442 1442 0 0.00 0 5008 11 0 0
v_cancel_customer_reserve 1408 1408 0 0.00 0 3817 7 0 0
v_cancel_payment_to_supplier 840 840 0 0.00 0 433 5 0 0
v_cancel_supplier_invoice 902 876 26 2.88 0 365 13 0 26
v_cancel_supplier_order 862 862 0 0.00 0 3325 12 0 0
v_cancel_write_off 2024 2024 0 0.00 0 5100 19 0 0
v_max_id_clo_ord 5685 5685 0 0.00 0 949 1 0 0
v_max_id_clo_res 1078 1078 0 0.00 0 2121 13 0 0
v_max_id_ord_sup 5706 5706 0 0.00 0 3021 5 0 0
v_max_non_paid_invoice 1782 1782 0 0.00 0 1185 5 0 0
v_max_non_paid_realizn 3865 3865 0 0.00 0 3554 5 0 0
v_min_id_clo_ord 5685 5685 0 0.00 0 1907 5 0 0
v_min_id_clo_res 1078 1078 0 0.00 0 3586 14 0 0
v_min_id_ord_sup 5706 5706 0 0.00 0 8222 9 0 0
v_min_non_paid_invoice 6022 6022 0 0.00 0 8979 23 0 0
v_min_non_paid_realizn 7259 7259 0 0.00 0 7881 29 0 0
v_our_firm 10810 10810 0 0.00 0 15 1 0 0
v_reserve_write_off 20562 20562 0 0.00 0 3596 6 0 0
x_lock_depdocs_on_canc_invoice 12389 12389 0 0.00 0 13459 93 0 0
x_lock_depdocs_on_canc_sup_ord 431 431 0 0.00 0 22881 393 0 0
x_make_qstorno_1000_1200 5685 5685 0 0.00 1 31651 1113 0 0
x_make_qstorno_1200_2000 5706 5706 0 0.00 3 96944 3214 0 0
x_make_qstorno_2100_3300 484967 484967 0 0.00 0 91787 161 0 0

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

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
20:08:40 3 8192000000 8797541376 8808267776 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:41 16270 8192000000 9892329680 10205704192 982290272 10.706 100 85 170 0 1010776512 6150880 22335360
20:11:01 18855 8192000000 9947282112 10268413952 1028522784 11.154 100 85 170 0 1059094192 8416848 22154560
20:12:27 16523 8192000000 9968729152 10297503744 1048726480 11.348 100 77 154 0 1077989504 9664944 19598080
20:13:46 10081 8192000000 10020856128 10365222912 1106097712 11.895 100 79 156 1 1134727920 10122592 18507616
20:15:03 8000 8192000000 10063366672 10419937280 1156829712 12.374 100 75 150 0 1185815728 11477888 17508128
20:16:17 4306 8192000000 10116290080 10481987584 1199808352 12.775 100 74 144 2 1228489920 12738048 15943520
20:17:23 2109 8192000000 10151765744 10527674368 1242539632 13.170 100 78 153 0 1271506672 12181520 16785520
20:18:26 1834 8192000000 10196397248 10575462400 1274371344 13.462 100 88 175 1 1307173664 13726272 19076048
20:19:30 1141 8192000000 10205862192 10589667328 1295298544 13.652 100 87 171 1 1328072960 14362272 18412144
20:20:32 881 8192000000 10219687040 10606419968 1310383648 13.790 100 80 159 2 1339284336 11960448 16940240
20:21:33 5460 8192000000 10199916112 10611408896 1315840192 13.839 100 52 104 0 1334123472 5716192 12567088
20:22:39 7297 8192000000 10205076816 10621169664 1319644848 13.873 100 20 40 0 1330517040 6078384 4793808
20:23:51 14611 8192000000 10214016256 10632052736 1328588496 13.954 100 16 32 1 1342178944 9648064 3942384
20:25:06 7282 8192000000 10217011968 10641145856 1336130336 14.023 100 13 26 2 1344539632 5752352 2656944
20:26:18 34659 8192000000 10250772896 10653532160 1337859888 14.038 100 62 124 0 1358541232 6594992 14086352
20:27:53 7527 8192000000 10219807360 10637651968 1322019136 13.895 99 20 40 0 1332489424 6012864 4457424
20:29:01 8253 8192000000 10167738528 10570248192 1250545600 13.243 97 26 52 1 1261274176 4933936 5794640
20:30:11 34861 8192000000 10168651888 10575921152 1182488560 12.613 91 17 34 0 1195199664 9000912 3710192
20:31:46 6308 8192000000 10149383936 10543804416 1246835648 13.209 99 25 51 1 1258147936 4724240 6588048
20:32:54 3642 8192000000 10145428192 10543058944 1231469216 13.068 98 31 62 0 1245636208 6878656 7288336
20:33:59 61090 8192000000 10172181920 10582249472 1281012192 13.522 100 32 63 0 1293324704 5298272 7014240
20:36:00 9819 8192000000 10197825328 10609106944 1303551936 13.728 100 20 40 0 1314566208 6312464 4701808
20:37:10 8613 8192000000 10205065696 10615189504 1310654448 13.792 100 27 52 2 1322422480 5312960 6455072
20:38:19 19767 8192000000 10231452240 10637901824 1330471744 13.971 100 20 40 0 1339591088 4716816 4402528
20:39:43 68419 8192000000 10247027808 10661007360 1343433648 14.088 100 49 96 0 1362934736 8804512 10696576
20:42:14 10296 8192000000 10278128768 10695430144 1366722448 14.298 100 48 95 1 1390719600 13578736 10418416
20:43:31 32256 8192000000 10290871696 10713710592 1381960048 14.434 100 42 84 0 1406143552 14240320 9943184
20:45:07 6387 8192000000 10297710512 10721996800 1384649120 14.458 100 47 93 0 1411806048 15838672 11318256
20:46:23 4552 8192000000 10308680240 10726932480 1387795392 14.486 100 59 117 1 1422120320 20855952 13468976
20:47:32 4028 8192000000 10316972576 10728448000 1384090608 14.453 100 72 144 1 1425573600 24650224 16832768
20:48:48 12907 8192000000 10308935392 10729377792 1380194816 14.418 99 54 108 1 1419886336 26575376 13116144
20:50:06 4004 8192000000 10311343152 10734952448 1391409552 14.518 100 48 95 0 1425303008 22313504 11579952
20:51:18 5802 8192000000 10320097872 10732675072 1365836656 14.290 98 65 128 0 1409708880 29068288 14803936
20:52:32 1581 8192000000 10313648864 10713927680 1358153856 14.221 99 85 168 1 1409864256 31274608 20435792
20:53:39 2244 8192000000 10306892128 10716246016 1358163360 14.221 99 69 139 0 1409982208 34948944 16869904
20:54:48 2874 8192000000 10318603216 10729885696 1370384736 14.330 100 68 134 0 1417217024 30147584 16684704
20:55:58 6915 8192000000 10295015776 10711629824 1356165792 14.203 100 52 103 1 1403753520 34603168 12984560
20:57:07 4253 8192000000 10280217344 10692403200 1331855664 13.984 99 47 92 0 1380184016 35500896 12827456
20:58:26 12234 8192000000 10300784144 10720301056 1348054224 14.130 99 42 84 1 1399658432 40092944 11511264
20:59:46 22840 8192000000 10311096912 10745049088 1372231648 14.347 100 32 63 1 1421762576 40903520 8627408
21:01:20 18874 8192000000 10302538528 10734452736 1373146832 14.355 100 29 58 0 1411390256 30657920 7585504
21:03:03 25432 8192000000 10304408064 10738860032 1384429696 14.456 100 19 37 0 1411084032 21820720 4833616
21:04:31 25484 8192000000 10294106528 10727481344 1365094272 14.283 100 20 40 0 1396769568 26287184 5388112
21:05:59 18828 8192000000 10292735168 10737381376 1366850688 14.299 100 21 42 0 1403644752 31260512 5533552
21:07:19 69942 8192000000 10289044704 10723315712 1348857920 14.137 98 22 44 0 1378001808 23153696 5990192
21:09:41 91082 8192000000 10296059664 10756112384 1359619472 14.234 98 5 10 0 1376334480 15991392 723616
21:12:16 79325 8192000000 10280501184 10719719424 1342280400 14.078 97 8 16 0 1353684544 9914672 1489472
21:14:36 119522 8192000000 10317459808 10774392832 1389760784 14.504 100 31 62 0 1412140336 15922512 6457040
21:17:54 10324 8192000000 10316605264 10757373952 1382744448 14.441 99 50 99 1 1406792496 12357520 11690528
21:19:06 51290 8192000000 10325982496 10757193728 1393713392 14.539 100 29 58 0 1411868992 11368848 6786752
21:20:59 54064 8192000000 10314789984 10769989632 1403266432 14.624 100 32 63 1 1424197040 13126720 7803888
21:23:01 31411 8192000000 10322451200 10771570688 1403266064 14.624 100 51 100 3 1433288992 17768768 12254160
21:24:37 34627 8192000000 10290655648 10754134016 1374928736 14.371 98 17 35 0 1386564912 7703776 3932400
21:26:13 31621 8192000000 10281253664 10748411904 1372605568 14.350 99 7 14 0 1384453168 10301808 1545792
21:27:53 63036 8192000000 10271054528 10740047872 1354774864 14.190 98 27 54 1 1371847680 10338768 6734048
21:30:09 80706 8192000000 10295086016 10764546048 1382663328 14.440 99 15 30 0 1405004736 19352864 2988544
21:32:33 79851 8192000000 10301370528 10779230208 1404963760 14.639 100 14 28 0 1418639552 10387232 3288560
21:35:00 24719 8192000000 10297470944 10758205440 1382760112 14.441 99 13 26 1 1399242528 13171568 3310848
21:36:25 43878 8192000000 10292365376 10760396800 1368451920 14.313 98 15 31 0 1387138432 15185520 3500992
21:38:09 60399 8192000000 10289746064 10750361600 1344553280 14.098 96 12 24 0 1365558480 18231184 2774016
21:40:33 58055 8192000000 10273096192 10738626560 1340562880 14.062 96 14 29 1 1357770176 13945968 3261328
21:42:32 51706 8192000000 10262654752 10703245312 1277399040 13.489 93 33 65 1 1295802992 10945200 7458752
21:44:31 37819 8192000000 10268679168 10708930560 1317312656 13.852 97 26 52 0 1340955200 17436816 6205728
21:46:55 44722 8192000000 10266808224 10708492288 1321540320 13.891 97 27 54 0 1341320912 13684256 6096336
21:48:40 128078 8192000000 10272324944 10717044736 1336272000 14.024 98 11 22 0 1350644704 12067728 2304976
21:51:50 55243 8192000000 10272964768 10715754496 1327081216 13.941 97 32 64 1 1345251872 11080512 7090144
21:53:47 20101 8192000000 10282934912 10712064000 1353992352 14.183 99 62 124 1 1378605312 9930560 14682400
21:55:36 39053 8192000000 10272402560 10719567872 1352907968 14.174 99 10 20 0 1361862752 6768944 2185840
21:57:16 33151 8192000000 10274935376 10727063552 1362263136 14.258 99 25 51 1 1379357344 11906416 5187792
21:58:58 63783 8192000000 10258526208 10719105024 1334278976 14.006 97 11 22 0 1348736832 11824304 2633552
22:01:03 43404 8192000000 10256510832 10720403456 1364519920 14.278 100 26 46 1 1378501696 8773984 5207792
22:02:49 81045 8192000000 10265785072 10728497152 1366861472 14.299 99 14 28 0 1381427184 11390288 3175424
22:05:10 25317 8192000000 10282661680 10724597760 1363945008 14.273 99 21 39 0 1374225664 6137888 4142768
22:06:36 36857 8192000000 10272595232 10734448640 1349836976 14.146 98 39 75 2 1371596752 13230128 8529648
22:08:16 23683 8192000000 10296163104 10743177216 1385457872 14.465 100 24 48 1 1400487440 10271920 4757648
22:09:43 49626 8192000000 10293250672 10758864896 1372934880 14.353 99 19 37 0 1396017520 19024944 4057696
22:11:51 49794 8192000000 10308649104 10752192512 1370971152 14.336 98 31 62 0 1392036160 14360624 6704384
22:13:46 92724 8192000000 10309243712 10771451904 1369788480 14.325 98 13 26 0 1385375952 12673680 2913792
22:16:19 86654 8192000000 10293965536 10753372160 1335707968 14.019 96 22 44 1 1354979664 14636768 4634928
22:18:47 62163 8192000000 10277379888 10724519936 1333242672 13.996 97 31 58 0 1351465792 12313360 5909760
22:20:52 79502 8192000000 10295093248 10740498432 1351169616 14.158 98 31 63 0 1368695424 10522368 7003440
22:23:17 55020 8192000000 10275610464 10742083584 1351174464 14.158 98 20 38 0 1375570208 19552288 4843456
22:25:12 47198 8192000000 10267468192 10739326976 1332411184 13.989 97 9 18 0 1356662336 21950064 2301088
22:28:05 80716 8192000000 10267398768 10731511808 1325721248 13.928 97 12 24 0 1342402208 13779216 2901744
22:30:26 71466 8192000000 10254008800 10697715712 1267604496 13.400 93 23 46 0 1285571920 12532016 5435408
22:32:38 72199 8192000000 10251465392 10713214976 1315219072 13.833 97 16 32 0 1334271728 15005872 4046784
22:34:54 12372 8192000000 10287997056 10716774400 1355432320 14.196 100 53 104 0 1376687984 9195552 12060112
22:36:11 27675 8192000000 10264477616 10702946304 1325059760 13.922 98 37 70 1 1344197008 11245504 7891744
22:37:45 62098 8192000000 9493055168 9819254784 628278176 7.123 46 9 18 0 638083200 8151456 1653568

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-02-2023 22:39:41 to 10-02-2023 22:39:57.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 163 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

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

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          247401
MON$OLDEST_ACTIVE               248423
MON$OLDEST_SNAPSHOT             248423
MON$NEXT_TRANSACTION            248423
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-03 16:41:55.3470
MON$PAGES                       2048096
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33662 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33662 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33662 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33662 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.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 ---
:60: 
:61: mscs-test-161.budzdorov.ru    Fri Feb 10 22:38:51 2023
:62:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:63: 
:64: 
:65: mscs-test-161.budzdorov.ru    Fri Feb 10 22:38:51 2023
:66:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:67: 
:68: 
:69: mscs-test-161.budzdorov.ru    Fri Feb 10 22:38:51 2023
:70:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:71: 
:72: 
:73: mscs-test-161.budzdorov.ru    Fri Feb 10 22:38:51 2023
:74:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:75: 
:76: 
:77: mscs-test-161.budzdorov.ru    Fri Feb 10 22:38:51 2023
:78:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:79: 
:80: 
:81: mscs-test-161.budzdorov.ru    Fri Feb 10 22:38:51 2023
:82:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:83: 
:84: 
:85: mscs-test-161.budzdorov.ru    Fri Feb 10 22:38:51 2023
:86:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:87: 
:88: 
:89: mscs-test-161.budzdorov.ru    Fri Feb 10 22:38:51 2023
:90:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:91: 
:92: 
:93: mscs-test-161.budzdorov.ru    Fri Feb 10 22:38:51 2023
:94:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:95: 
:96: 
:97: mscs-test-161.budzdorov.ru    Fri Feb 10 22:38:51 2023
:98:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:99: 
:100: 
:101: mscs-test-161.budzdorov.ru    Fri Feb 10 22:38:51 2023
:102:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:103: 
:104: 
:105: mscs-test-161.budzdorov.ru    Fri Feb 10 22:38:51 2023
:106:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:107: 
:108: 
:109: mscs-test-161.budzdorov.ru    Fri Feb 10 22:38:51 2023
:110:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:111: 
:112: 
:113: mscs-test-161.budzdorov.ru    Fri Feb 10 22:38:51 2023
:114:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:115: 
:116: 
:117: mscs-test-161.budzdorov.ru    Fri Feb 10 22:38:51 2023
:118:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:119: 
:120: 
:121: mscs-test-161.budzdorov.ru    Fri Feb 10 22:38:51 2023
:122:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:123: 
:124: 
:125: mscs-test-161.budzdorov.ru    Fri Feb 10 22:38:51 2023
:126:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:127: 
:128: 
:129: mscs-test-161.budzdorov.ru    Fri Feb 10 22:38:51 2023
:130:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:131: 
:132: 
:133: mscs-test-161.budzdorov.ru    Fri Feb 10 22:38:51 2023
:134:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:135: 
--- end of diff output ---

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

10.02.23 22:40:01. There are 3 logs in /var/tmp/logs-oltp30 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-10 22:40:03 No severe PSQL-related problems occured
10.02.23 22:40:05. End of report.