Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 25.12.2022 16:39
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 15277760 kB
MemAvailable 30105008 kB
Buffers 0 kB
Cached 16457188 kB
SwapCached 3192 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 2022-12-25 16:38:35
DTS_END                         2022-12-25 16:38:35.4230
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/48272
TRN_ID                          238839
ATT_ID                          705
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: 9225.21 21086 1107025
customer order: creation 175.06 359 21007
customer order: refuse 14.91 368 1789
order to supplier: creation 46.22 1213 5546
order to supplier: removal 3.44 99559 413
invoice (draft): creation 47.30 3366 5676
invoice (draft): removal 52.11 800 6253
invoice accept: apply 42.19 17962 5063
invoice accept: cancel 47.01 849 5641
customer reserve: creation 3852.20 181 462264
customer reserve: removal 4644.97 58 557396
realization accept: apply 86.28 178 10353
realization accept: cancel 82.65 103 9918
payment from customer: creation 60.13 103 7216
payment from customer: removal 5.92 82 710
payment to supplier: creation 48.81 123 5857
payment to supplier: removal 3.61 60 433
service: total inventory turnovers 5.14 1245 617
service: total monetary turnovers 6.40 22 768
service: aggregate perf. data 0.84 21388 101
service: refresh index statistics 0.03 273703 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 25-12-2022 16:40:09 to 25-12-2022 16:40:12.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2476
TEST_TIME 2 3543
TEST_TIME 3 3842
TEST_TIME 4 4091
TEST_TIME 5 4658
TEST_TIME 6 4868
TEST_TIME 7 5250
TEST_TIME 8 5180
TEST_TIME 9 5274
TEST_TIME 10 5461
TEST_TIME 11 5538
TEST_TIME 12 5416
TEST_TIME 13 5665
TEST_TIME 14 6184
TEST_TIME 15 6145
TEST_TIME 16 6522
TEST_TIME 17 6787
TEST_TIME 18 7175
TEST_TIME 19 7278
TEST_TIME 20 7793
TEST_TIME 21 7954
TEST_TIME 22 8634
TEST_TIME 23 8772
TEST_TIME 24 9641
TEST_TIME 25 10153
TEST_TIME 26 10338
TEST_TIME 27 10358
TEST_TIME 28 10141
TEST_TIME 29 10204
TEST_TIME 30 10265
TEST_TIME 31 10387
TEST_TIME 32 10163
TEST_TIME 33 10050
TEST_TIME 34 9987
TEST_TIME 35 9815
TEST_TIME 36 9699
TEST_TIME 37 9834
TEST_TIME 38 9925
TEST_TIME 39 9857
TEST_TIME 40 9708
TEST_TIME 41 9709
TEST_TIME 42 9596
TEST_TIME 43 9678
TEST_TIME 44 9538
TEST_TIME 45 9405
TEST_TIME 46 9287
TEST_TIME 47 9169
TEST_TIME 48 9203
TEST_TIME 49 9199
TEST_TIME 50 9123
TEST_TIME 51 9122
TEST_TIME 52 9082
TEST_TIME 53 9042
TEST_TIME 54 8970
TEST_TIME 55 8870
TEST_TIME 56 8973
TEST_TIME 57 8873
TEST_TIME 58 8820
TEST_TIME 59 8884
TEST_TIME 60 8978
TEST_TIME 61 9000
TEST_TIME 62 9005
TEST_TIME 63 9125
TEST_TIME 64 9167
TEST_TIME 65 9225
TEST_TIME 66 9205
TEST_TIME 67 9191
TEST_TIME 68 9190
TEST_TIME 69 9201
TEST_TIME 70 9253
TEST_TIME 71 9346
TEST_TIME 72 9282
TEST_TIME 73 9188
TEST_TIME 74 9192
TEST_TIME 75 9107
TEST_TIME 76 9150
TEST_TIME 77 9116
TEST_TIME 78 9065
TEST_TIME 79 9092
TEST_TIME 80 9065
TEST_TIME 81 9079
TEST_TIME 82 9098
TEST_TIME 83 9068
TEST_TIME 84 9139
TEST_TIME 85 9111
TEST_TIME 86 9173
TEST_TIME 87 9162
TEST_TIME 88 9251
TEST_TIME 89 9271
TEST_TIME 90 9262
TEST_TIME 91 9232
TEST_TIME 92 9276
TEST_TIME 93 9377
TEST_TIME 94 9408
TEST_TIME 95 9344
TEST_TIME 96 9271
TEST_TIME 97 9261
TEST_TIME 98 9238
TEST_TIME 99 9247
TEST_TIME 100 9206
TEST_TIME 101 9205
TEST_TIME 102 9153
TEST_TIME 103 9103
TEST_TIME 104 9113
TEST_TIME 105 9133
TEST_TIME 106 9148
TEST_TIME 107 9158
TEST_TIME 108 9185
TEST_TIME 109 9170
TEST_TIME 110 9166
TEST_TIME 111 9169
TEST_TIME 112 9231
TEST_TIME 113 9243
TEST_TIME 114 9220
TEST_TIME 115 9219
TEST_TIME 116 9298
TEST_TIME 117 9287
TEST_TIME 118 9362
TEST_TIME 119 9293
TEST_TIME 120 9228

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 25-12-2022 16:40:13 to 25-12-2022 16:40:16.

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 1535649 1535649 0 0.00 0 19523 6 0 0
doc_list_biud 1535649 1535649 0 0.00 0 60597 27 0 0
sp_add_invoice_to_stock 5129 5063 66 1.28 37 181464 17963 0 66
sp_cancel_adding_invoice 5641 5641 0 0.00 4 63990 849 0 0
sp_cancel_client_order 1789 1789 0 0.00 4 15202 368 0 0
sp_cancel_customer_reserve 557396 557396 0 0.00 0 60598 58 0 0
sp_cancel_pay_from_customer 710 710 0 0.00 3 10410 82 0 0
sp_cancel_pay_to_supplier 433 433 0 0.00 3 2630 61 0 0
sp_cancel_supplier_invoice 6260 6253 7 0.11 2 28462 800 0 7
sp_cancel_supplier_order 413 413 0 0.00 5 950859 99559 0 0
sp_cancel_write_off 9918 9918 0 0.00 1 8240 104 0 0
sp_client_order 21007 21007 0 0.00 3 62173 359 0 0
sp_customer_reserve 462264 462264 0 0.00 0 79105 181 0 0
sp_fill_shopping_cart 32212 32212 0 0.00 1 14873 39 0 0
sp_fill_shopping_cart_clo_res 462252 462252 0 0.00 0 14317 14 0 0
sp_get_clo_for_invoice 5051 5051 0 0.00 0 25848 322 0 0
sp_kill_cost_storno 16702 16702 0 0.00 0 7830 54 0 0
sp_kill_qstorno_ret_qs2qd 1131376 1131376 0 0.00 0 60579 32 0 0
sp_kill_qty_storno 596814 596814 0 0.00 0 60596 68 0 0
sp_lock_selected_doc 21258 21258 0 0.00 0 8189 8 0 0
sp_make_cost_storno 28477 28477 0 0.00 0 11895 51 0 0
sp_multiply_rows_for_pdistr 28477 28477 0 0.00 0 7216 25 0 0
sp_multiply_rows_for_qdistr 21007 21007 0 0.00 0 62151 171 0 0
sp_pay_from_customer 7216 7216 0 0.00 3 9916 104 0 0
sp_pay_to_supplier 5857 5857 0 0.00 5 13238 123 0 0
sp_payment_common 13073 13073 0 0.00 1 7455 20 0 0
sp_qd_handle_on_cancel_clo 1789 1789 0 0.00 0 15173 331 0 0
sp_qd_handle_on_invoice_upd_sts 10692 10692 0 0.00 0 24886 306 0 0
sp_qd_handle_on_reserve_upd_sts 10353 10353 0 0.00 0 19551 60 0 0
sp_reserve_write_off 10353 10353 0 0.00 6 19692 178 0 0
sp_supplier_invoice 5676 5676 0 0.00 5 88545 3367 0 0
sp_supplier_order 5546 5546 0 0.00 5 41984 1214 0 0
srv_aggregate_perf_data 101 101 0 0.00 2729 209320 21389 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 2283 153254 46212 0 0
srv_find_qd_qs_mism 1059130 1059130 0 0.00 0 19522 6 0 0
srv_make_invnt_saldo 617 617 0 0.00 19 83767 1245 0 0
srv_make_money_saldo 768 768 0 0.00 1 356 22 0 0
srv_recalc_idx_stat 4 4 0 0.00 181121 346895 273704 0 0
t$perf-abend:sp_add_invoice_to_stock 66 66 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 5063 5063 0 0.00 0 11249 166 0 0
t$perf-norm:sp_cancel_adding_invoice 434 434 0 0.00 0 3522 83 0 0
t$perf-norm:sp_cancel_client_order 1789 1789 0 0.00 0 4054 13 0 0
t$perf-norm:sp_cancel_customer_reserve 713 713 0 0.00 0 7137 21 0 0
t$perf-norm:sp_cancel_pay_from_customer 710 710 0 0.00 0 2156 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 433 433 0 0.00 0 3030 17 0 0
t$perf-norm:sp_cancel_supplier_invoice 383 383 0 0.00 0 770 9 0 0
t$perf-norm:sp_cancel_supplier_order 413 413 0 0.00 0 33575 1512 0 0
t$perf-norm:sp_cancel_write_off 979 979 0 0.00 0 3229 12 0 0
t$perf-norm:sp_client_order 21007 21007 0 0.00 0 7824 14 0 0
t$perf-norm:sp_customer_reserve 1005 1005 0 0.00 0 2731 13 0 0
t$perf-norm:sp_pay_from_customer 7216 7216 0 0.00 0 8256 12 0 0
t$perf-norm:sp_pay_to_supplier 5857 5857 0 0.00 0 6088 13 0 0
t$perf-norm:sp_reserve_write_off 10353 10353 0 0.00 0 7658 13 0 0
t$perf-norm:sp_supplier_invoice 5676 5676 0 0.00 0 7833 15 0 0
t$perf-norm:sp_supplier_order 5546 5546 0 0.00 0 9675 17 0 0
t$perf-norm:srv_aggregate_perf_data 101 101 0 0.00 0 218 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 746 17 0 0
t$perf-norm:srv_make_invnt_saldo 617 617 0 0.00 0 742 14 0 0
t$perf-norm:srv_make_money_saldo 768 768 0 0.00 0 726 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 3 0 0
v_add_invoice_to_stock 10234 10102 132 1.28 0 9846 20 0 132
v_all_customers 38140 38140 0 0.00 0 130 0 0 0
v_all_suppliers 31038 31038 0 0.00 0 105 1 0 0
v_all_wares 42014 42014 0 0.00 0 24 1 0 0
v_cancel_adding_invoice 868 868 0 0.00 0 527 6 0 0
v_cancel_client_order 3578 3578 0 0.00 0 5942 9 0 0
v_cancel_customer_prepayment 1420 1420 0 0.00 0 681 4 0 0
v_cancel_customer_reserve 1426 1426 0 0.00 0 968 4 0 0
v_cancel_payment_to_supplier 866 866 0 0.00 0 2607 9 0 0
v_cancel_supplier_invoice 780 766 14 1.79 0 831 12 0 14
v_cancel_supplier_order 826 826 0 0.00 0 4864 9 0 0
v_cancel_write_off 1958 1958 0 0.00 0 1392 11 0 0
v_max_id_clo_ord 5546 5546 0 0.00 0 261 1 0 0
v_max_id_clo_res 1005 1005 0 0.00 0 3635 21 0 0
v_max_id_ord_sup 5659 5659 0 0.00 0 10725 5 0 0
v_max_non_paid_invoice 1543 1543 0 0.00 0 280 2 0 0
v_max_non_paid_realizn 3852 3852 0 0.00 0 3044 5 0 0
v_min_id_clo_ord 5546 5546 0 0.00 0 4021 7 0 0
v_min_id_clo_res 1005 1005 0 0.00 0 1044 3 0 0
v_min_id_ord_sup 5659 5659 0 0.00 0 4553 7 0 0
v_min_non_paid_invoice 5857 5857 0 0.00 0 3413 11 0 0
v_min_non_paid_realizn 7216 7216 0 0.00 0 7452 16 0 0
v_our_firm 10602 10602 0 0.00 0 22 1 0 0
v_reserve_write_off 20706 20706 0 0.00 0 9799 8 0 0
x_lock_depdocs_on_canc_invoice 11511 11511 0 0.00 0 19045 132 0 0
x_lock_depdocs_on_canc_sup_ord 413 413 0 0.00 0 26044 414 0 0
x_make_qstorno_1000_1200 5546 5546 0 0.00 1 41975 1147 0 0
x_make_qstorno_1200_2000 5676 5676 0 0.00 3 88534 3215 0 0
x_make_qstorno_2100_3300 462264 462264 0 0.00 0 79100 165 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 25-12-2022 16:40:18 to 25-12-2022 16:40:22.

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
14:08:39 3 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:09:39 27983 8192000000 9916853808 10229727232 996292016 10.843 100 90 180 0 1026040160 6313264 23434880
14:11:14 32656 8192000000 9948650608 10272354304 1029632224 11.165 100 92 184 0 1062485184 8932912 23920048
14:13:00 8761 8192000000 9984779184 10324459520 1077584976 11.624 100 70 140 0 1104915440 10155504 17174960
14:14:10 4708 8192000000 10046627008 10395168768 1134123920 12.160 100 83 165 0 1165096992 11713648 19259424
14:15:19 5404 8192000000 10081458848 10442612736 1172470048 12.520 100 78 156 0 1202749168 12354784 17924336
14:16:32 2584 8192000000 10128831056 10495610880 1210574128 12.874 100 81 161 0 1242250208 13775280 17900800
14:17:37 2276 8192000000 10149871792 10523398144 1237031520 13.119 100 79 157 2 1267695408 13478080 17185808
14:18:40 1385 8192000000 10175617856 10555731968 1266695648 13.391 100 84 168 0 1297972240 12441984 18834608
14:19:43 782 8192000000 10206202256 10587807744 1286406336 13.571 100 92 185 1 1320021872 12639808 20975728
14:20:45 2948 8192000000 10197595056 10589364224 1291994528 13.622 100 73 146 0 1316891840 8217520 16679792
14:21:49 6928 8192000000 10188036448 10600820736 1299791216 13.693 100 31 62 0 1314560512 7537152 7232144
14:22:57 6722 8192000000 10201324656 10615066624 1317244656 13.852 100 17 34 0 1326536560 5644640 3647264
14:24:05 17598 8192000000 10203851744 10623873024 1322358752 13.898 100 9 18 0 1330127392 5380176 2388464
14:25:25 41836 8192000000 10238548784 10645164032 1327103216 13.941 100 53 106 0 1345990320 6087904 12799200
14:27:07 5711 8192000000 10233968400 10655096832 1339375936 14.052 100 30 61 0 1352533040 6298912 6858192
14:28:14 8715 8192000000 10219265792 10641657856 1322913824 13.903 99 15 31 0 1331646144 5238720 3493600
14:29:24 30363 8192000000 10200622000 10620583936 1296550592 13.664 98 43 81 0 1312471680 6115408 9805680
14:30:59 4390 8192000000 10163881728 10565914624 1266574032 13.390 99 27 55 1 1280026960 7388752 6064176
14:32:04 3695 8192000000 10149135568 10541568000 1241025248 13.156 99 38 78 0 1257140416 7830896 8284272
14:33:10 63409 8192000000 10152012880 10557489152 1237980688 13.128 97 30 59 2 1248938176 4382896 6574592
14:35:14 6617 8192000000 10164836144 10576457728 1269968256 13.421 99 15 31 0 1278960432 5519744 3472432
14:36:21 7432 8192000000 10182432448 10597040128 1294150736 13.642 100 23 46 1 1304541968 5056496 5334736
14:37:29 8953 8192000000 10203833856 10615078912 1306123472 13.751 100 22 45 0 1316832800 5576032 5133296
14:38:40 21989 8192000000 10215087536 10628526080 1313778544 13.820 100 41 78 1 1328000224 5344768 8876912
14:40:20 21361 8192000000 10245738704 10660675584 1343417904 14.088 100 42 84 1 1363401680 10744128 9239648
14:41:48 46156 8192000000 10262602064 10689277952 1357956160 14.219 100 30 58 0 1379030384 14448896 6625328
14:43:46 10960 8192000000 10287609696 10706014208 1371059536 14.337 100 31 62 0 1392694400 13617200 8017664
14:45:01 5552 8192000000 10306709056 10723311616 1380566592 14.422 100 65 128 0 1413796432 17988032 15241808
14:46:10 7528 8192000000 10302985952 10728792064 1391104080 14.516 100 42 81 0 1417632592 17618512 8910000
14:47:31 7609 8192000000 10328785568 10753171456 1404041792 14.631 100 50 100 1 1440173168 23950336 12181040
14:48:43 7313 8192000000 10356518816 10783154176 1423134048 14.800 100 51 100 1 1465731840 30351936 12245856
14:49:55 7733 8192000000 10362531488 10794377216 1427574640 14.840 100 43 85 0 1471464320 32696320 11193360
14:51:07 5363 8192000000 10372357888 10797256704 1425764608 14.824 100 64 126 1 1480851088 39660064 15426416
14:52:17 13669 8192000000 10392034928 10809864192 1425733344 14.824 100 68 135 1 1490094480 47751760 16609376
14:53:36 2328 8192000000 10410349648 10830577664 1444639888 14.991 100 77 151 2 1511548512 48117712 18790912
14:54:43 2280 8192000000 10410531696 10829258752 1447174624 15.013 100 80 160 0 1516611808 48548864 20888320
14:55:54 3393 8192000000 10376210832 10795134976 1407484864 14.662 99 64 125 1 1470054176 47043296 15526016
14:57:02 6217 8192000000 10362577888 10791600128 1409501104 14.680 100 38 75 0 1472393024 52551088 10340832
14:58:11 14978 8192000000 10350249200 10779889664 1383483216 14.448 99 44 87 1 1449528608 53953328 12092064
14:59:38 6870 8192000000 10342116720 10772226048 1383805856 14.451 100 43 84 0 1452578720 56806944 11965920
15:00:49 18972 8192000000 10336260576 10772332544 1388582064 14.493 100 30 60 1 1443421936 46829712 8010160
15:02:11 14394 8192000000 10335299376 10770333696 1368294816 14.312 99 31 62 0 1430243696 53075456 8873424
15:03:29 5924 8192000000 10305004608 10743078912 1365727616 14.289 100 33 63 2 1408148304 34480160 7940528
15:04:39 60719 8192000000 10285000528 10730549248 1351924992 14.165 99 29 56 0 1388216432 29048464 7242976
15:06:44 22170 8192000000 10288882384 10721198080 1365725136 14.289 100 52 103 0 1394676912 16110272 12841504
15:08:10 45846 8192000000 10249563472 10696122368 1314217968 13.824 97 16 32 1 1335574912 17163360 4193584
15:10:08 58346 8192000000 10254708752 10689253376 1328676992 13.955 98 53 106 1 1347524736 6861072 11986672
15:12:11 41659 8192000000 10249127216 10677829632 1319399232 13.871 98 21 42 0 1330495104 6713440 4382432
15:13:55 79684 8192000000 10275240304 10714054656 1326023376 13.931 97 34 66 1 1348739968 14789360 7927232
15:16:18 81143 8192000000 10259475424 10714677248 1339898064 14.056 99 6 12 0 1358613120 17521024 1194032
15:18:40 6439 8192000000 10269111728 10704400384 1355914992 14.201 100 55 110 0 1383876576 15608016 12353568
15:20:04 22593 8192000000 10289376032 10722656256 1370184800 14.329 100 42 84 1 1390418464 10661424 9572240
15:21:29 44915 8192000000 10270787504 10734252032 1374183104 14.365 100 4 8 0 1385054624 10173824 697696
15:23:35 52967 8192000000 10287011296 10738925568 1371878560 14.344 99 13 24 0 1383759312 9116608 2764144
15:25:34 138007 8192000000 10285284528 10734305280 1376137120 14.382 99 17 34 0 1393194000 13130448 3926432
15:29:01 54184 8192000000 10288053360 10747625472 1390928960 14.514 100 16 31 0 1405255952 10932816 3394176
15:30:58 31216 8192000000 10293363344 10746101760 1385447392 14.465 99 17 34 0 1395214784 5892592 3874800
15:32:35 62204 8192000000 10277334496 10737381376 1365902400 14.290 98 10 20 1 1380839280 11856816 3080064
15:34:49 78167 8192000000 10279031872 10743562240 1388457712 14.492 100 13 27 0 1398510832 7419216 2633904
15:37:08 45653 8192000000 10281797376 10744999936 1347656640 14.126 97 27 54 1 1367665136 13196320 6812176
15:39:12 41265 8192000000 10300453920 10758496256 1380805280 14.424 99 26 51 0 1401462304 15157408 5499616
15:40:53 66941 8192000000 10283475088 10734759936 1296676432 13.665 93 21 40 1 1311454352 10205024 4572896
15:45:14 12410 8192000000 10276735680 10705436672 1348334032 14.132 99 52 105 0 1371351632 11707360 11310240
15:46:29 40259 8192000000 10267154736 10707390464 1319024896 13.868 97 27 54 2 1341831552 16307760 6498896
15:48:14 73308 8192000000 10257649952 10711085056 1319332880 13.871 97 12 24 0 1337630672 15553248 2744544
15:50:34 19082 8192000000 10290904640 10717396992 1357158464 14.212 99 53 98 0 1377854000 9961200 10734336
15:51:54 43586 8192000000 10270978144 10707787776 1357035504 14.211 100 59 118 0 1385818848 15870464 12912880
15:53:40 28737 8192000000 10259498080 10701205504 1343746016 14.091 99 32 64 0 1359806016 9014304 7045696
15:55:09 53931 8192000000 10260154272 10713464832 1346491472 14.116 98 11 22 0 1362543696 13795120 2257104
15:57:03 80340 8192000000 10261056320 10715934720 1366168896 14.293 100 18 36 0 1380207120 9385312 4652912
15:59:28 76725 8192000000 10279390976 10735730688 1345948720 14.111 98 13 26 0 1362210128 13629216 2632192
16:01:45 57749 8192000000 10270012304 10726535168 1312176576 13.806 95 5 10 0 1326732272 13414736 1140960
16:03:49 37351 8192000000 10278398288 10715615232 1348153056 14.131 99 36 70 0 1367236512 10932896 8150560
16:05:31 76882 8192000000 10268125872 10735001600 1359357840 14.232 99 16 32 0 1378500176 15369024 3773312
16:08:09 65941 8192000000 10278265632 10709016576 1304214672 13.734 95 33 63 0 1321192416 10701584 6276160
16:10:18 96341 8192000000 10293781648 10759245824 1373135600 14.355 99 13 26 0 1394479744 18863584 2480560
16:12:55 125093 8192000000 10299965328 10751655936 1371461184 14.340 98 31 62 0 1389243776 9925472 7857120
16:16:02 60717 8192000000 10325316704 10780946432 1405530896 14.644 100 36 72 0 1432186896 18931232 7724768
16:18:38 49656 8192000000 10306150496 10780069888 1410720000 14.690 100 12 24 0 1427697664 14104512 2873152
16:20:36 87640 8192000000 10314163280 10775691264 1411975392 14.701 100 20 40 1 1431098176 14401888 4720896
16:24:09 133767 8192000000 10271427776 10731798528 1258408688 13.315 89 11 22 0 1274005856 13068736 2528432
16:27:34 31028 8192000000 10287651136 10733035520 1357655168 14.216 98 44 88 0 1375686080 8452032 9578880
16:29:08 24335 8192000000 10269416528 10712788992 1336933152 14.030 98 42 84 0 1356334720 10363456 9038112
16:30:33 135723 8192000000 10268380304 10716610560 1302787904 13.721 95 15 30 0 1315700400 9839984 3072512
16:33:54 153254 8192000000 10286901024 10748694528 1324927712 13.921 96 14 28 0 1342991504 15121392 2942400
16:37:29 82708 8192000000 9031691184 9241833472 198677680 2.367 15 6 12 0 206270304 6405968 1186656

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 25-12-2022 16:40:24 to 25-12-2022 16:40:39.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 66 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 25-12-2022 16:40:40 to 25-12-2022 16:40:42.

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          239391
MON$OLDEST_ACTIVE               239392
MON$OLDEST_SNAPSHOT             239392
MON$NEXT_TRANSACTION            239392
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                       2003328
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.33650 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33650 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33650 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33650 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 ---
:38: 
:39: mscs-test-161.budzdorov.ru    Sun Dec 25 16:38:54 2022
:40:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:41: 
--- end of diff output ---

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

25.12.22 16:40:43. 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
2022-12-25 16:40:45 No severe PSQL-related problems occured
25.12.22 16:40:47. End of report.