Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 23.06.2022 13:38
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15701740 kB
MemAvailable 31260148 kB
Buffers 0 kB
Cached 16027972 kB
SwapCached 2496 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-06-23 13:37:19
DTS_END                         2022-06-23 13:37:19.0520
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(68:17)
IP                              ::1/34008
TRN_ID                          224405
ATT_ID                          641
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: 9029.65 25512 1083558
customer order: creation 177.09 454 21251
customer order: refuse 14.95 464 1794
order to supplier: creation 47.51 1415 5701
order to supplier: removal 3.37 199433 404
invoice (draft): creation 47.56 3589 5707
invoice (draft): removal 48.80 1201 5856
invoice accept: apply 43.61 27592 5233
invoice accept: cancel 43.78 1388 5254
customer reserve: creation 4026.53 262 483183
customer reserve: removal 4280.98 121 513718
realization accept: apply 87.15 308 10458
realization accept: cancel 74.05 209 8886
payment from customer: creation 60.95 203 7314
payment from customer: removal 6.05 143 726
payment to supplier: creation 50.14 212 6017
payment to supplier: removal 3.65 167 438
service: total inventory turnovers 3.93 5084 472
service: total monetary turnovers 8.50 413 1020
service: aggregate perf. data 1.02 16245 122
service: refresh index statistics 0.03 251331 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 23-06-2022 13:38:55 to 23-06-2022 13:38:57.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 12930
TEST_TIME 2 8889
TEST_TIME 3 7670
TEST_TIME 4 7033
TEST_TIME 5 6524
TEST_TIME 6 6501
TEST_TIME 7 6564
TEST_TIME 8 6513
TEST_TIME 9 6756
TEST_TIME 10 6638
TEST_TIME 11 6838
TEST_TIME 12 7054
TEST_TIME 13 7055
TEST_TIME 14 7048
TEST_TIME 15 7289
TEST_TIME 16 7272
TEST_TIME 17 7612
TEST_TIME 18 7471
TEST_TIME 19 7492
TEST_TIME 20 7509
TEST_TIME 21 7418
TEST_TIME 22 7455
TEST_TIME 23 7671
TEST_TIME 24 7684
TEST_TIME 25 7643
TEST_TIME 26 7626
TEST_TIME 27 7842
TEST_TIME 28 7837
TEST_TIME 29 7848
TEST_TIME 30 7951
TEST_TIME 31 8041
TEST_TIME 32 8622
TEST_TIME 33 8980
TEST_TIME 34 9122
TEST_TIME 35 9464
TEST_TIME 36 9603
TEST_TIME 37 9855
TEST_TIME 38 10023
TEST_TIME 39 9996
TEST_TIME 40 10322
TEST_TIME 41 10390
TEST_TIME 42 10268
TEST_TIME 43 10263
TEST_TIME 44 10179
TEST_TIME 45 10198
TEST_TIME 46 10154
TEST_TIME 47 10049
TEST_TIME 48 9982
TEST_TIME 49 9981
TEST_TIME 50 9952
TEST_TIME 51 9926
TEST_TIME 52 9769
TEST_TIME 53 9684
TEST_TIME 54 9614
TEST_TIME 55 9589
TEST_TIME 56 9538
TEST_TIME 57 9452
TEST_TIME 58 9444
TEST_TIME 59 9396
TEST_TIME 60 9334
TEST_TIME 61 9426
TEST_TIME 62 9524
TEST_TIME 63 9526
TEST_TIME 64 9498
TEST_TIME 65 9479
TEST_TIME 66 9412
TEST_TIME 67 9305
TEST_TIME 68 9270
TEST_TIME 69 9197
TEST_TIME 70 9169
TEST_TIME 71 9124
TEST_TIME 72 9060
TEST_TIME 73 9053
TEST_TIME 74 9158
TEST_TIME 75 9125
TEST_TIME 76 9144
TEST_TIME 77 9163
TEST_TIME 78 9184
TEST_TIME 79 9197
TEST_TIME 80 9209
TEST_TIME 81 9174
TEST_TIME 82 9296
TEST_TIME 83 9320
TEST_TIME 84 9306
TEST_TIME 85 9306
TEST_TIME 86 9280
TEST_TIME 87 9265
TEST_TIME 88 9205
TEST_TIME 89 9217
TEST_TIME 90 9149
TEST_TIME 91 9113
TEST_TIME 92 9058
TEST_TIME 93 9121
TEST_TIME 94 9122
TEST_TIME 95 9122
TEST_TIME 96 9098
TEST_TIME 97 9069
TEST_TIME 98 9093
TEST_TIME 99 9074
TEST_TIME 100 9070
TEST_TIME 101 9085
TEST_TIME 102 9149
TEST_TIME 103 9140
TEST_TIME 104 9251
TEST_TIME 105 9269
TEST_TIME 106 9434
TEST_TIME 107 9420
TEST_TIME 108 9351
TEST_TIME 109 9331
TEST_TIME 110 9295
TEST_TIME 111 9288
TEST_TIME 112 9237
TEST_TIME 113 9206
TEST_TIME 114 9169
TEST_TIME 115 9140
TEST_TIME 116 9126
TEST_TIME 117 9124
TEST_TIME 118 9083
TEST_TIME 119 9096
TEST_TIME 120 9058

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 23-06-2022 13:38:59 to 23-06-2022 13:39:02.

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 1527700 1527700 0 0.00 0 34863 16 0 0
doc_list_biud 1527693 1527693 0 0.00 0 20708 47 0 0
sp_add_invoice_to_stock 5360 5233 127 2.36 12 220653 27592 0 127
sp_cancel_adding_invoice 5254 5254 0 0.00 6 69577 1388 0 0
sp_cancel_client_order 1794 1794 0 0.00 6 8755 465 0 0
sp_cancel_customer_reserve 513718 513718 0 0.00 1 16439 122 0 0
sp_cancel_pay_from_customer 726 726 0 0.00 3 4490 144 0 0
sp_cancel_pay_to_supplier 438 438 0 0.00 3 5357 167 0 0
sp_cancel_supplier_invoice 5865 5856 9 0.15 3 14805 1201 0 9
sp_cancel_supplier_order 404 404 0 0.00 7 1622652 199433 0 0
sp_cancel_write_off 8886 8886 0 0.00 2 13461 209 0 0
sp_client_order 21251 21251 0 0.00 6 25652 455 0 0
sp_customer_reserve 483183 483183 0 0.00 1 55449 263 0 0
sp_fill_shopping_cart 32647 32647 0 0.00 1 12816 89 0 0
sp_fill_shopping_cart_clo_res 483159 483159 0 0.00 0 20447 21 0 0
sp_get_clo_for_invoice 5201 5201 0 0.00 0 32316 283 0 0
sp_kill_cost_storno 15304 15304 0 0.00 0 10157 96 0 0
sp_kill_qstorno_ret_qs2qd 1042582 1042582 0 0.00 0 16434 52 0 0
sp_kill_qty_storno 551567 551567 0 0.00 0 20707 121 0 0
sp_lock_selected_doc 21482 21482 0 0.00 0 7758 7 0 0
sp_make_cost_storno 28988 28988 0 0.00 0 34861 86 0 0
sp_multiply_rows_for_pdistr 28987 28987 0 0.00 0 26254 45 0 0
sp_multiply_rows_for_qdistr 21251 21251 0 0.00 0 14592 162 0 0
sp_pay_from_customer 7314 7314 0 0.00 5 26262 203 0 0
sp_pay_to_supplier 6017 6017 0 0.00 7 34869 213 0 0
sp_payment_common 13331 13331 0 0.00 2 6172 47 0 0
sp_qd_handle_on_cancel_clo 1794 1794 0 0.00 0 8727 381 0 0
sp_qd_handle_on_invoice_upd_sts 10453 10453 0 0.00 1 18608 621 0 0
sp_qd_handle_on_reserve_upd_sts 10456 10456 0 0.00 0 20706 72 0 0
sp_reserve_write_off 10458 10458 0 0.00 9 20778 308 0 0
sp_supplier_invoice 5707 5707 0 0.00 10 98220 3589 0 0
sp_supplier_order 5701 5701 0 0.00 9 30966 1416 0 0
srv_aggregate_perf_data 122 122 0 0.00 2545 190221 16245 0 0
srv_fill_mon_cache_memory 77 77 0 0.00 847 122365 28183 0 0
srv_find_qd_qs_mism 1032394 1032394 0 0.00 0 21772 12 0 0
srv_make_invnt_saldo 472 472 0 0.00 47 65785 5084 0 0
srv_make_money_saldo 1020 1020 0 0.00 1 16584 414 0 0
srv_recalc_idx_stat 4 4 0 0.00 53803 431483 251332 0 0
t$perf-abend:sp_add_invoice_to_stock 127 127 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5242 5242 0 0.00 0 11325 1067 0 0
t$perf-norm:sp_cancel_adding_invoice 404 404 0 0.00 2 6687 437 0 0
t$perf-norm:sp_cancel_client_order 1794 1794 0 0.00 0 1696 31 0 0
t$perf-norm:sp_cancel_customer_reserve 680 680 0 0.00 0 1430 33 0 0
t$perf-norm:sp_cancel_pay_from_customer 726 726 0 0.00 0 1379 19 0 0
t$perf-norm:sp_cancel_pay_to_supplier 438 438 0 0.00 0 299 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 372 372 0 0.00 0 806 44 0 0
t$perf-norm:sp_cancel_supplier_order 404 404 0 0.00 0 95029 8136 0 0
t$perf-norm:sp_cancel_write_off 1014 1014 0 0.00 0 3243 26 0 0
t$perf-norm:sp_client_order 21253 21253 0 0.00 0 5513 43 0 0
t$perf-norm:sp_customer_reserve 1012 1012 0 0.00 0 3380 21 0 0
t$perf-norm:sp_pay_from_customer 7316 7316 0 0.00 0 7816 22 0 0
t$perf-norm:sp_pay_to_supplier 6017 6017 0 0.00 0 3899 23 0 0
t$perf-norm:sp_reserve_write_off 10458 10458 0 0.00 0 11270 27 0 0
t$perf-norm:sp_supplier_invoice 5708 5708 0 0.00 0 3612 39 0 0
t$perf-norm:sp_supplier_order 5702 5702 0 0.00 0 3884 38 0 0
t$perf-norm:srv_aggregate_perf_data 122 122 0 0.00 0 1085 31 0 0
t$perf-norm:srv_fill_mon_cache_memory 77 77 0 0.00 0 3021 49 0 0
t$perf-norm:srv_make_invnt_saldo 472 472 0 0.00 0 515 16 0 0
t$perf-norm:srv_make_money_saldo 1020 1020 0 0.00 0 464 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 250 92 0 0
v_add_invoice_to_stock 10642 10388 254 2.38 0 4403 26 0 254
v_all_customers 38878 38878 0 0.00 0 66 4 0 0
v_all_suppliers 31781 31781 0 0.00 0 87 5 0 0
v_all_wares 42494 42494 0 0.00 0 52 4 0 0
v_cancel_adding_invoice 808 808 0 0.00 0 1162 18 0 0
v_cancel_client_order 3588 3588 0 0.00 0 2215 12 0 0
v_cancel_customer_prepayment 1452 1452 0 0.00 0 4400 15 0 0
v_cancel_customer_reserve 1360 1360 0 0.00 1 1087 12 0 0
v_cancel_payment_to_supplier 876 876 0 0.00 0 3845 17 0 0
v_cancel_supplier_invoice 762 744 18 2.36 0 2732 33 0 18
v_cancel_supplier_order 808 808 0 0.00 0 838 13 0 0
v_cancel_write_off 2028 2028 0 0.00 0 4636 32 0 0
v_max_id_clo_ord 5696 5696 0 0.00 0 640 6 0 0
v_max_id_clo_res 1011 1011 0 0.00 0 8346 19 0 0
v_max_id_ord_sup 5701 5701 0 0.00 0 1732 7 0 0
v_max_non_paid_invoice 1522 1522 0 0.00 0 5010 11 0 0
v_max_non_paid_realizn 3872 3872 0 0.00 0 761 8 0 0
v_min_id_clo_ord 5696 5696 0 0.00 0 1600 10 0 0
v_min_id_clo_res 1011 1011 0 0.00 0 6482 16 0 0
v_min_id_ord_sup 5700 5700 0 0.00 0 7847 10 0 0
v_min_non_paid_invoice 6017 6017 0 0.00 0 5425 14 0 0
v_min_non_paid_realizn 7314 7314 0 0.00 0 5235 16 0 0
v_our_firm 10500 10500 0 0.00 0 87 5 0 0
v_reserve_write_off 20908 20908 0 0.00 0 4844 12 0 0
x_lock_depdocs_on_canc_invoice 10738 10738 0 0.00 0 13859 135 0 0
x_lock_depdocs_on_canc_sup_ord 404 404 0 0.00 0 10960 409 0 0
x_make_qstorno_1000_1200 5701 5701 0 0.00 3 30955 1221 0 0
x_make_qstorno_1200_2000 5707 5707 0 0.00 4 98141 3358 0 0
x_make_qstorno_2100_3300 483182 483182 0 0.00 0 55427 227 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 23-06-2022 13:39:03 to 23-06-2022 13:39:07.

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
11:07:22 4 8192000000 8800085920 8811831296 5692352 0.069 2 2 3 0 5857616 103904 61360
11:08:24 11571 8192000000 9721394944 10019921920 806049088 8.958 100 91 182 0 837075168 8269168 22756912
11:09:46 10282 8192000000 9769012112 10078261248 847477872 9.375 100 88 176 0 880052928 12160784 20414272
11:11:05 17774 8192000000 9802057712 10119905280 874985552 9.650 100 84 168 0 912599760 18720848 18893360
11:12:31 11948 8192000000 9863949776 10191073280 907889600 9.976 100 89 174 2 972821312 45612560 19319152
11:13:46 11315 8192000000 9914195856 10252705792 936626656 10.260 100 94 186 0 1029290112 72733728 19929728
11:14:59 5229 8192000000 9945970832 10291769344 968273824 10.570 100 87 172 0 1052179856 65845024 18061008
11:16:07 1256 8192000000 9991003824 10345291776 1003391552 10.911 100 97 188 1 1108543600 85941328 19210720
11:17:11 2246 8192000000 9999607632 10364706816 1031484912 11.183 100 86 163 1 1122674848 75103744 16086192
11:18:16 1128 8192000000 10021807072 10388684800 1055702416 11.415 100 89 173 0 1139474800 65820640 17951744
11:19:18 1113 8192000000 10043945376 10417680384 1066891632 11.522 100 99 191 1 1163551376 77969184 18690560
11:20:21 1216 8192000000 10034832608 10412847104 1077124544 11.620 100 95 185 1 1157149488 61819264 18205680
11:21:25 1258 8192000000 10038163088 10416844800 1078829632 11.636 100 99 188 3 1159931360 61031856 20069872
11:22:28 806 8192000000 10052460784 10438172672 1085297456 11.698 100 94 171 1 1171917856 70780864 15839536
11:23:30 782 8192000000 10048612896 10429771776 1088568896 11.729 100 97 188 0 1167026992 59461568 18996528
11:24:34 1260 8192000000 10071473664 10457042944 1090510464 11.748 100 96 182 2 1196840720 87650208 18680048
11:25:37 1383 8192000000 10087415456 10476707840 1102208944 11.859 100 98 192 1 1216900160 94149472 20541744
11:26:39 1033 8192000000 10056852240 10444455936 1092216560 11.764 100 91 168 0 1179606016 70702336 16687120
11:27:41 1175 8192000000 10062525136 10453581824 1099225008 11.830 100 98 187 1 1183641776 65808400 18608368
11:28:43 9811 8192000000 10046847536 10448777216 1093176848 11.773 100 56 108 0 1169860896 66071536 10612512
11:29:56 1184 8192000000 10093218432 10480988160 1095789680 11.798 100 98 194 2 1216276592 101085216 19401696
11:30:58 1199 8192000000 10051628976 10434199552 1080431920 11.652 100 97 178 1 1174288864 74375392 19481552
11:32:00 1585 8192000000 10044568944 10427027456 1073943728 11.590 100 93 189 3 1160805696 68155616 18706352
11:33:03 1268 8192000000 10055374816 10442579968 1069942464 11.552 100 94 180 1 1174193424 85209200 19041760
11:34:07 1423 8192000000 9971503952 10348515328 1028953328 11.158 100 74 138 4 1096046320 53245264 13847728
11:35:09 791 8192000000 9979875184 10360066048 1052858912 11.388 100 78 154 1 1104254560 34964304 16431344
11:36:11 6107 8192000000 9988264016 10368167936 1041357184 11.278 99 63 111 1 1114258576 62269024 10632368
11:37:18 1066 8192000000 9987456560 10361147392 1032543280 11.193 100 89 168 1 1110599184 60380992 17674912
11:38:23 5635 8192000000 10020732336 10403856384 1067046992 11.524 100 74 143 2 1148083056 67077808 13958256
11:39:40 8477 8192000000 10055525088 10438078464 1079926592 11.647 100 77 150 0 1175553664 80349936 15277136
11:40:52 3774 8192000000 10067225328 10445418496 1085606640 11.701 100 79 155 0 1187880688 86483168 15790880
11:42:00 6850 8192000000 10040838416 10430390272 1066959728 11.523 98 55 109 0 1158208112 79139008 12109376
11:43:19 7460 8192000000 10107983232 10500911104 1105395248 11.889 100 65 123 1 1220774656 102144672 13234736
11:44:32 2885 8192000000 10130515344 10526384128 1121179216 12.038 100 77 143 1 1244279024 109469456 13630352
11:45:40 995 8192000000 10171133024 10565599232 1136989152 12.187 100 94 176 2 1286529744 129699680 19840912
11:46:46 6269 8192000000 10196634144 10600087552 1146184432 12.274 100 75 148 0 1311864304 150383312 15296560
11:48:01 1741 8192000000 10210222096 10623422464 1154025632 12.347 100 93 178 0 1327222896 152796832 20400432
11:49:09 1943 8192000000 10220192336 10622504960 1159851936 12.402 100 92 178 1 1329628560 150479904 19296720
11:50:14 2415 8192000000 10255367680 10656899072 1169738128 12.494 100 84 161 1 1364782704 177656640 17387936
11:51:22 2684 8192000000 10279728240 10693586944 1177696352 12.569 100 80 157 0 1395622560 200685456 17240752
11:52:32 850 8192000000 10322808592 10752565248 1189560496 12.679 100 96 191 1 1426681648 214768064 22353088
11:53:37 1365 8192000000 10314763952 10747576320 1197873584 12.757 100 95 185 3 1423426224 204210160 21342480
11:54:42 954 8192000000 10333976416 10761216000 1197881584 12.757 100 85 164 0 1444953792 228876256 18195952
11:55:48 2366 8192000000 10375473216 10787979264 1199269744 12.770 100 72 144 0 1485345824 269607456 16468624
11:56:59 1340 8192000000 10387555056 10797666304 1191364336 12.696 100 90 168 0 1493565680 282637712 19563632
11:58:03 1703 8192000000 10422691120 10826973184 1186103744 12.647 100 88 168 1 1527604624 322372720 19128160
11:59:11 1221 8192000000 10467634272 10883235840 1204116480 12.815 100 93 181 1 1574728816 348358912 22253424
12:00:16 993 8192000000 10481796720 10898739200 1212095872 12.889 100 96 188 4 1588874352 353460272 23318208
12:01:22 1123 8192000000 10504532000 10922811392 1210540992 12.874 100 96 188 3 1606104480 372090464 23473024
12:02:30 858 8192000000 10545044640 10954313728 1212141264 12.889 100 97 190 3 1647668256 412100176 23426816
12:03:34 867 8192000000 10582983264 11002068992 1213562880 12.902 100 99 196 2 1685226320 447942080 23721360
12:04:39 1105 8192000000 10563351536 11020029952 1194385328 12.724 100 82 162 1 1671117984 456105840 20626816
12:05:45 830 8192000000 10585445024 11031420928 1204947040 12.822 100 91 177 0 1690174864 463240272 21987552
12:06:51 1371 8192000000 10608723600 11020640256 1181402096 12.603 100 96 191 2 1710761760 505385120 23974544
12:07:55 1449 8192000000 10581278816 11049205760 1180358480 12.594 100 93 185 1 1684259392 480179008 23721904
12:08:58 4118 8192000000 10587120304 11073122304 1188364736 12.668 99 75 150 0 1715601312 507265280 19971296
12:10:28 7762 8192000000 10485000592 10948280320 1193021296 12.711 100 51 100 1 1584487520 378710256 12755968
12:11:40 30119 8192000000 10431622800 10906480640 1167838336 12.477 99 34 68 0 1535696720 358529184 9329200
12:13:43 40348 8192000000 10306109600 10819252224 1147896640 12.290 98 23 46 3 1434115504 280281472 5937392
12:15:24 68138 8192000000 10230659984 10709618688 1128529312 12.107 98 19 38 0 1347767040 213866720 5371008
12:17:37 30592 8192000000 10101326432 10633580544 1120091200 12.028 99 16 32 1 1233368352 109151376 4125776
12:19:28 47714 8192000000 10068555072 10534465536 1117432336 12.003 99 37 67 1 1171010512 46914560 6663616
12:21:17 15461 8192000000 10076933184 10516205568 1136077616 12.179 100 26 51 1 1180338720 39335936 4925168
12:22:42 122365 8192000000 10083201264 10552381440 1083509920 11.681 95 8 16 1 1125849328 40972208 1367200
12:25:46 31736 8192000000 10055358864 10502934528 1118700096 12.015 99 24 48 0 1154001568 30218416 5083056
12:27:24 69942 8192000000 10064039664 10505199616 1100647408 11.844 97 36 70 2 1137247888 27839136 8761344
12:29:36 103671 8192000000 10086766560 10531721216 1108686352 11.920 98 44 84 2 1156007472 38620288 8700832
12:32:25 10033 8192000000 10101660080 10530074624 1113918400 11.969 99 67 121 4 1193641488 65928800 13794288
12:33:38 26809 8192000000 10077250224 10528522240 1125458000 12.079 100 32 63 1 1172344416 39922864 6963552
12:35:13 16942 8192000000 10125126192 10555564032 1109276464 11.926 98 46 88 0 1200429328 81894304 9258560
12:36:31 55841 8192000000 10123458272 10567450624 1117841232 12.007 99 32 60 2 1214213232 89913008 6458992
12:38:30 16513 8192000000 10202665184 10667646976 1145655936 12.269 100 51 102 0 1285396960 128869472 10871552
12:40:07 30416 8192000000 10052650288 10525904896 1123316304 12.058 99 15 30 1 1175665040 49468064 2880672
12:41:40 63273 8192000000 10068023600 10536525824 1129484976 12.117 99 9 18 0 1166751072 35401584 1864512
12:43:46 64784 8192000000 10054961488 10521948160 1129312112 12.115 99 7 14 0 1145248848 14493008 1443728
12:45:57 38652 8192000000 10070445136 10518020096 1137071664 12.188 99 16 32 0 1172101648 32046736 2983248
12:47:46 58833 8192000000 10086511584 10535649280 1111319648 11.945 98 19 38 3 1180527888 64940128 4268112
12:49:47 67165 8192000000 10136469680 10649255936 1135223712 12.171 100 12 24 0 1251288272 113347904 2716656
12:51:55 18368 8192000000 10085815408 10554724352 1137717744 12.194 100 39 78 1 1174460912 28783792 7959376
12:53:15 26606 8192000000 10100436000 10548789248 1122073520 12.047 99 40 80 1 1186913984 56792784 8047680
12:54:42 8478 8192000000 10082513056 10511843328 1112170784 11.953 99 48 95 1 1163526560 41787696 9568080
12:55:51 8884 8192000000 10083621792 10498506752 1106394336 11.898 99 68 128 0 1167951472 48762032 12795104
12:57:01 26088 8192000000 10133791472 10568638464 1115318000 11.983 100 45 90 0 1213396512 89118048 8960464
12:58:34 29169 8192000000 10119195776 10581098496 1107714752 11.911 99 38 74 0 1205967952 89505264 8747936
13:00:04 49263 8192000000 10072231280 10505969664 1113972896 11.970 100 34 68 0 1173100720 52105472 7022352
13:01:57 29112 8192000000 10137612816 10577874944 1126491136 12.088 100 55 110 1 1203101248 65323504 11286608
13:03:27 75524 8192000000 10053048288 10525278208 1095999808 11.800 98 26 52 1 1170317392 69138704 5178880
13:06:13 46207 8192000000 10051690080 10533355520 1118179344 12.010 100 38 75 1 1198231648 72323744 7728560
13:08:02 54584 8192000000 10100759616 10568499200 1122845216 12.054 99 15 30 0 1181939072 56280112 2813744
13:09:57 76848 8192000000 10058582928 10564517888 1103349232 11.869 97 10 20 1 1178424336 72926592 2148512
13:12:14 29129 8192000000 10111245728 10552152064 1116797408 11.997 98 31 57 3 1184751424 61902896 6051120
13:13:49 25760 8192000000 10138395856 10565758976 1108580624 11.919 97 34 68 3 1191445712 75474800 7390288
13:15:24 19037 8192000000 10242227360 10678648832 1152327040 12.331 100 51 102 0 1316622128 152946768 11348320
13:16:47 36041 8192000000 10266945968 10721001472 1160993952 12.413 100 42 84 0 1363527392 193045872 9487568
13:18:31 31681 8192000000 10291968832 10742140928 1163138256 12.433 100 22 44 2 1379995024 211368240 5488528
13:20:07 28579 8192000000 10310798272 10782089216 1158925360 12.393 99 30 59 2 1397391568 231392656 7073552
13:21:37 68546 8192000000 10221204224 10695356416 1131201136 12.133 98 21 42 0 1355364560 219035152 5128272
13:23:46 23115 8192000000 10101080384 10538553344 1137644400 12.193 100 46 90 1 1194405392 46563232 10197760
13:25:10 60590 8192000000 10128302608 10563121152 1138423648 12.201 100 54 105 0 1224138000 74982256 10732096
13:27:18 77990 8192000000 10084733168 10586415104 1141858704 12.233 100 6 12 0 1187081456 44212400 1010352
13:29:40 65194 8192000000 10092384896 10547466240 1132135680 12.141 99 26 52 1 1173523312 35976608 5411024
13:31:45 54830 8192000000 10151470960 10607943680 1140185728 12.217 99 28 56 3 1236648432 90169584 6293120
13:33:44 48989 8192000000 10170751408 10632327168 1129254064 12.114 98 30 60 0 1247931392 111853296 6824032
13:35:35 59319 8192000000 10191552688 10640338944 1131554272 12.136 98 31 62 1 1275771264 137076624 7140368

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 23-06-2022 13:39:09 to 23-06-2022 13:39:26.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 127 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 23-06-2022 13:39:27 to 23-06-2022 13:39:29.

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          216130
MON$OLDEST_ACTIVE               224515
MON$OLDEST_SNAPSHOT             224515
MON$NEXT_TRANSACTION            224515
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                       2105280
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             772
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2782 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2782 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2782 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2782 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 ---
:116: 
:117: mscs-test-161.budzdorov.ru    Thu Jun 23 13:37:19 2022
:118:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:119: 
:120: 
:121: mscs-test-161.budzdorov.ru    Thu Jun 23 13:37:32 2022
:122:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:123: 
:124: 
:125: mscs-test-161.budzdorov.ru    Thu Jun 23 13:37:32 2022
:126:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:127: 
:128: 
:129: mscs-test-161.budzdorov.ru    Thu Jun 23 13:37:34 2022
:130:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:131: 
:132: 
:133: mscs-test-161.budzdorov.ru    Thu Jun 23 13:37:34 2022
:134:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:135: 
:136: 
:137: mscs-test-161.budzdorov.ru    Thu Jun 23 13:37:34 2022
:138:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:139: 
--- end of diff output ---

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

23.06.22 13:39:30. 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-06-23 13:39:32 No severe PSQL-related problems occured
23.06.22 13:39:34. End of report.