Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 26.09.2022 01: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 15790872 kB
MemAvailable 30619660 kB
Buffers 0 kB
Cached 15959152 kB
SwapCached 2132 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-09-26 01:38:23
DTS_END                         2022-09-26 01:38:23.5320
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/37000
TRN_ID                          236309
ATT_ID                          680
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: 9043.73 20315 1085248
customer order: creation 166.46 398 19975
customer order: refuse 13.74 317 1649
order to supplier: creation 43.73 1256 5248
order to supplier: removal 3.50 95193 420
invoice (draft): creation 43.93 3211 5271
invoice (draft): removal 52.55 798 6306
invoice accept: apply 40.68 17596 4882
invoice accept: cancel 47.40 740 5688
customer reserve: creation 3730.50 176 447660
customer reserve: removal 4623.71 57 554845
realization accept: apply 79.35 172 9522
realization accept: cancel 76.26 104 9151
payment from customer: creation 55.96 112 6715
payment from customer: removal 5.23 80 628
payment to supplier: creation 46.68 129 5601
payment to supplier: removal 3.04 64 365
service: total inventory turnovers 4.43 1037 532
service: total monetary turnovers 5.81 21 697
service: aggregate perf. data 0.74 15574 89
service: refresh index statistics 0.03 269267 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 26-09-2022 01:39:58 to 26-09-2022 01:40:01.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4899
TEST_TIME 2 5051
TEST_TIME 3 4094
TEST_TIME 4 4890
TEST_TIME 5 5346
TEST_TIME 6 5838
TEST_TIME 7 5485
TEST_TIME 8 5607
TEST_TIME 9 5617
TEST_TIME 10 5873
TEST_TIME 11 6004
TEST_TIME 12 6369
TEST_TIME 13 6476
TEST_TIME 14 6453
TEST_TIME 15 6730
TEST_TIME 16 6933
TEST_TIME 17 7323
TEST_TIME 18 7657
TEST_TIME 19 7490
TEST_TIME 20 8069
TEST_TIME 21 8187
TEST_TIME 22 8443
TEST_TIME 23 8996
TEST_TIME 24 8883
TEST_TIME 25 8915
TEST_TIME 26 9259
TEST_TIME 27 10475
TEST_TIME 28 10429
TEST_TIME 29 10543
TEST_TIME 30 10418
TEST_TIME 31 10446
TEST_TIME 32 10208
TEST_TIME 33 10133
TEST_TIME 34 10001
TEST_TIME 35 9944
TEST_TIME 36 9995
TEST_TIME 37 9968
TEST_TIME 38 9773
TEST_TIME 39 9723
TEST_TIME 40 9519
TEST_TIME 41 9431
TEST_TIME 42 9339
TEST_TIME 43 9358
TEST_TIME 44 9459
TEST_TIME 45 9347
TEST_TIME 46 9368
TEST_TIME 47 9209
TEST_TIME 48 9163
TEST_TIME 49 9154
TEST_TIME 50 9236
TEST_TIME 51 9191
TEST_TIME 52 9116
TEST_TIME 53 9173
TEST_TIME 54 9155
TEST_TIME 55 9151
TEST_TIME 56 9297
TEST_TIME 57 9339
TEST_TIME 58 9435
TEST_TIME 59 9442
TEST_TIME 60 9479
TEST_TIME 61 9510
TEST_TIME 62 9511
TEST_TIME 63 9451
TEST_TIME 64 9484
TEST_TIME 65 9371
TEST_TIME 66 9357
TEST_TIME 67 9275
TEST_TIME 68 9307
TEST_TIME 69 9242
TEST_TIME 70 9222
TEST_TIME 71 9173
TEST_TIME 72 9122
TEST_TIME 73 9136
TEST_TIME 74 9110
TEST_TIME 75 9062
TEST_TIME 76 8997
TEST_TIME 77 9085
TEST_TIME 78 9186
TEST_TIME 79 9182
TEST_TIME 80 9127
TEST_TIME 81 9203
TEST_TIME 82 9228
TEST_TIME 83 9242
TEST_TIME 84 9220
TEST_TIME 85 9251
TEST_TIME 86 9300
TEST_TIME 87 9295
TEST_TIME 88 9266
TEST_TIME 89 9210
TEST_TIME 90 9264
TEST_TIME 91 9225
TEST_TIME 92 9199
TEST_TIME 93 9128
TEST_TIME 94 9121
TEST_TIME 95 9141
TEST_TIME 96 9164
TEST_TIME 97 9112
TEST_TIME 98 9150
TEST_TIME 99 9160
TEST_TIME 100 9227
TEST_TIME 101 9219
TEST_TIME 102 9196
TEST_TIME 103 9171
TEST_TIME 104 9246
TEST_TIME 105 9244
TEST_TIME 106 9261
TEST_TIME 107 9307
TEST_TIME 108 9309
TEST_TIME 109 9240
TEST_TIME 110 9207
TEST_TIME 111 9193
TEST_TIME 112 9184
TEST_TIME 113 9171
TEST_TIME 114 9116
TEST_TIME 115 9096
TEST_TIME 116 9052
TEST_TIME 117 9065
TEST_TIME 118 9024
TEST_TIME 119 9048
TEST_TIME 120 9049

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 26-09-2022 01:40:02 to 26-09-2022 01:40:05.

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 1500905 1500905 0 0.00 0 27338 6 0 0
doc_list_biud 1500903 1500903 0 0.00 0 45199 27 0 0
sp_add_invoice_to_stock 5057 4882 175 3.46 31 230803 17596 0 175
sp_cancel_adding_invoice 5688 5688 0 0.00 4 54437 740 0 0
sp_cancel_client_order 1649 1649 0 0.00 4 17340 318 0 0
sp_cancel_customer_reserve 554845 554845 0 0.00 0 45200 57 0 0
sp_cancel_pay_from_customer 628 628 0 0.00 2 7677 81 0 0
sp_cancel_pay_to_supplier 365 365 0 0.00 3 2342 65 0 0
sp_cancel_supplier_invoice 6315 6306 9 0.14 1 31248 799 0 9
sp_cancel_supplier_order 420 420 0 0.00 6 1091062 95194 0 0
sp_cancel_write_off 9151 9151 0 0.00 1 10479 104 0 0
sp_client_order 19975 19975 0 0.00 3 53123 399 0 0
sp_customer_reserve 447660 447660 0 0.00 0 37335 176 0 0
sp_fill_shopping_cart 30490 30490 0 0.00 1 27622 40 0 0
sp_fill_shopping_cart_clo_res 447654 447654 0 0.00 0 18660 16 0 0
sp_get_clo_for_invoice 4875 4875 0 0.00 0 23640 359 0 0
sp_kill_cost_storno 15832 15832 0 0.00 0 9348 54 0 0
sp_kill_qstorno_ret_qs2qd 1125567 1125567 0 0.00 0 45196 31 0 0
sp_kill_qty_storno 592456 592456 0 0.00 0 45198 67 0 0
sp_lock_selected_doc 19889 19889 0 0.00 0 10055 5 0 0
sp_make_cost_storno 26713 26713 0 0.00 0 14358 50 0 0
sp_multiply_rows_for_pdistr 26713 26713 0 0.00 0 13721 28 0 0
sp_multiply_rows_for_qdistr 19975 19975 0 0.00 0 53117 187 0 0
sp_pay_from_customer 6715 6715 0 0.00 3 17319 112 0 0
sp_pay_to_supplier 5601 5601 0 0.00 4 14366 130 0 0
sp_payment_common 12316 12316 0 0.00 1 17313 37 0 0
sp_qd_handle_on_cancel_clo 1649 1649 0 0.00 0 17331 286 0 0
sp_qd_handle_on_invoice_upd_sts 10563 10563 0 0.00 0 19057 297 0 0
sp_qd_handle_on_reserve_upd_sts 9522 9522 0 0.00 0 13940 61 0 0
sp_reserve_write_off 9522 9522 0 0.00 6 14371 172 0 0
sp_supplier_invoice 5271 5271 0 0.00 6 102901 3212 0 0
sp_supplier_order 5248 5248 0 0.00 5 51843 1257 0 0
srv_aggregate_perf_data 89 89 0 0.00 2567 125692 15574 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 1558 136799 48328 0 0
srv_find_qd_qs_mism 1040029 1040029 0 0.00 0 27337 5 0 0
srv_make_invnt_saldo 532 532 0 0.00 37 118310 1038 0 0
srv_make_money_saldo 697 697 0 0.00 1 705 22 0 0
srv_recalc_idx_stat 4 4 0 0.00 134320 424563 269267 0 0
t$perf-abend:sp_add_invoice_to_stock 175 175 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4882 4882 0 0.00 0 14307 160 0 0
t$perf-norm:sp_cancel_adding_invoice 400 400 0 0.00 1 9834 183 0 0
t$perf-norm:sp_cancel_client_order 1649 1649 0 0.00 0 19190 25 0 0
t$perf-norm:sp_cancel_customer_reserve 651 651 0 0.00 0 2828 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 628 628 0 0.00 0 3677 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 365 365 0 0.00 0 4178 16 0 0
t$perf-norm:sp_cancel_supplier_invoice 377 377 0 0.00 0 502 10 0 0
t$perf-norm:sp_cancel_supplier_order 420 420 0 0.00 0 26469 1550 0 0
t$perf-norm:sp_cancel_write_off 1003 1003 0 0.00 0 2309 12 0 0
t$perf-norm:sp_client_order 19975 19975 0 0.00 0 8520 15 0 0
t$perf-norm:sp_customer_reserve 927 927 0 0.00 0 5143 14 0 0
t$perf-norm:sp_pay_from_customer 6715 6715 0 0.00 0 7748 12 0 0
t$perf-norm:sp_pay_to_supplier 5601 5601 0 0.00 0 7747 12 0 0
t$perf-norm:sp_reserve_write_off 9522 9522 0 0.00 0 8981 11 0 0
t$perf-norm:sp_supplier_invoice 5271 5271 0 0.00 0 4340 16 0 0
t$perf-norm:sp_supplier_order 5248 5248 0 0.00 0 7167 16 0 0
t$perf-norm:srv_aggregate_perf_data 89 89 0 0.00 0 106 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 272 5 0 0
t$perf-norm:srv_make_invnt_saldo 532 532 0 0.00 0 1423 17 0 0
t$perf-norm:srv_make_money_saldo 697 697 0 0.00 0 4011 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 125 33 0 0
v_add_invoice_to_stock 10098 9748 350 3.46 0 10660 19 0 350
v_all_customers 36434 36434 0 0.00 0 59 0 0 0
v_all_suppliers 29110 29110 0 0.00 0 19 1 0 0
v_all_wares 39950 39950 0 0.00 0 20 1 0 0
v_cancel_adding_invoice 800 800 0 0.00 0 440 7 0 0
v_cancel_client_order 3298 3298 0 0.00 0 9771 9 0 0
v_cancel_customer_prepayment 1256 1256 0 0.00 0 1579 7 0 0
v_cancel_customer_reserve 1302 1302 0 0.00 0 880 5 0 0
v_cancel_payment_to_supplier 730 730 0 0.00 0 336 3 0 0
v_cancel_supplier_invoice 772 754 18 2.33 0 426 15 0 18
v_cancel_supplier_order 840 840 0 0.00 0 3832 11 0 0
v_cancel_write_off 2006 2006 0 0.00 0 3628 17 0 0
v_max_id_clo_ord 5248 5248 0 0.00 0 3723 2 0 0
v_max_id_clo_res 927 927 0 0.00 0 5897 23 0 0
v_max_id_ord_sup 5267 5267 0 0.00 0 4745 4 0 0
v_max_non_paid_invoice 1561 1561 0 0.00 0 2748 3 0 0
v_max_non_paid_realizn 3539 3539 0 0.00 0 3770 5 0 0
v_min_id_clo_ord 5248 5248 0 0.00 0 3643 6 0 0
v_min_id_clo_res 927 927 0 0.00 0 3292 9 0 0
v_min_id_ord_sup 5267 5267 0 0.00 0 7953 14 0 0
v_min_non_paid_invoice 5601 5601 0 0.00 0 13104 28 0 0
v_min_non_paid_realizn 6715 6715 0 0.00 0 12143 28 0 0
v_our_firm 9868 9868 0 0.00 0 19 1 0 0
v_reserve_write_off 19043 19043 0 0.00 0 12314 8 0 0
x_lock_depdocs_on_canc_invoice 11617 11617 0 0.00 0 15104 103 0 0
x_lock_depdocs_on_canc_sup_ord 420 420 0 0.00 0 30780 421 0 0
x_make_qstorno_1000_1200 5248 5248 0 0.00 1 51837 1189 0 0
x_make_qstorno_1200_2000 5271 5271 0 0.00 3 96952 3051 0 0
x_make_qstorno_2100_3300 447660 447660 0 0.00 0 37333 158 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 26-09-2022 01:40:06 to 26-09-2022 01:40:11.

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
23:08:27 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:28 22989 8192000000 9903783280 10213658624 983963328 10.723 100 85 170 0 1011559760 5364592 22231840
23:11:04 19950 8192000000 9955340816 10277814272 1036366368 11.230 100 85 170 0 1065760128 7458992 21934768
23:12:27 20843 8192000000 9994353072 10333880320 1078447184 11.633 100 81 162 2 1107920464 9352464 20120816
23:13:51 9373 8192000000 10056709280 10406383616 1139932624 12.215 100 87 174 0 1171102080 10853344 20316112
23:15:05 4967 8192000000 10101357920 10459664384 1184347888 12.631 100 84 168 1 1216822672 12972048 19502736
23:16:13 5245 8192000000 10130637824 10500476928 1219656608 12.959 100 76 151 1 1248136256 11623328 16856320
23:17:21 1570 8192000000 10158010960 10531672064 1242284272 13.167 100 82 161 0 1274531104 14403984 17842848
23:18:25 1318 8192000000 10185044080 10568462336 1270712144 13.428 100 82 164 0 1303691664 15307776 17671744
23:19:29 903 8192000000 10212534592 10598592512 1298994016 13.686 100 89 172 1 1332383440 12905440 20483984
23:20:30 5941 8192000000 10196458960 10602676224 1303727008 13.729 100 37 73 1 1319991616 8388128 7876480
23:21:37 8752 8192000000 10214390016 10628616192 1322618368 13.900 100 29 58 0 1336501648 8003504 5879776
23:22:49 6160 8192000000 10218706352 10634846208 1327414272 13.944 100 16 32 0 1341105504 10346448 3344784
23:24:02 10689 8192000000 10218695008 10639310848 1334769328 14.010 100 31 63 0 1348181872 6379328 7033216
23:25:13 30112 8192000000 10226273184 10648768512 1334273328 14.006 100 18 36 0 1343664480 5258192 4132960
23:26:43 8973 8192000000 10231260128 10651774976 1337953184 14.039 100 22 44 0 1349103376 5613936 5536256
23:27:56 8651 8192000000 10211254192 10634850304 1316741760 13.847 99 23 46 0 1327478000 5752368 4983872
23:29:05 5847 8192000000 10173528784 10584670208 1275376576 13.471 99 18 35 0 1284781952 5698064 3707312
23:30:12 23442 8192000000 10177961456 10575081472 1264309920 13.370 99 47 96 2 1280945888 4785120 11850848
23:31:40 6741 8192000000 10132006560 10529759232 1213952944 12.906 97 22 44 1 1224323264 5838864 4531456
23:32:47 6256 8192000000 10159553664 10563133440 1266451632 13.389 100 29 59 1 1279201392 6236464 6513296
23:33:53 10610 8192000000 10176994640 10580393984 1268677552 13.410 99 28 57 0 1280851040 5221488 6952000
23:35:04 27967 8192000000 10185015984 10592591872 1287677920 13.583 100 17 34 0 1296928896 5761504 3489472
23:36:33 6348 8192000000 10193197520 10606530560 1298742928 13.684 100 15 30 0 1311598608 9520816 3334864
23:37:41 8734 8192000000 10201440704 10615410688 1306130224 13.751 100 22 44 0 1316738336 5559904 5048208
23:38:53 27800 8192000000 10250570944 10658295808 1336175216 14.023 100 48 96 0 1355053312 8420976 10457120
23:40:24 40988 8192000000 10244286016 10665652224 1349647536 14.144 100 39 78 0 1367685296 8986704 9051056
23:42:09 22341 8192000000 10266546656 10690154496 1361438800 14.250 100 35 69 0 1383612080 14144960 8028320
23:43:35 52214 8192000000 10283712160 10709876736 1375437312 14.376 100 37 74 2 1400196112 15322224 9436576
23:45:32 7043 8192000000 10300705616 10717876224 1378606272 14.404 100 62 124 1 1413684544 20878336 14199936
23:46:47 4839 8192000000 10304937760 10728349696 1388545696 14.493 100 52 104 0 1416313392 14969952 12797744
23:47:58 7906 8192000000 10325681248 10748370944 1394397824 14.545 100 54 109 1 1429045136 21671408 12975904
23:49:16 7914 8192000000 10332488752 10753699840 1396724288 14.566 100 66 132 0 1438390144 25829104 15836752
23:50:33 1817 8192000000 10317798928 10728316928 1372523072 14.350 99 76 149 1 1422068992 31307984 18237936
23:51:39 4576 8192000000 10319903472 10735607808 1377863984 14.397 100 60 118 2 1426052208 33448112 14740112
23:52:50 6737 8192000000 10310555712 10725785600 1371133584 14.337 99 56 112 2 1421799984 35682608 14983792
23:54:01 8197 8192000000 10322360960 10739507200 1369340288 14.321 99 56 111 1 1420459264 36420464 14698512
23:55:18 1556 8192000000 10316126896 10715885568 1358007440 14.219 100 87 172 0 1420901376 41580160 21313776
23:56:22 7182 8192000000 10301535504 10716327936 1352652160 14.171 99 48 94 1 1399141008 35145776 11343072
23:57:33 5467 8192000000 10299108624 10719875072 1350587392 14.153 100 40 79 0 1407738768 46720784 10430592
23:58:42 10708 8192000000 10299536752 10712637440 1349356688 14.142 100 47 94 0 1403826240 42278656 12190896
23:59:57 11101 8192000000 10303676640 10719547392 1350589216 14.153 100 41 82 1 1405439376 43744112 11106048
00:01:19 23975 8192000000 10303167488 10744172544 1359012624 14.228 99 26 52 0 1412822928 46098160 7712144
00:02:53 20735 8192000000 10321632880 10757652480 1375055072 14.372 100 24 48 0 1426709136 45108400 6545664
00:04:14 27806 8192000000 10311769536 10748301312 1348369856 14.133 99 28 56 0 1407057504 51101584 7586064
00:05:48 37822 8192000000 10275185072 10706239488 1306519984 13.754 96 18 37 0 1330435040 19353376 4561680
00:07:28 54808 8192000000 10250442224 10697404416 1332645808 13.991 99 7 14 0 1351111376 16895696 1569872
00:09:50 65633 8192000000 10271869792 10716082176 1351125696 14.158 99 13 26 0 1362920720 9260416 2534608
00:11:58 14219 8192000000 10282905072 10723672064 1376708928 14.387 100 33 65 0 1389892624 6170352 7013344
00:13:15 98084 8192000000 10276416000 10735190016 1361305440 14.249 99 12 24 0 1382256544 18281984 2669120
00:15:57 136799 8192000000 10261033488 10720923648 1345169392 14.104 98 30 61 0 1360068208 7591920 7306896
00:19:24 61187 8192000000 10295682768 10745511936 1386596528 14.475 100 26 52 0 1410425056 18151584 5676944
00:21:30 55697 8192000000 10287349568 10753191936 1388841168 14.496 100 27 55 0 1412088656 16781056 6466432
00:23:26 70390 8192000000 10292678608 10750148608 1381017824 14.426 99 23 46 0 1398931008 12550800 5362384
00:25:39 73178 8192000000 10297972832 10753159168 1381268736 14.428 99 19 38 0 1402625088 17039392 4316960
00:27:58 94336 8192000000 10288415904 10766196736 1378295536 14.401 99 10 20 0 1399781728 19252864 2233328
00:30:39 46028 8192000000 10301176784 10768056320 1400013872 14.595 100 26 52 0 1420019968 14296384 5709712
00:32:26 58121 8192000000 10341677136 10785783808 1403979072 14.630 99 34 68 0 1429439040 17009872 8450096
00:34:25 61967 8192000000 10323779456 10789404672 1416690496 14.743 100 19 37 0 1440112448 18761568 4660384
00:36:31 30538 8192000000 10314100592 10755153920 1367753200 14.307 97 24 49 0 1380577648 7195840 5628608
00:38:02 38152 8192000000 10301865856 10758938624 1382573152 14.440 98 31 62 0 1405453520 16386336 6494032
00:39:47 76835 8192000000 10296151584 10737922048 1299081232 13.687 93 15 30 0 1310661792 8319008 3261552
00:42:04 11624 8192000000 10292809120 10726928384 1352339472 14.169 99 44 88 0 1379309392 17166576 9803344
00:43:22 110783 8192000000 10218878608 10657124352 1285684624 13.565 94 33 67 0 1303840096 10004848 8150624
00:46:14 101441 8192000000 10233990992 10689650688 1297139728 13.669 96 8 16 0 1307445696 8371088 1934880
00:48:57 52488 8192000000 10267437568 10718568448 1352373344 14.169 99 17 34 0 1363966880 7182736 4410800
00:50:49 86993 8192000000 10258545696 10726563840 1360233200 14.239 99 21 42 0 1380949008 16080416 4635392
00:53:37 39886 8192000000 10267025392 10711408640 1358555120 14.224 100 14 28 0 1373527584 11670496 3301968
00:55:28 95067 8192000000 10257852896 10715406336 1308101088 13.769 96 12 24 0 1327038320 16279392 2657840
00:58:03 88514 8192000000 10272947952 10732335104 1353258000 14.177 99 28 53 1 1376383312 17058160 6067152
01:00:45 37964 8192000000 10280215280 10710319104 1339178960 14.050 99 40 78 0 1358177712 10725504 8273248
01:02:25 43780 8192000000 10269187296 10704232448 1331780768 13.983 98 30 60 0 1349528416 11137072 6610576
01:04:11 63228 8192000000 10276360096 10712088576 1339915248 14.057 99 23 46 0 1354682560 9800288 4967024
01:06:22 98728 8192000000 10271830192 10708791296 1354974576 14.192 99 25 49 0 1372167888 12158272 5035040
01:09:03 17997 8192000000 10294950336 10748399616 1379740896 14.414 100 27 54 0 1402050672 16854048 5455728
01:10:50 59118 8192000000 10285245680 10745982976 1387055168 14.480 100 11 22 0 1402297728 13032160 2210400
01:14:21 54389 8192000000 10300003392 10745438208 1385325280 14.464 100 18 36 0 1396179600 6997408 3856912
01:16:15 38623 8192000000 10300565536 10757238784 1380864688 14.424 99 19 38 0 1398437264 13375328 4197248
01:18:06 112914 8192000000 10292130848 10756358144 1374699040 14.369 98 13 26 0 1394198576 16744208 2755328
01:20:59 16387 8192000000 10318787856 10759987200 1394628336 14.547 100 46 93 0 1417282576 11984880 10669360
01:22:30 52343 8192000000 10271456320 10719440896 1319429152 13.872 95 15 30 0 1333425552 10816192 3180208
01:24:29 63334 8192000000 10282829568 10734055424 1346018832 14.112 98 15 30 1 1366451216 16914912 3517472
01:27:39 125620 8192000000 10298146000 10753822720 1358594128 14.225 98 31 62 0 1380756608 15186656 6975824
01:30:46 54518 8192000000 10264186512 10723921920 1365540400 14.287 100 10 20 0 1378934256 11274096 2119760
01:33:37 62816 8192000000 10250295264 10703036416 1323051328 13.904 97 14 28 1 1334308416 8038432 3218656
01:36:04 84736 8192000000 10265011008 10726875136 1352244448 14.168 99 7 14 0 1364983136 11089568 1649120

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 26-09-2022 01:40:13 to 26-09-2022 01:40:28.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 175 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 26-09-2022 01:40:29 to 26-09-2022 01:40:31.

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          236038
MON$OLDEST_ACTIVE               236456
MON$OLDEST_SNAPSHOT             236456
MON$NEXT_TRANSACTION            236456
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                       2010368
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.33631 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33631 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33631 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33631 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 ---
:45: 
:46: mscs-test-161.budzdorov.ru    Mon Sep 26 01:38:43 2022
:47:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:48: 
:49: 
:50: mscs-test-161.budzdorov.ru    Mon Sep 26 01:38:43 2022
:51:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:52: 
:53: 
:54: mscs-test-161.budzdorov.ru    Mon Sep 26 01:38:44 2022
:55:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:56: 
--- end of diff output ---

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

26.09.22 01:40:32. 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-09-26 01:40:34 No severe PSQL-related problems occured
26.09.22 01:40:36. End of report.