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

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-05-19 04:37:17
DTS_END                         2022-05-19 04:37:17.5400
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/55440
TRN_ID                          217271
ATT_ID                          643
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: 8826.86 25484 1059223
customer order: creation 163.23 470 19588
customer order: refuse 14.29 412 1715
order to supplier: creation 43.54 1454 5225
order to supplier: removal 3.51 150192 421
invoice (draft): creation 43.75 3435 5250
invoice (draft): removal 50.63 1003 6076
invoice accept: apply 40.28 26657 4833
invoice accept: cancel 45.43 1190 5451
customer reserve: creation 3707.76 258 444931
customer reserve: removal 4433.15 93 531978
realization accept: apply 80.24 277 9629
realization accept: cancel 78.07 182 9368
payment from customer: creation 56.31 176 6757
payment from customer: removal 5.53 143 664
payment to supplier: creation 45.94 201 5513
payment to supplier: removal 3.34 102 401
service: total inventory turnovers 3.97 3754 476
service: total monetary turnovers 6.94 318 833
service: aggregate perf. data 0.92 14818 110
service: refresh index statistics 0.03 304540 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 19-05-2022 04:40:07 to 19-05-2022 04:40:09.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7890
TEST_TIME 2 7306
TEST_TIME 3 6237
TEST_TIME 4 6396
TEST_TIME 5 6425
TEST_TIME 6 6261
TEST_TIME 7 6408
TEST_TIME 8 6745
TEST_TIME 9 6764
TEST_TIME 10 6868
TEST_TIME 11 7091
TEST_TIME 12 7075
TEST_TIME 13 6936
TEST_TIME 14 7043
TEST_TIME 15 7125
TEST_TIME 16 7445
TEST_TIME 17 7483
TEST_TIME 18 7723
TEST_TIME 19 7985
TEST_TIME 20 8086
TEST_TIME 21 8279
TEST_TIME 22 8625
TEST_TIME 23 8500
TEST_TIME 24 8651
TEST_TIME 25 8501
TEST_TIME 26 8798
TEST_TIME 27 9536
TEST_TIME 28 9941
TEST_TIME 29 10406
TEST_TIME 30 10264
TEST_TIME 31 10143
TEST_TIME 32 10183
TEST_TIME 33 10120
TEST_TIME 34 9993
TEST_TIME 35 10216
TEST_TIME 36 10462
TEST_TIME 37 10393
TEST_TIME 38 10236
TEST_TIME 39 10108
TEST_TIME 40 9997
TEST_TIME 41 9891
TEST_TIME 42 9815
TEST_TIME 43 9826
TEST_TIME 44 9788
TEST_TIME 45 9667
TEST_TIME 46 9621
TEST_TIME 47 9452
TEST_TIME 48 9434
TEST_TIME 49 9355
TEST_TIME 50 9253
TEST_TIME 51 9264
TEST_TIME 52 9221
TEST_TIME 53 9198
TEST_TIME 54 9232
TEST_TIME 55 9288
TEST_TIME 56 9209
TEST_TIME 57 9155
TEST_TIME 58 9061
TEST_TIME 59 9103
TEST_TIME 60 9077
TEST_TIME 61 9093
TEST_TIME 62 9224
TEST_TIME 63 9211
TEST_TIME 64 9324
TEST_TIME 65 9419
TEST_TIME 66 9471
TEST_TIME 67 9489
TEST_TIME 68 9454
TEST_TIME 69 9567
TEST_TIME 70 9554
TEST_TIME 71 9502
TEST_TIME 72 9433
TEST_TIME 73 9386
TEST_TIME 74 9308
TEST_TIME 75 9295
TEST_TIME 76 9323
TEST_TIME 77 9266
TEST_TIME 78 9211
TEST_TIME 79 9176
TEST_TIME 80 9128
TEST_TIME 81 9045
TEST_TIME 82 9077
TEST_TIME 83 9087
TEST_TIME 84 9077
TEST_TIME 85 9041
TEST_TIME 86 9058
TEST_TIME 87 9115
TEST_TIME 88 9082
TEST_TIME 89 9164
TEST_TIME 90 9205
TEST_TIME 91 9171
TEST_TIME 92 9203
TEST_TIME 93 9229
TEST_TIME 94 9196
TEST_TIME 95 9157
TEST_TIME 96 9109
TEST_TIME 97 9076
TEST_TIME 98 9064
TEST_TIME 99 9057
TEST_TIME 100 9017
TEST_TIME 101 9006
TEST_TIME 102 8969
TEST_TIME 103 8958
TEST_TIME 104 8912
TEST_TIME 105 8926
TEST_TIME 106 8930
TEST_TIME 107 8892
TEST_TIME 108 8941
TEST_TIME 109 8954
TEST_TIME 110 8961
TEST_TIME 111 8930
TEST_TIME 112 8924
TEST_TIME 113 8929
TEST_TIME 114 9016
TEST_TIME 115 9055
TEST_TIME 116 9044
TEST_TIME 117 8970
TEST_TIME 118 8955
TEST_TIME 119 8923
TEST_TIME 120 8850

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 19-05-2022 04:40:10 to 19-05-2022 04:40:14.

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 1470351 1470351 0 0.00 0 18899 13 0 0
doc_list_biud 1470350 1470350 0 0.00 0 85842 40 0 0
sp_add_invoice_to_stock 4965 4833 132 2.65 35 214359 26658 0 132
sp_cancel_adding_invoice 5451 5451 0 0.00 6 95491 1191 0 0
sp_cancel_client_order 1715 1715 0 0.00 7 9617 412 0 0
sp_cancel_customer_reserve 531978 531978 0 0.00 1 31698 93 0 0
sp_cancel_pay_from_customer 664 664 0 0.00 3 7443 144 0 0
sp_cancel_pay_to_supplier 401 401 0 0.00 4 2821 102 0 0
sp_cancel_supplier_invoice 6091 6076 15 0.24 3 21827 1003 0 15
sp_cancel_supplier_order 421 421 0 0.00 10 1202637 150192 0 0
sp_cancel_write_off 9368 9368 0 0.00 2 86030 183 0 0
sp_client_order 19588 19588 0 0.00 6 23167 471 0 0
sp_customer_reserve 444931 444931 0 0.00 1 133856 259 0 0
sp_fill_shopping_cart 30057 30057 0 0.00 2 13623 79 0 0
sp_fill_shopping_cart_clo_res 444917 444917 0 0.00 0 18806 20 0 0
sp_get_clo_for_invoice 4795 4795 0 0.00 0 42022 311 0 0
sp_kill_cost_storno 15884 15884 0 0.00 0 11234 77 0 0
sp_kill_qstorno_ret_qs2qd 1079821 1079821 0 0.00 0 85837 43 0 0
sp_kill_qty_storno 569430 569430 0 0.00 0 85839 96 0 0
sp_lock_selected_doc 20029 20029 0 0.00 0 10472 9 0 0
sp_make_cost_storno 26691 26691 0 0.00 0 18896 85 0 0
sp_multiply_rows_for_pdistr 26691 26691 0 0.00 0 5625 35 0 0
sp_multiply_rows_for_qdistr 19588 19588 0 0.00 0 15341 179 0 0
sp_pay_from_customer 6757 6757 0 0.00 6 13828 177 0 0
sp_pay_to_supplier 5513 5513 0 0.00 7 18905 202 0 0
sp_payment_common 12270 12270 0 0.00 2 9687 45 0 0
sp_qd_handle_on_cancel_clo 1715 1715 0 0.00 1 9608 333 0 0
sp_qd_handle_on_invoice_upd_sts 10243 10243 0 0.00 0 41229 464 0 0
sp_qd_handle_on_reserve_upd_sts 9629 9629 0 0.00 0 10819 64 0 0
sp_reserve_write_off 9629 9629 0 0.00 9 13797 278 0 0
sp_supplier_invoice 5250 5250 0 0.00 11 89400 3435 0 0
sp_supplier_order 5225 5225 0 0.00 9 91216 1454 0 0
srv_aggregate_perf_data 110 110 0 0.00 2839 124944 14819 0 0
srv_fill_mon_cache_memory 68 68 0 0.00 948 158377 38399 0 0
srv_find_qd_qs_mism 1013230 1013230 0 0.00 0 11657 11 0 0
srv_make_invnt_saldo 476 476 0 0.00 64 62039 3755 0 0
srv_make_money_saldo 833 833 0 0.00 1 14585 318 0 0
srv_recalc_idx_stat 4 4 0 0.00 106112 548027 304541 0 0
t$perf-abend:sp_add_invoice_to_stock 132 132 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 15 15 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4836 4836 0 0.00 0 10079 822 0 0
t$perf-norm:sp_cancel_adding_invoice 388 388 0 0.00 1 6277 451 0 0
t$perf-norm:sp_cancel_client_order 1715 1715 0 0.00 0 9842 38 0 0
t$perf-norm:sp_cancel_customer_reserve 650 650 0 0.00 0 11223 48 0 0
t$perf-norm:sp_cancel_pay_from_customer 664 664 0 0.00 0 7313 29 0 0
t$perf-norm:sp_cancel_pay_to_supplier 401 401 0 0.00 0 663 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 419 419 0 0.00 0 2636 34 0 0
t$perf-norm:sp_cancel_supplier_order 421 421 0 0.00 0 92701 5777 0 0
t$perf-norm:sp_cancel_write_off 951 951 0 0.00 0 4118 23 0 0
t$perf-norm:sp_client_order 19588 19588 0 0.00 0 12275 34 0 0
t$perf-norm:sp_customer_reserve 920 920 0 0.00 0 3187 25 0 0
t$perf-norm:sp_pay_from_customer 6757 6757 0 0.00 0 3181 17 0 0
t$perf-norm:sp_pay_to_supplier 5513 5513 0 0.00 0 3587 18 0 0
t$perf-norm:sp_reserve_write_off 9629 9629 0 0.00 0 5873 24 0 0
t$perf-norm:sp_supplier_invoice 5250 5250 0 0.00 0 5097 31 0 0
t$perf-norm:sp_supplier_order 5225 5225 0 0.00 0 3122 27 0 0
t$perf-norm:srv_aggregate_perf_data 110 110 0 0.00 0 306 15 0 0
t$perf-norm:srv_fill_mon_cache_memory 68 68 0 0.00 0 5524 89 0 0
t$perf-norm:srv_make_invnt_saldo 476 476 0 0.00 0 456 10 0 0
t$perf-norm:srv_make_money_saldo 833 833 0 0.00 0 259 4 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 198 91 0 0
v_add_invoice_to_stock 9846 9582 264 2.68 0 6618 24 0 264
v_all_customers 35988 35988 0 0.00 0 130 3 0 0
v_all_suppliers 29104 29104 0 0.00 0 112 4 0 0
v_all_wares 39176 39176 0 0.00 0 54 3 0 0
v_cancel_adding_invoice 776 776 0 0.00 0 2297 17 0 0
v_cancel_client_order 3430 3430 0 0.00 0 6920 16 0 0
v_cancel_customer_prepayment 1328 1328 0 0.00 0 5756 17 0 0
v_cancel_customer_reserve 1300 1300 0 0.00 0 3285 13 0 0
v_cancel_payment_to_supplier 802 802 0 0.00 0 1077 12 0 0
v_cancel_supplier_invoice 868 838 30 3.45 0 1148 25 0 30
v_cancel_supplier_order 842 842 0 0.00 0 1985 12 0 0
v_cancel_write_off 1902 1902 0 0.00 0 208 19 0 0
v_max_id_clo_ord 5225 5225 0 0.00 0 1129 5 0 0
v_max_id_clo_res 920 920 0 0.00 0 1612 12 0 0
v_max_id_ord_sup 5244 5244 0 0.00 0 7590 8 0 0
v_max_non_paid_invoice 1430 1430 0 0.00 0 266 7 0 0
v_max_non_paid_realizn 3470 3470 0 0.00 0 2236 7 0 0
v_min_id_clo_ord 5225 5225 0 0.00 0 6186 10 0 0
v_min_id_clo_res 920 920 0 0.00 0 7013 21 0 0
v_min_id_ord_sup 5244 5244 0 0.00 0 8363 12 0 0
v_min_non_paid_invoice 5513 5513 0 0.00 0 5185 20 0 0
v_min_non_paid_realizn 6757 6757 0 0.00 0 5219 17 0 0
v_our_firm 9762 9762 0 0.00 0 53 4 0 0
v_reserve_write_off 19258 19258 0 0.00 0 6141 12 0 0
x_lock_depdocs_on_canc_invoice 11108 11108 0 0.00 0 30872 119 0 0
x_lock_depdocs_on_canc_sup_ord 421 421 0 0.00 0 15500 332 0 0
x_make_qstorno_1000_1200 5225 5225 0 0.00 3 91190 1296 0 0
x_make_qstorno_1200_2000 5250 5250 0 0.00 5 89377 3211 0 0
x_make_qstorno_2100_3300 444931 444931 0 0.00 0 133853 228 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 19-05-2022 04:40:15 to 19-05-2022 04:40:19.

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
02:07:21 1 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:22 18684 8192000000 9716736128 10017574912 801199056 8.908 100 83 166 0 828146864 7068016 19879792
02:09:46 20256 8192000000 9760258960 10066407424 837955488 9.279 100 93 186 1 872196336 11568496 22672352
02:11:09 10359 8192000000 9787977360 10110124032 864525328 9.545 100 81 161 2 900062480 17253456 18283696
02:12:21 9795 8192000000 9869503344 10201395200 916306992 10.060 100 82 164 0 970615904 36189424 18119488
02:13:32 7350 8192000000 9896182720 10239791104 961574288 10.504 100 84 163 0 1015510624 37066624 16869712
02:14:44 1692 8192000000 9958576832 10309361664 998148192 10.861 100 95 181 0 1075712800 59482224 18082384
02:15:49 1523 8192000000 10011904320 10372034560 1014732704 11.021 100 90 173 1 1132482320 100356368 17393248
02:16:53 804 8192000000 10008894432 10374234112 1034863568 11.215 100 93 180 0 1126388208 73235744 18288896
02:17:55 802 8192000000 10037325968 10411200512 1056308352 11.421 100 98 187 0 1160638944 85466528 18864064
02:18:56 1160 8192000000 10038643360 10420416512 1064262256 11.497 100 97 182 2 1159262224 77136016 17863952
02:19:59 1126 8192000000 10050783424 10429599744 1071579936 11.567 100 92 185 0 1170678992 80814880 18284176
02:21:00 1087 8192000000 10070038576 10456711168 1078137488 11.630 100 92 184 2 1188426608 91749840 18539280
02:22:02 9879 8192000000 10024366640 10423062528 1080675024 11.654 100 63 118 1 1158783328 67250512 10857792
02:23:14 2109 8192000000 10052948416 10446323712 1087351520 11.717 100 76 140 2 1165246080 63178816 14715744
02:24:17 1159 8192000000 10056024032 10444165120 1091118976 11.753 100 92 175 0 1178098288 69182384 17796928
02:25:19 1175 8192000000 10062162336 10450886656 1105418608 11.889 100 95 181 0 1181517952 57704704 18394640
02:26:22 1195 8192000000 10097368304 10488512512 1102908672 11.865 100 94 186 0 1217695232 95524464 19262096
02:27:24 3456 8192000000 10064889760 10463543296 1100328896 11.841 100 71 134 2 1183410672 69783376 13298400
02:28:28 1070 8192000000 10060128288 10454188032 1098581936 11.824 100 85 160 0 1188163360 72047024 17534400
02:29:30 1250 8192000000 10029270032 10411786240 1080437648 11.652 100 85 168 1 1153033568 56180880 16415040
02:30:32 3898 8192000000 10015391344 10393833472 1064603632 11.501 99 79 148 1 1142109120 63479040 14026448
02:31:37 1182 8192000000 10054271104 10437107712 1081775504 11.664 100 96 184 0 1173512560 73894320 17842736
02:32:40 1199 8192000000 10019812784 10393735168 1049303424 11.354 99 89 173 2 1143309040 76851744 17153872
02:33:42 4172 8192000000 9996451440 10369396736 1040768208 11.272 100 75 140 0 1100993520 45831936 14393376
02:34:47 5125 8192000000 9995552400 10372644864 1027188144 11.141 98 74 131 1 1105550384 66841056 11521184
02:35:53 1118 8192000000 9983085952 10357555200 1036355248 11.230 99 91 170 2 1106041840 52226304 17460288
02:36:55 1058 8192000000 9992520032 10371235840 1041799680 11.282 100 84 155 0 1117003456 58133136 17070640
02:38:01 1954 8192000000 9986270096 10367377408 1044197008 11.305 99 56 105 0 1105671536 52235568 9238960
02:39:14 6535 8192000000 10035389104 10421182464 1085963856 11.704 100 62 121 0 1158119072 59828848 12326368
02:40:26 3373 8192000000 10068891056 10450567168 1091501408 11.757 100 85 155 0 1181622512 74800992 15320112
02:41:36 1106 8192000000 10119947008 10508771328 1114647152 11.976 100 94 186 1 1238706688 103485888 20573648
02:42:41 1667 8192000000 10152135872 10547036160 1125408752 12.078 100 87 168 0 1275971488 131553872 19008864
02:43:45 9253 8192000000 10165476144 10569240576 1125336544 12.077 100 69 133 1 1278480928 138591920 14552464
02:44:59 1310 8192000000 10199354816 10605228032 1136980048 12.187 100 94 181 5 1311631168 154069888 20581232
02:46:04 1936 8192000000 10219696592 10616897536 1147490048 12.286 100 96 185 2 1335498704 166431520 21577136
02:47:08 2197 8192000000 10247668656 10656362496 1158111232 12.386 100 75 144 0 1364221568 190231584 15878752
02:48:14 1677 8192000000 10261008560 10679029760 1171580304 12.512 100 89 164 3 1371159552 181433904 18145344
02:49:19 2439 8192000000 10292455040 10699980800 1179432992 12.585 100 78 151 1 1405790080 209718432 16638656
02:50:26 1982 8192000000 10336258848 10734067712 1167406992 12.473 99 95 180 0 1445171168 256360560 21403616
02:51:32 1551 8192000000 10351859856 10773524480 1186896304 12.654 100 84 161 2 1464516592 258203456 19416832
02:52:39 1326 8192000000 10370203136 10790375424 1184977856 12.637 100 97 183 1 1482064480 276031712 21054912
02:53:45 936 8192000000 10385124064 10805882880 1180118224 12.591 100 91 174 2 1489377520 288561968 20697328
02:54:50 968 8192000000 10419336800 10841858048 1192493248 12.707 100 92 180 0 1527475184 313251488 21730448
02:55:53 1593 8192000000 10425873728 10861101056 1186877168 12.654 100 81 155 0 1535812416 330422160 18513088
02:56:59 9120 8192000000 10418229040 10851000320 1189420112 12.678 100 54 104 1 1536160768 332804480 13936176
02:58:17 4858 8192000000 10400452688 10834386944 1199263312 12.769 100 56 110 0 1513701216 300653056 13784848
02:59:28 14655 8192000000 10372226272 10815111168 1172457904 12.520 98 39 78 0 1464662464 282037440 10167120
03:00:47 16187 8192000000 10428097520 10896461824 1188736976 12.672 100 40 80 0 1544064912 344808144 10519792
03:02:07 5196 8192000000 10431257232 10854236160 1173692800 12.531 100 56 106 0 1536167760 349104944 13370016
03:03:15 8945 8192000000 10458634864 10925215744 1175855968 12.552 100 51 102 0 1565701616 377326384 12519264
03:04:29 9576 8192000000 10324742176 10809606144 1157743056 12.382 100 30 60 0 1457911728 292612224 7556448
03:05:42 42675 8192000000 10202075808 10689236992 1145049264 12.263 100 25 50 1 1340798848 189712192 6037392
03:07:25 31997 8192000000 10165707424 10583011328 1136508272 12.183 99 63 126 2 1252441616 102372256 13561088
03:09:02 28776 8192000000 10186422272 10656661504 1130607920 12.127 98 15 30 0 1275011872 140807696 3596256
03:10:32 69298 8192000000 10221730032 10680958976 1135852400 12.176 99 19 39 1 1279740848 139815632 4072816
03:13:03 66397 8192000000 10064220464 10501763072 1104519744 11.881 97 13 25 1 1181101536 73938160 2643632
03:15:09 56309 8192000000 10082705728 10507771904 1134329248 12.162 99 38 66 2 1172679456 31011504 7338704
03:17:07 57122 8192000000 10073585040 10503995392 1087873040 11.722 96 24 43 1 1147126128 53862768 5390320
03:19:14 70423 8192000000 10057660640 10543689728 1101772224 11.854 98 19 38 0 1196213072 90431072 4009776
03:21:39 39695 8192000000 10086507056 10505560064 1114157968 11.972 98 44 81 1 1175142896 53543952 7440976
03:23:21 132033 8192000000 10053312896 10477891584 1108074576 11.914 98 44 82 0 1178005984 60413312 9518096
03:26:35 65257 8192000000 10090327312 10510741504 1128199760 12.104 99 51 96 2 1172444864 34768992 9476112
03:28:41 31147 8192000000 10131563904 10579505152 1147275040 12.284 100 45 84 3 1243726816 86930672 9521104
03:30:13 45631 8192000000 10139016192 10594070528 1120755904 12.034 99 34 66 0 1226873984 98018032 8100048
03:32:11 7185 8192000000 10103214480 10556747776 1141745072 12.232 100 20 38 3 1216771264 71239296 3786896
03:33:30 158376 8192000000 10172071888 10634190848 1111555984 11.947 97 11 22 0 1183732944 69769664 2407296
03:37:17 54057 8192000000 10213473856 10675445760 1150806208 12.317 99 28 55 3 1311339904 154478736 6054960
03:39:19 45667 8192000000 10181598400 10634985472 1139736272 12.213 98 30 60 1 1256085616 109640544 6708800
03:41:14 41943 8192000000 10169285488 10650734592 1138815904 12.204 98 27 54 0 1238258288 93939024 5503360
03:42:58 27207 8192000000 10147378096 10611331072 1151266992 12.321 99 17 32 0 1235037872 79657472 4113408
03:44:30 54269 8192000000 10144375824 10582900736 1154379040 12.351 99 29 58 0 1255002832 94067760 6556032
03:46:25 26718 8192000000 10094449488 10546561024 1133988400 12.159 99 34 68 2 1197004400 55320672 7695328
03:47:53 58263 8192000000 10091024320 10522755072 1105237696 11.887 96 35 70 0 1149544000 37545984 6760320
03:50:00 66552 8192000000 10095341024 10528555008 1126840816 12.092 99 32 62 0 1181957632 48976736 6140080
03:52:21 36443 8192000000 10105747152 10564186112 1145394288 12.266 100 36 72 0 1208434736 55718224 7322224
03:54:01 61528 8192000000 10082869696 10533281792 1129344896 12.115 99 14 28 0 1173388528 40897264 3146368
03:56:04 89157 8192000000 10102814064 10551300096 1108379168 11.917 98 20 40 0 1178873344 66173664 4320512
03:59:33 62748 8192000000 10085521584 10552107008 1123627808 12.061 99 18 36 1 1242243744 114556432 4059504
04:01:36 28969 8192000000 10135500320 10588483584 1132871904 12.148 99 17 34 0 1221856688 85385488 3599296
04:03:06 39479 8192000000 10151513568 10615304192 1134612720 12.165 99 35 70 1 1253150912 111179072 7359120
04:04:46 72742 8192000000 10148569856 10582495232 1089879024 11.742 95 28 56 3 1206586944 110677248 6030672
04:06:59 55672 8192000000 10058798512 10482769920 1106331216 11.898 99 30 56 1 1137434848 24809232 6294400
04:08:55 37299 8192000000 10070999136 10501083136 1084998224 11.695 96 33 66 2 1141554672 50162992 6393456
04:10:36 89906 8192000000 10078332176 10513727488 1079423952 11.642 96 21 42 0 1113740752 30413056 3903744
04:13:56 72323 8192000000 10103713696 10556035072 1116277104 11.992 99 54 106 1 1193268080 66380992 10609984
04:16:09 62251 8192000000 10067122416 10544549888 1118814576 12.016 100 20 39 1 1163512448 39979136 4718736
04:18:16 65523 8192000000 10080092544 10522656768 1116073424 11.990 99 23 46 1 1176390128 55618384 4698320
04:20:56 95518 8192000000 10080581280 10567954432 1128526976 12.107 99 12 24 1 1195808528 64899840 2381712
04:23:44 33720 8192000000 10088112048 10518216704 1118913536 12.017 99 35 64 2 1181809712 56464304 6431872
04:25:19 54237 8192000000 10063314272 10501808128 1128437216 12.107 100 36 68 1 1184718112 48467040 7813856
04:27:18 38597 8192000000 10111421776 10565738496 1134694208 12.166 100 25 50 0 1213421808 73731744 4995856
04:29:11 46380 8192000000 10150190864 10594963456 1133507344 12.154 99 38 75 0 1236840016 95667936 7664736
04:30:58 29863 8192000000 10078629168 10560655360 1107982288 11.913 98 18 36 1 1199713168 87699904 4030976
04:32:28 31686 8192000000 10097594608 10565451776 1105013952 11.885 98 42 84 1 1191158864 77843792 8301120
04:34:01 119480 8192000000 10119501648 10594844672 1128996448 12.112 100 36 72 0 1228799872 91894656 7908768

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 19-05-2022 04:40:21 to 19-05-2022 04:40:37.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 132 v_add_invoice_to_stock 335544517
except 15 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 19-05-2022 04:40:37 to 19-05-2022 04:40:39.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          217561
MON$OLDEST_ACTIVE               217562
MON$OLDEST_SNAPSHOT             217562
MON$NEXT_TRANSACTION            217562
MON$PAGE_BUFFERS                1000000
MON$SQL_DIALECT                 3
MON$SHUTDOWN_MODE               0
MON$SWEEP_INTERVAL              0
MON$READ_ONLY                   0
MON$FORCED_WRITES               1
MON$RESERVE_SPACE               1
MON$CREATION_DATE               2020-04-30 07:30:08.6030 GMT
MON$PAGES                       2053152
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             768
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2764 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2764 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2764 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2764 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
:333: 
:334: mscs-test-161.budzdorov.ru    Thu May 19 04:38:07 2022
:335:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:336: 
:337: 
:338: mscs-test-161.budzdorov.ru    Thu May 19 04:38:25 2022
:339:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:340: 
--- end of diff output ---

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

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