Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 01.03.2023 19:40
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 15186468 kB
MemAvailable 30458544 kB
Buffers 0 kB
Cached 16543228 kB
SwapCached 3084 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-03-01 19:38:37
DTS_END                         2023-03-01 19:38:37.5430
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_ORDER(38:5) ==> X_MAKE_QSTORNO_1000_1200(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/46626
TRN_ID                          247911
ATT_ID                          648
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: 9489.69 19368 1138763
customer order: creation 183.49 326 22019
customer order: refuse 15.34 334 1841
order to supplier: creation 47.11 1192 5653
order to supplier: removal 3.72 95424 446
invoice (draft): creation 49.13 3157 5896
invoice (draft): removal 53.66 752 6439
invoice accept: apply 42.99 17100 5159
invoice accept: cancel 48.46 744 5815
customer reserve: creation 4004.93 167 480592
customer reserve: removal 4728.46 58 567415
realization accept: apply 89.62 175 10754
realization accept: cancel 86.30 111 10356
payment from customer: creation 61.32 96 7358
payment from customer: removal 6.00 72 720
payment to supplier: creation 52.58 105 6310
payment to supplier: removal 3.99 71 479
service: total inventory turnovers 5.09 1055 611
service: total monetary turnovers 6.67 26 800
service: aggregate perf. data 0.80 20887 96
service: refresh index statistics 0.03 245504 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 01-03-2023 19:40:38 to 01-03-2023 19:40:41.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5768
TEST_TIME 2 5055
TEST_TIME 3 5023
TEST_TIME 4 4878
TEST_TIME 5 4970
TEST_TIME 6 5176
TEST_TIME 7 5320
TEST_TIME 8 5502
TEST_TIME 9 6665
TEST_TIME 10 6654
TEST_TIME 11 6805
TEST_TIME 12 6866
TEST_TIME 13 6771
TEST_TIME 14 6697
TEST_TIME 15 6822
TEST_TIME 16 7200
TEST_TIME 17 7381
TEST_TIME 18 7642
TEST_TIME 19 7820
TEST_TIME 20 7985
TEST_TIME 21 8214
TEST_TIME 22 8444
TEST_TIME 23 9364
TEST_TIME 24 9613
TEST_TIME 25 9936
TEST_TIME 26 10136
TEST_TIME 27 10886
TEST_TIME 28 11105
TEST_TIME 29 11143
TEST_TIME 30 10962
TEST_TIME 31 10906
TEST_TIME 32 10651
TEST_TIME 33 11006
TEST_TIME 34 11050
TEST_TIME 35 11062
TEST_TIME 36 10879
TEST_TIME 37 10746
TEST_TIME 38 10551
TEST_TIME 39 10478
TEST_TIME 40 10483
TEST_TIME 41 10270
TEST_TIME 42 10271
TEST_TIME 43 10314
TEST_TIME 44 10190
TEST_TIME 45 10304
TEST_TIME 46 10264
TEST_TIME 47 10151
TEST_TIME 48 10146
TEST_TIME 49 10111
TEST_TIME 50 10119
TEST_TIME 51 10188
TEST_TIME 52 10225
TEST_TIME 53 10184
TEST_TIME 54 10225
TEST_TIME 55 10203
TEST_TIME 56 10210
TEST_TIME 57 10211
TEST_TIME 58 10259
TEST_TIME 59 10244
TEST_TIME 60 10302
TEST_TIME 61 10374
TEST_TIME 62 10291
TEST_TIME 63 10374
TEST_TIME 64 10233
TEST_TIME 65 10207
TEST_TIME 66 10145
TEST_TIME 67 10123
TEST_TIME 68 10054
TEST_TIME 69 10000
TEST_TIME 70 9997
TEST_TIME 71 9963
TEST_TIME 72 9889
TEST_TIME 73 9888
TEST_TIME 74 9817
TEST_TIME 75 9773
TEST_TIME 76 9700
TEST_TIME 77 9632
TEST_TIME 78 9705
TEST_TIME 79 9714
TEST_TIME 80 9707
TEST_TIME 81 9710
TEST_TIME 82 9678
TEST_TIME 83 9745
TEST_TIME 84 9732
TEST_TIME 85 9763
TEST_TIME 86 9822
TEST_TIME 87 9803
TEST_TIME 88 9792
TEST_TIME 89 9909
TEST_TIME 90 9873
TEST_TIME 91 9889
TEST_TIME 92 9848
TEST_TIME 93 9789
TEST_TIME 94 9736
TEST_TIME 95 9746
TEST_TIME 96 9676
TEST_TIME 97 9659
TEST_TIME 98 9653
TEST_TIME 99 9607
TEST_TIME 100 9594
TEST_TIME 101 9551
TEST_TIME 102 9570
TEST_TIME 103 9586
TEST_TIME 104 9572
TEST_TIME 105 9559
TEST_TIME 106 9558
TEST_TIME 107 9606
TEST_TIME 108 9625
TEST_TIME 109 9641
TEST_TIME 110 9625
TEST_TIME 111 9648
TEST_TIME 112 9681
TEST_TIME 113 9720
TEST_TIME 114 9701
TEST_TIME 115 9632
TEST_TIME 116 9627
TEST_TIME 117 9590
TEST_TIME 118 9575
TEST_TIME 119 9523
TEST_TIME 120 9493

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 01-03-2023 19:40:42 to 01-03-2023 19:40:45.

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 1583607 1583607 0 0.00 0 12693 6 0 0
doc_list_biud 1583607 1583607 0 0.00 0 40614 27 0 0
sp_add_invoice_to_stock 5286 5159 127 2.40 39 169513 17101 0 127
sp_cancel_adding_invoice 5815 5815 0 0.00 3 69469 744 0 0
sp_cancel_client_order 1841 1841 0 0.00 4 13168 335 0 0
sp_cancel_customer_reserve 567415 567415 0 0.00 0 40616 59 0 0
sp_cancel_pay_from_customer 720 720 0 0.00 3 3915 72 0 0
sp_cancel_pay_to_supplier 479 479 0 0.00 2 2333 71 0 0
sp_cancel_supplier_invoice 6446 6439 7 0.10 1 14037 753 0 7
sp_cancel_supplier_order 446 446 0 0.00 6 1041816 95424 0 0
sp_cancel_write_off 10356 10356 0 0.00 1 11122 112 0 0
sp_client_order 22019 22019 0 0.00 3 27443 327 0 0
sp_customer_reserve 480592 480592 0 0.00 0 40746 168 0 0
sp_fill_shopping_cart 33563 33563 0 0.00 1 12518 36 0 0
sp_fill_shopping_cart_clo_res 480587 480587 0 0.00 0 19321 13 0 0
sp_get_clo_for_invoice 5145 5145 0 0.00 0 31454 299 0 0
sp_kill_cost_storno 17370 17370 0 0.00 0 7132 53 0 0
sp_kill_qstorno_ret_qs2qd 1152071 1152071 0 0.00 0 40610 32 0 0
sp_kill_qty_storno 608206 608206 0 0.00 0 40614 68 0 0
sp_lock_selected_doc 21996 21996 0 0.00 0 7796 6 0 0
sp_make_cost_storno 29562 29562 0 0.00 0 9113 44 0 0
sp_multiply_rows_for_pdistr 29562 29562 0 0.00 0 8336 28 0 0
sp_multiply_rows_for_qdistr 22019 22019 0 0.00 0 16685 150 0 0
sp_pay_from_customer 7358 7358 0 0.00 4 9748 97 0 0
sp_pay_to_supplier 6310 6310 0 0.00 4 14384 106 0 0
sp_payment_common 13668 13668 0 0.00 1 14374 25 0 0
sp_qd_handle_on_cancel_clo 1841 1841 0 0.00 0 13110 302 0 0
sp_qd_handle_on_invoice_upd_sts 10955 10955 0 0.00 0 20309 304 0 0
sp_qd_handle_on_reserve_upd_sts 10754 10754 0 0.00 0 21330 67 0 0
sp_reserve_write_off 10754 10754 0 0.00 6 21350 176 0 0
sp_supplier_invoice 5896 5896 0 0.00 5 97932 3158 0 0
sp_supplier_order 5653 5653 0 0.00 5 69279 1193 0 0
srv_aggregate_perf_data 96 96 0 0.00 2677 220204 20888 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 2076 111069 42263 0 0
srv_find_qd_qs_mism 1088478 1088478 0 0.00 0 11007 6 0 0
srv_make_invnt_saldo 611 611 0 0.00 35 103261 1055 0 0
srv_make_money_saldo 800 800 0 0.00 0 711 27 0 0
srv_recalc_idx_stat 4 4 0 0.00 145942 380406 245505 0 0
t$perf-abend:sp_add_invoice_to_stock 127 127 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5159 5159 0 0.00 0 12061 171 0 0
t$perf-norm:sp_cancel_adding_invoice 460 460 0 0.00 0 6059 110 0 0
t$perf-norm:sp_cancel_client_order 1841 1841 0 0.00 0 4875 20 0 0
t$perf-norm:sp_cancel_customer_reserve 718 718 0 0.00 0 1632 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 720 720 0 0.00 0 1683 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 479 479 0 0.00 0 1131 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 393 393 0 0.00 0 1709 18 0 0
t$perf-norm:sp_cancel_supplier_order 446 446 0 0.00 0 31797 1477 0 0
t$perf-norm:sp_cancel_write_off 1045 1045 0 0.00 0 7336 10 0 0
t$perf-norm:sp_client_order 22019 22019 0 0.00 0 8557 17 0 0
t$perf-norm:sp_customer_reserve 1092 1092 0 0.00 0 6604 24 0 0
t$perf-norm:sp_pay_from_customer 7358 7358 0 0.00 0 5561 10 0 0
t$perf-norm:sp_pay_to_supplier 6310 6310 0 0.00 0 7282 13 0 0
t$perf-norm:sp_reserve_write_off 10754 10754 0 0.00 0 6805 10 0 0
t$perf-norm:sp_supplier_invoice 5896 5896 0 0.00 0 6049 26 0 0
t$perf-norm:sp_supplier_order 5653 5653 0 0.00 0 5600 20 0 0
t$perf-norm:srv_aggregate_perf_data 96 96 0 0.00 0 1227 25 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 181 6 0 0
t$perf-norm:srv_make_invnt_saldo 611 611 0 0.00 0 587 16 0 0
t$perf-norm:srv_make_money_saldo 800 800 0 0.00 0 557 10 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 10534 10280 254 2.41 0 4863 17 0 254
v_all_customers 39832 39832 0 0.00 0 19 1 0 0
v_all_suppliers 32348 32348 0 0.00 0 20 1 0 0
v_all_wares 44038 44038 0 0.00 0 24 1 0 0
v_cancel_adding_invoice 920 920 0 0.00 0 1948 12 0 0
v_cancel_client_order 3682 3682 0 0.00 0 3867 6 0 0
v_cancel_customer_prepayment 1440 1440 0 0.00 0 3834 13 0 0
v_cancel_customer_reserve 1436 1436 0 0.00 0 1601 8 0 0
v_cancel_payment_to_supplier 958 958 0 0.00 0 951 6 0 0
v_cancel_supplier_invoice 800 786 14 1.75 0 1889 20 0 14
v_cancel_supplier_order 892 892 0 0.00 0 1939 7 0 0
v_cancel_write_off 2090 2090 0 0.00 0 3876 19 0 0
v_max_id_clo_ord 5653 5653 0 0.00 0 218 1 0 0
v_max_id_clo_res 1091 1091 0 0.00 0 2435 20 0 0
v_max_id_ord_sup 5891 5891 0 0.00 0 5980 5 0 0
v_max_non_paid_invoice 1680 1680 0 0.00 0 961 3 0 0
v_max_non_paid_realizn 4005 4005 0 0.00 0 1814 4 0 0
v_min_id_clo_ord 5653 5653 0 0.00 0 1893 5 0 0
v_min_id_clo_res 1091 1091 0 0.00 0 553 2 0 0
v_min_id_ord_sup 5891 5891 0 0.00 0 4789 8 0 0
v_min_non_paid_invoice 6310 6310 0 0.00 0 14369 17 0 0
v_min_non_paid_realizn 7358 7358 0 0.00 0 6903 16 0 0
v_our_firm 10912 10912 0 0.00 0 16 1 0 0
v_reserve_write_off 21508 21508 0 0.00 0 4038 6 0 0
x_lock_depdocs_on_canc_invoice 11861 11861 0 0.00 0 34157 133 0 0
x_lock_depdocs_on_canc_sup_ord 446 446 0 0.00 0 7071 302 0 0
x_make_qstorno_1000_1200 5653 5653 0 0.00 1 69118 1123 0 0
x_make_qstorno_1200_2000 5896 5896 0 0.00 2 97899 3029 0 0
x_make_qstorno_2100_3300 480592 480592 0 0.00 0 40745 152 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 01-03-2023 19:40:46 to 01-03-2023 19:40:51.

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
17:08:41 3 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:42 14309 8192000000 9892444576 10201722880 977690672 10.662 100 86 172 0 1006382720 5529008 23163040
17:11:05 18610 8192000000 9944358352 10265661440 1023056384 11.102 100 87 174 0 1052627984 7239776 22331824
17:12:29 14990 8192000000 9968876352 10294878208 1050612256 11.367 100 92 183 1 1083685424 9094944 23978224
17:13:59 13780 8192000000 10039831328 10387169280 1125460160 12.079 100 79 158 0 1154378656 10152400 18766096
17:15:17 5228 8192000000 10083734928 10448510976 1176520176 12.558 100 77 153 0 1205899744 11996496 17383072
17:16:23 3951 8192000000 10132717632 10502377472 1219869184 12.960 100 77 153 0 1250797344 13742320 17185840
17:17:33 2296 8192000000 10157157072 10532110336 1248078528 13.221 100 82 164 1 1279109392 13198304 17832560
17:18:39 8557 8192000000 10189085264 10566283264 1272755744 13.447 100 82 163 0 1305408256 15637888 17014624
17:19:49 1882 8192000000 10204501312 10590765056 1298876144 13.685 100 74 146 2 1327557296 12212608 16468544
17:20:52 5828 8192000000 10194312016 10600054784 1303013824 13.723 100 43 87 0 1320868496 8246144 9608528
17:22:00 6488 8192000000 10201354752 10615132160 1315550064 13.836 100 27 55 1 1328842272 7547984 5744224
17:23:13 8046 8192000000 10203590144 10622300160 1322528368 13.900 100 19 39 0 1332763280 6386576 3848336
17:24:24 50910 8192000000 10227880416 10638217216 1326205152 13.933 100 29 58 0 1337502416 4934576 6362688
17:26:18 4287 8192000000 10230317232 10644250624 1326310384 13.934 100 45 89 1 1343857456 7726960 9820112
17:27:23 6334 8192000000 10215726992 10637479936 1327119616 13.941 100 31 62 1 1340591920 6372592 7099712
17:28:31 6373 8192000000 10206877728 10623234048 1305037264 13.741 99 20 40 2 1315529280 6115536 4376480
17:29:38 4037 8192000000 10202658192 10620637184 1311510144 13.800 100 41 81 1 1327083440 7158928 8414368
17:30:44 6756 8192000000 10137557152 10539786240 1216986032 12.934 96 30 60 0 1229903088 6258416 6658640
17:31:52 5900 8192000000 10143756976 10545303552 1252902560 13.265 100 21 42 0 1262890560 5313488 4674512
17:33:00 62063 8192000000 10157779408 10564788224 1224793616 13.006 96 31 62 0 1237989488 5876800 7319072
17:35:08 4782 8192000000 10181356800 10588053504 1288438352 13.590 100 35 69 1 1304015808 8184096 7393360
17:36:15 8440 8192000000 10191537008 10606792704 1302606928 13.719 100 23 46 1 1313249344 5829696 4812720
17:37:23 5467 8192000000 10209903088 10618417152 1311754400 13.802 100 26 52 0 1322976064 5317776 5903888
17:38:29 37957 8192000000 10230022816 10640134144 1327521248 13.945 100 58 112 2 1346953664 6826624 12605792
17:40:24 24760 8192000000 10259787648 10671939584 1353328208 14.177 100 46 92 0 1374388176 9851376 11208592
17:42:02 44371 8192000000 10277935232 10700025856 1373788336 14.361 100 36 72 1 1393294320 10670688 8835296
17:43:52 17799 8192000000 10290472096 10716815360 1383687696 14.450 100 42 84 0 1407932576 14020304 10224576
17:45:17 11162 8192000000 10313302608 10731503616 1395619184 14.556 100 58 112 1 1424089584 15579408 12890992
17:46:31 3958 8192000000 10319909536 10739896320 1394188608 14.543 100 57 113 0 1428199216 21264784 12745824
17:47:44 7139 8192000000 10344628848 10772684800 1414745312 14.726 100 49 97 0 1454241600 28535616 10960672
17:48:55 5402 8192000000 10356195520 10787708928 1430005168 14.861 100 54 106 0 1467940320 25354864 12580288
17:50:05 5022 8192000000 10356446448 10780540928 1412930560 14.710 99 70 137 1 1455239648 25571696 16737392
17:51:13 4301 8192000000 10369221936 10792697856 1427305312 14.837 100 59 116 0 1471750352 30188048 14256992
17:52:26 2954 8192000000 10355000896 10768470016 1410416912 14.688 99 63 121 1 1456530656 31094736 15019008
17:53:34 2152 8192000000 10358344336 10761490432 1389756416 14.504 98 87 172 0 1450734176 39165648 21812112
17:54:42 2075 8192000000 10358855968 10767695872 1400164944 14.596 99 74 146 0 1457752816 38497504 19090368
17:55:54 4077 8192000000 10374233776 10781839360 1409681264 14.681 100 80 161 0 1472816304 42907488 20227552
17:57:04 7241 8192000000 10358161360 10783801344 1405688192 14.646 100 45 88 3 1464055120 45890800 12476128
17:58:16 11089 8192000000 10369640400 10789662720 1411356480 14.696 100 55 107 0 1475400048 50056976 13986592
17:59:32 9369 8192000000 10326606704 10746249216 1355911248 14.201 98 37 74 0 1415427040 48774960 10740832
18:00:44 12573 8192000000 10351290112 10772025344 1385147952 14.463 100 44 89 0 1460992720 63040976 12803792
18:02:02 9807 8192000000 10345152928 10778624000 1401758240 14.611 100 41 80 1 1458053392 45003968 11291184
18:03:26 18009 8192000000 10321429328 10757857280 1370529552 14.332 99 27 54 0 1421315504 42955376 7830576
18:05:44 73080 8192000000 10269883952 10714828800 1339521728 14.053 98 10 20 0 1356190160 14740752 1927680
18:08:17 81022 8192000000 10294599184 10744594432 1364383824 14.277 99 11 20 1 1381279504 14719712 2175968
18:10:44 18913 8192000000 10320310320 10752233472 1382332320 14.437 100 41 77 1 1411806736 20797264 8677152
18:12:20 55267 8192000000 10300899808 10751389696 1385107792 14.462 100 5 10 0 1399179264 12790128 1281344
18:14:28 18676 8192000000 10309479616 10739503104 1382077824 14.435 100 43 88 2 1398678384 6485488 10115072
18:15:50 100533 8192000000 10294870176 10751938560 1391903728 14.523 100 40 80 0 1412785616 11920016 8961872
18:18:31 39572 8192000000 10298134224 10753503232 1388432192 14.492 100 25 49 0 1405957760 12019968 5505600
18:20:47 40345 8192000000 10315839968 10773860352 1404787088 14.638 100 24 47 1 1421720992 11801024 5132880
18:22:28 14457 8192000000 10348944848 10789236736 1415242720 14.730 100 62 124 1 1444823440 14198944 15381776
18:23:46 47720 8192000000 10316888096 10783383552 1410757888 14.691 100 14 26 0 1428264176 14574128 2932160
18:25:34 66972 8192000000 10318128592 10785169408 1372916336 14.353 97 10 21 0 1393416896 18192464 2308096
18:27:57 39116 8192000000 10313569072 10782920704 1395482976 14.555 99 15 30 0 1416160096 17023456 3653664
18:29:46 48385 8192000000 10309734192 10772766720 1391806432 14.522 99 14 28 1 1419117312 24110080 3200800
18:31:35 18150 8192000000 10308280128 10754244608 1358855232 14.227 97 50 98 1 1382778112 12410720 11512160
18:32:56 23421 8192000000 10299536992 10747699200 1355624864 14.198 97 35 70 3 1371507440 8068592 7813984
18:34:22 59547 8192000000 10284662912 10738741248 1306594512 13.755 94 20 40 0 1320386816 9460448 4331856
18:36:23 90114 8192000000 10285546848 10735820800 1300058784 13.696 94 19 36 0 1314659536 10150240 4450512
18:38:53 103838 8192000000 10281624576 10728194048 1290231088 13.606 94 15 30 0 1307023568 13828832 2963648
18:41:50 111069 8192000000 10269603040 10717470720 1315849952 13.839 97 25 50 1 1330968032 9512944 5605136
18:44:42 53192 8192000000 10273129360 10735468544 1356579872 14.207 100 15 30 1 1384270512 23844960 3845680
18:46:36 58824 8192000000 10274004704 10737483776 1359214400 14.230 99 11 22 1 1373969344 12694064 2060880
18:48:35 57190 8192000000 10257102560 10714324992 1333888112 14.002 97 15 26 2 1344094768 6800096 3406560
18:50:33 54660 8192000000 10245027344 10697981952 1319054160 13.868 97 13 26 0 1332061376 9460752 3546464
18:52:34 8201 8192000000 10254616096 10703376384 1341026256 14.067 99 30 59 1 1356396656 8035920 7334480
18:53:45 51439 8192000000 10259836816 10711392256 1350978448 14.156 99 23 46 0 1367468576 10799616 5690512
18:55:40 73399 8192000000 10260254080 10732302336 1360994992 14.246 100 14 28 0 1381033376 15789840 4248544
18:57:56 45748 8192000000 10238371120 10699264000 1311987456 13.804 97 9 18 0 1322620496 9144640 1488400
18:59:49 23420 8192000000 10275444208 10721816576 1362104448 14.256 100 38 76 1 1378969888 8587232 8278208
19:01:21 41244 8192000000 10266281600 10720489472 1348293808 14.132 99 25 50 0 1367407504 13751104 5362592
19:03:06 44109 8192000000 10282154416 10733449216 1339087792 14.049 97 30 60 0 1357605136 12018752 6498592
19:04:54 42963 8192000000 10289996544 10739212288 1364537840 14.278 99 17 35 0 1378648480 9821216 4289424
19:06:38 29016 8192000000 10295179152 10741166080 1370546848 14.332 99 36 72 0 1390200912 11852464 7801600
19:08:08 65636 8192000000 10317791952 10759421952 1358725408 14.226 98 35 68 0 1380910656 14970592 7214656
19:10:14 110931 8192000000 10292794064 10752053248 1367967120 14.309 98 22 44 1 1384153216 11222288 4963808
19:13:06 25150 8192000000 10284904112 10755964928 1390156640 14.507 100 8 16 0 1400191504 8070352 1964512
19:15:00 64911 8192000000 10282769920 10751504384 1383220640 14.445 99 7 14 0 1393172448 8330112 1621696
19:17:08 86862 8192000000 10269160352 10745942016 1364489616 14.278 98 12 24 0 1377020192 10034704 2495872
19:19:37 23616 8192000000 10270538560 10730618880 1351699936 14.163 98 16 32 0 1362512000 6731808 4080256
19:21:00 78159 8192000000 10272750352 10742579200 1364935664 14.282 99 14 28 0 1379701984 11559376 3206944
19:23:26 73385 8192000000 10272804320 10730147840 1327850816 13.948 97 24 48 1 1352080832 18251648 5978368
19:25:42 57712 8192000000 10245678672 10705285120 1300573760 13.700 96 10 20 0 1315668976 12911952 2183264
19:27:44 43738 8192000000 10251344272 10698973184 1305826080 13.748 96 18 36 0 1320263568 10353120 4084368
19:29:34 102139 8192000000 10275219088 10731663360 1330468176 13.971 98 13 26 1 1354446144 20557952 3420016
19:32:26 88019 8192000000 10263467680 10713145344 1353351392 14.178 100 12 24 0 1366833712 11018032 2464288
19:35:03 40463 8192000000 10253610624 10711052288 1338328624 14.042 99 13 26 0 1354114272 12983424 2802224
19:37:06 53748 8192000000 10273081504 10722844672 1357623456 14.216 99 11 23 0 1372093056 11571728 2897872

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 01-03-2023 19:40:53 to 01-03-2023 19:41:08.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 127 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 01-03-2023 19:41:09 to 01-03-2023 19:41:11.

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          246722
MON$OLDEST_ACTIVE               248028
MON$OLDEST_SNAPSHOT             248028
MON$NEXT_TRANSACTION            248028
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                       2043232
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.33665 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33665 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33665 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33665 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 ---
:831: 
:832: mscs-test-161.budzdorov.ru    Wed Mar  1 19:38:43 2023
:833:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:834: 
:835: 
:836: mscs-test-161.budzdorov.ru    Wed Mar  1 19:38:43 2023
:837:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:838: 
:839: 
:840: mscs-test-161.budzdorov.ru    Wed Mar  1 19:38:50 2023
:841:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:842: 
:843: 
:844: mscs-test-161.budzdorov.ru    Wed Mar  1 19:38:50 2023
:845:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:846: 
:847: 
:848: mscs-test-161.budzdorov.ru    Wed Mar  1 19:38:51 2023
:849:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:850: 
--- end of diff output ---

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

01.03.23 19:41:12. 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-03-01 19:41:15 No severe PSQL-related problems occured
01.03.23 19:41:16. End of report.