Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 08.04.2022 10: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 16085808 kB
MemAvailable 31576732 kB
Buffers 0 kB
Cached 15639268 kB
SwapCached 2280 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-04-08 10:37:12
DTS_END                         2022-04-08 10:37:12.5840
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/56830
TRN_ID                          214713
ATT_ID                          583
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: 8919.21 23078 1070305
customer order: creation 165.88 516 19906
customer order: refuse 13.73 508 1648
order to supplier: creation 44.05 1574 5286
order to supplier: removal 3.49 179549 419
invoice (draft): creation 43.54 3542 5225
invoice (draft): removal 51.94 1162 6233
invoice accept: apply 40.08 24869 4809
invoice accept: cancel 46.77 1414 5612
customer reserve: creation 3647.25 237 437670
customer reserve: removal 4573.66 105 548839
realization accept: apply 79.80 318 9576
realization accept: cancel 83.88 168 10065
payment from customer: creation 57.45 208 6894
payment from customer: removal 5.92 138 710
payment to supplier: creation 46.54 218 5585
payment to supplier: removal 3.46 190 415
service: total inventory turnovers 3.30 5173 396
service: total monetary turnovers 7.51 426 901
service: aggregate perf. data 0.93 18546 111
service: refresh index statistics 0.04 222702 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 08-04-2022 10:38:37 to 08-04-2022 10:38:40.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6780
TEST_TIME 2 6009
TEST_TIME 3 5924
TEST_TIME 4 6493
TEST_TIME 5 6204
TEST_TIME 6 6061
TEST_TIME 7 6032
TEST_TIME 8 5939
TEST_TIME 9 6261
TEST_TIME 10 6457
TEST_TIME 11 6609
TEST_TIME 12 6851
TEST_TIME 13 6877
TEST_TIME 14 6779
TEST_TIME 15 6626
TEST_TIME 16 6665
TEST_TIME 17 6719
TEST_TIME 18 6731
TEST_TIME 19 6905
TEST_TIME 20 7066
TEST_TIME 21 7192
TEST_TIME 22 7115
TEST_TIME 23 7061
TEST_TIME 24 7151
TEST_TIME 25 7567
TEST_TIME 26 7869
TEST_TIME 27 8576
TEST_TIME 28 8903
TEST_TIME 29 8943
TEST_TIME 30 8986
TEST_TIME 31 9151
TEST_TIME 32 9290
TEST_TIME 33 9230
TEST_TIME 34 9267
TEST_TIME 35 9548
TEST_TIME 36 9949
TEST_TIME 37 10056
TEST_TIME 38 9895
TEST_TIME 39 10231
TEST_TIME 40 10378
TEST_TIME 41 10461
TEST_TIME 42 10510
TEST_TIME 43 10430
TEST_TIME 44 10274
TEST_TIME 45 10217
TEST_TIME 46 10182
TEST_TIME 47 10144
TEST_TIME 48 10041
TEST_TIME 49 10034
TEST_TIME 50 10110
TEST_TIME 51 10151
TEST_TIME 52 10027
TEST_TIME 53 9873
TEST_TIME 54 9788
TEST_TIME 55 9705
TEST_TIME 56 9611
TEST_TIME 57 9492
TEST_TIME 58 9454
TEST_TIME 59 9407
TEST_TIME 60 9379
TEST_TIME 61 9405
TEST_TIME 62 9467
TEST_TIME 63 9473
TEST_TIME 64 9499
TEST_TIME 65 9465
TEST_TIME 66 9379
TEST_TIME 67 9408
TEST_TIME 68 9429
TEST_TIME 69 9415
TEST_TIME 70 9360
TEST_TIME 71 9372
TEST_TIME 72 9457
TEST_TIME 73 9486
TEST_TIME 74 9532
TEST_TIME 75 9509
TEST_TIME 76 9437
TEST_TIME 77 9470
TEST_TIME 78 9594
TEST_TIME 79 9618
TEST_TIME 80 9676
TEST_TIME 81 9572
TEST_TIME 82 9555
TEST_TIME 83 9449
TEST_TIME 84 9418
TEST_TIME 85 9413
TEST_TIME 86 9355
TEST_TIME 87 9296
TEST_TIME 88 9265
TEST_TIME 89 9244
TEST_TIME 90 9231
TEST_TIME 91 9183
TEST_TIME 92 9161
TEST_TIME 93 9094
TEST_TIME 94 9163
TEST_TIME 95 9154
TEST_TIME 96 9134
TEST_TIME 97 9134
TEST_TIME 98 9181
TEST_TIME 99 9185
TEST_TIME 100 9166
TEST_TIME 101 9201
TEST_TIME 102 9225
TEST_TIME 103 9200
TEST_TIME 104 9246
TEST_TIME 105 9363
TEST_TIME 106 9387
TEST_TIME 107 9338
TEST_TIME 108 9271
TEST_TIME 109 9218
TEST_TIME 110 9193
TEST_TIME 111 9210
TEST_TIME 112 9162
TEST_TIME 113 9142
TEST_TIME 114 9142
TEST_TIME 115 9086
TEST_TIME 116 9059
TEST_TIME 117 9067
TEST_TIME 118 9013
TEST_TIME 119 9010
TEST_TIME 120 8944

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 08-04-2022 10:38:41 to 08-04-2022 10:38:44.

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 1475078 1475078 0 0.00 0 38973 13 0 0
doc_list_biud 1475066 1475066 0 0.00 0 33265 46 0 0
sp_add_invoice_to_stock 4938 4809 129 2.61 53 190259 24870 0 129
sp_cancel_adding_invoice 5612 5612 0 0.00 6 105828 1414 0 0
sp_cancel_client_order 1648 1648 0 0.00 7 12105 508 0 0
sp_cancel_customer_reserve 548839 548839 0 0.00 1 26984 105 0 0
sp_cancel_pay_from_customer 710 710 0 0.00 4 2771 139 0 0
sp_cancel_pay_to_supplier 415 415 0 0.00 4 18136 191 0 0
sp_cancel_supplier_invoice 6241 6233 8 0.12 3 33269 1162 0 8
sp_cancel_supplier_order 419 419 0 0.00 11 1439772 179549 0 0
sp_cancel_write_off 10065 10065 0 0.00 2 8067 168 0 0
sp_client_order 19906 19906 0 0.00 5 40349 516 0 0
sp_customer_reserve 437670 437670 0 0.00 1 122564 237 0 0
sp_fill_shopping_cart 30401 30401 0 0.00 1 13353 93 0 0
sp_fill_shopping_cart_clo_res 437637 437637 0 0.00 0 28312 21 0 0
sp_get_clo_for_invoice 4761 4761 0 0.00 0 29211 300 0 0
sp_kill_cost_storno 16802 16802 0 0.00 0 38961 95 0 0
sp_kill_qstorno_ret_qs2qd 1114395 1114395 0 0.00 0 33264 46 0 0
sp_kill_qty_storno 587152 587152 0 0.00 0 33264 107 0 0
sp_lock_selected_doc 19930 19930 0 0.00 0 8392 8 0 0
sp_make_cost_storno 26816 26816 0 0.00 0 26209 95 0 0
sp_multiply_rows_for_pdistr 26816 26816 0 0.00 0 7360 43 0 0
sp_multiply_rows_for_qdistr 19904 19904 0 0.00 0 10764 188 0 0
sp_pay_from_customer 6894 6894 0 0.00 5 32062 209 0 0
sp_pay_to_supplier 5585 5585 0 0.00 7 19061 218 0 0
sp_payment_common 12479 12479 0 0.00 2 11680 52 0 0
sp_qd_handle_on_cancel_clo 1648 1648 0 0.00 0 12095 414 0 0
sp_qd_handle_on_invoice_upd_sts 10372 10372 0 0.00 0 16393 600 0 0
sp_qd_handle_on_reserve_upd_sts 9576 9576 0 0.00 0 13684 69 0 0
sp_reserve_write_off 9576 9576 0 0.00 9 32242 318 0 0
sp_supplier_invoice 5225 5225 0 0.00 9 89487 3543 0 0
sp_supplier_order 5286 5286 0 0.00 8 75309 1574 0 0
srv_aggregate_perf_data 111 111 0 0.00 2421 238918 18547 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 859 200697 40047 0 0
srv_find_qd_qs_mism 1024327 1024327 0 0.00 0 11367 9 0 0
srv_make_invnt_saldo 396 396 0 0.00 47 56352 5173 0 0
srv_make_money_saldo 901 901 0 0.00 2 8991 426 0 0
srv_recalc_idx_stat 5 5 0 0.00 82950 355875 222702 0 0
t$perf-abend:sp_add_invoice_to_stock 129 129 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4812 4812 0 0.00 1 10194 1038 0 0
t$perf-norm:sp_cancel_adding_invoice 418 418 0 0.00 1 6791 539 0 0
t$perf-norm:sp_cancel_client_order 1648 1648 0 0.00 0 4563 33 0 0
t$perf-norm:sp_cancel_customer_reserve 613 613 0 0.00 0 2835 29 0 0
t$perf-norm:sp_cancel_pay_from_customer 710 710 0 0.00 0 950 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 415 415 0 0.00 0 1474 20 0 0
t$perf-norm:sp_cancel_supplier_invoice 399 399 0 0.00 0 1316 42 0 0
t$perf-norm:sp_cancel_supplier_order 419 419 0 0.00 1 124848 8576 0 0
t$perf-norm:sp_cancel_write_off 975 975 0 0.00 0 2319 23 0 0
t$perf-norm:sp_client_order 19906 19906 0 0.00 0 5314 40 0 0
t$perf-norm:sp_customer_reserve 925 925 0 0.00 0 1934 18 0 0
t$perf-norm:sp_pay_from_customer 6894 6894 0 0.00 0 4887 24 0 0
t$perf-norm:sp_pay_to_supplier 5585 5585 0 0.00 0 7617 24 0 0
t$perf-norm:sp_reserve_write_off 9576 9576 0 0.00 0 6323 27 0 0
t$perf-norm:sp_supplier_invoice 5225 5225 0 0.00 0 6936 37 0 0
t$perf-norm:sp_supplier_order 5286 5286 0 0.00 0 2150 36 0 0
t$perf-norm:srv_aggregate_perf_data 111 111 0 0.00 0 952 33 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 55 4 0 0
t$perf-norm:srv_make_invnt_saldo 396 396 0 0.00 0 790 24 0 0
t$perf-norm:srv_make_money_saldo 901 901 0 0.00 0 4410 12 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 7 196 107 0 0
v_add_invoice_to_stock 9771 9513 258 2.64 0 30062 35 0 258
v_all_customers 36326 36326 0 0.00 0 99 4 0 0
v_all_suppliers 29426 29426 0 0.00 0 86 5 0 0
v_all_wares 39802 39802 0 0.00 0 125 4 0 0
v_cancel_adding_invoice 836 836 0 0.00 0 3460 21 0 0
v_cancel_client_order 3296 3296 0 0.00 0 4835 17 0 0
v_cancel_customer_prepayment 1420 1420 0 0.00 0 1679 13 0 0
v_cancel_customer_reserve 1226 1226 0 0.00 0 17999 26 0 0
v_cancel_payment_to_supplier 830 830 0 0.00 0 17998 34 0 0
v_cancel_supplier_invoice 814 798 16 1.96 0 3514 29 0 16
v_cancel_supplier_order 838 838 0 0.00 0 1441 13 0 0
v_cancel_write_off 1950 1950 0 0.00 0 6979 29 0 0
v_max_id_clo_ord 5281 5281 0 0.00 0 976 6 0 0
v_max_id_clo_res 924 924 0 0.00 0 2929 18 0 0
v_max_id_ord_sup 5216 5216 0 0.00 0 6216 7 0 0
v_max_non_paid_invoice 1369 1369 0 0.00 0 2444 12 0 0
v_max_non_paid_realizn 3641 3641 0 0.00 0 3738 9 0 0
v_min_id_clo_ord 5281 5281 0 0.00 0 3101 10 0 0
v_min_id_clo_res 924 924 0 0.00 0 4863 14 0 0
v_min_id_ord_sup 5216 5216 0 0.00 0 6782 14 0 0
v_min_non_paid_invoice 5585 5585 0 0.00 0 7357 18 0 0
v_min_non_paid_realizn 6894 6894 0 0.00 0 8199 20 0 0
v_our_firm 9982 9982 0 0.00 0 53 5 0 0
v_reserve_write_off 19150 19150 0 0.00 0 31958 14 0 0
x_lock_depdocs_on_canc_invoice 11446 11446 0 0.00 0 24273 142 0 0
x_lock_depdocs_on_canc_sup_ord 419 419 0 0.00 0 2842 370 0 0
x_make_qstorno_1000_1200 5286 5286 0 0.00 3 75271 1372 0 0
x_make_qstorno_1200_2000 5225 5225 0 0.00 5 88008 3285 0 0
x_make_qstorno_2100_3300 437668 437668 0 0.00 0 122553 203 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 08-04-2022 10:38:45 to 08-04-2022 10:38:50.

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
08:07:16 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:16 35024 8192000000 9717729072 10016628736 796054512 8.856 100 87 174 2 825901920 7857024 21990384
08:10:03 24641 8192000000 9760338688 10072461312 836873040 9.268 100 88 174 1 870459328 12493072 21093216
08:11:37 21575 8192000000 9813599120 10134523904 875091680 9.651 100 84 169 1 919694800 25579120 19024000
08:13:02 4998 8192000000 9859757040 10194640896 912438512 10.021 100 80 156 0 976262496 45708704 18115280
08:14:15 7184 8192000000 9904891696 10248601600 954710320 10.437 100 76 149 0 1022137152 51749072 15677760
08:15:25 2977 8192000000 9954763552 10308210688 993574112 10.816 100 94 184 0 1069564256 57348688 18641456
08:16:31 949 8192000000 10005606320 10365476864 1014077408 11.015 100 95 187 0 1129145072 96291760 18775904
08:17:34 930 8192000000 9998538080 10369388544 1037551600 11.241 100 95 182 1 1118917952 63064432 18301920
08:18:36 1095 8192000000 10041857856 10418503680 1054334304 11.402 100 97 191 0 1162373648 89309600 18729744
08:19:38 1357 8192000000 10028570832 10405556224 1066242832 11.516 100 97 194 0 1156966720 71666336 19057552
08:20:41 993 8192000000 10059405264 10434969600 1067263632 11.526 100 94 185 1 1177432960 91315888 18853440
08:21:43 1392 8192000000 10071764736 10457817088 1074690096 11.597 100 81 160 0 1188068768 97625536 15753136
08:22:46 3842 8192000000 10058704096 10442338304 1081955552 11.666 100 93 172 0 1181886512 83295376 16635584
08:23:51 1958 8192000000 10034622144 10420535296 1089092144 11.734 100 90 177 2 1158370224 53044384 16233696
08:24:54 1316 8192000000 10063788224 10455535616 1091326736 11.755 100 97 184 0 1184597568 74458848 18811984
08:25:57 1312 8192000000 10054728736 10442371072 1098685520 11.825 100 95 177 1 1180327312 63531152 18110640
08:26:59 1117 8192000000 10082078752 10470576128 1099495904 11.833 100 97 188 1 1201423888 82793712 19134272
08:28:02 2848 8192000000 10061649200 10456391680 1103282896 11.869 100 75 139 1 1185996592 68597904 14115792
08:29:05 1002 8192000000 10073342256 10462621696 1100600672 11.843 100 96 184 0 1192496448 73815040 18080736
08:30:07 1256 8192000000 10029608256 10410573824 1083499392 11.681 100 97 173 1 1159199088 55477664 20222032
08:31:11 2361 8192000000 10047153440 10436567040 1083430992 11.680 100 73 143 0 1171665376 72737920 15496464
08:32:14 9819 8192000000 10005769024 10390728704 1060258016 11.459 99 61 120 0 1133225680 60991872 11975792
08:33:24 5960 8192000000 9992086912 10378432512 1040825120 11.273 99 75 144 2 1109491440 54752944 13913376
08:34:32 1112 8192000000 9989670544 10365906944 1034133456 11.208 100 85 159 0 1115583552 64348480 17101616
08:35:34 1093 8192000000 10000311136 10377895936 1038155488 11.247 100 90 175 2 1126878176 70064944 18657744
08:36:36 1255 8192000000 9974093648 10350989312 1027180528 11.141 100 81 153 0 1097759408 54614544 15964336
08:37:42 11658 8192000000 9990028240 10371231744 1054000272 11.399 100 62 115 4 1114106752 49645456 10461024
08:39:01 26734 8192000000 10057167552 10438045696 1082361488 11.670 100 70 133 1 1166313712 69839696 14112528
08:40:34 7737 8192000000 10074173936 10467880960 1100134704 11.839 100 70 130 1 1197743824 85339088 12270032
08:41:47 1121 8192000000 10116437760 10503909376 1111925296 11.951 100 85 163 1 1232449536 102168992 18355248
08:42:52 4264 8192000000 10139942768 10536628224 1123286912 12.058 100 72 142 2 1263380256 125405520 14687824
08:44:00 1214 8192000000 10173041024 10563592192 1127806736 12.101 100 84 163 0 1284701696 139747024 17147936
08:45:07 10808 8192000000 10183717136 10584551424 1137183072 12.189 100 70 129 0 1300187280 148859936 14144272
08:46:30 1096 8192000000 10238592048 10650238976 1156789584 12.373 100 78 156 0 1349406560 175016032 17600944
08:47:41 2646 8192000000 10251720080 10651934720 1163287808 12.434 100 90 172 1 1367210480 184493328 19429344
08:48:47 936 8192000000 10286737744 10694160384 1171548224 12.511 100 92 174 1 1394620016 203444944 19626848
08:49:51 5445 8192000000 10312804992 10719023104 1181870608 12.608 100 83 166 1 1427653728 227072432 18710688
08:51:01 2126 8192000000 10364418976 10768027648 1193661776 12.717 100 86 164 0 1471107360 259434976 18010608
08:52:08 1215 8192000000 10408966592 10809663488 1198168416 12.759 100 97 191 0 1522076688 300236288 23671984
08:53:13 2389 8192000000 10450088656 10853048320 1196646192 12.745 100 90 176 0 1559026000 342022736 20357072
08:54:24 1652 8192000000 10467918832 10892742656 1216179472 12.926 100 78 151 0 1582495296 347990592 18325232
08:55:31 1556 8192000000 10500765120 10929807360 1213235216 12.899 100 96 184 1 1608427776 372958992 22233568
08:56:36 1317 8192000000 10539338816 10983391232 1221283360 12.974 100 96 186 0 1643887632 400074464 22529808
08:57:41 940 8192000000 10541325936 10961301504 1204786928 12.821 100 95 186 1 1648239776 421037968 22414880
08:58:46 1744 8192000000 10585182928 10998034432 1208396928 12.854 100 88 174 0 1692936944 463488480 21051536
08:59:54 847 8192000000 10604980064 11039363072 1196475312 12.744 100 96 184 0 1709842768 491295808 22071648
09:01:00 2659 8192000000 10605399648 11067392000 1182050912 12.609 100 94 183 0 1705526624 501457376 22018336
09:02:09 1245 8192000000 10594369824 11048132608 1167242960 12.471 99 94 177 2 1699769552 510245392 22281200
09:03:14 9702 8192000000 10519061744 11019300864 1147013760 12.281 99 57 111 1 1617039392 455066688 14958944
09:04:27 14525 8192000000 10425457808 10965307392 1157292336 12.378 99 47 89 1 1556299232 387575680 11431216
09:05:44 13547 8192000000 10387624976 10830311424 1160300176 12.406 99 37 72 1 1483064240 314391760 8372304
09:07:46 38942 8192000000 10367223280 10819022848 1145162640 12.264 99 35 67 3 1480750512 327811520 7776352
09:09:27 2467 8192000000 10365631840 10793984000 1165817120 12.458 100 53 104 1 1468878464 290453616 12607728
09:10:38 2144 8192000000 10362938784 10837090304 1169108432 12.488 100 60 115 2 1467684240 284493056 14082752
09:11:42 900 8192000000 10407041024 10861367296 1162865008 12.430 100 92 184 3 1504944576 319595728 22483840
09:12:44 9892 8192000000 10315490768 10833555456 1156236752 12.368 99 54 105 0 1425822400 256277248 13308400
09:14:11 25584 8192000000 10260506688 10723803136 1165436672 12.454 100 27 50 3 1387489824 215913088 6140064
09:15:39 32135 8192000000 10299627184 10749612032 1145983136 12.272 99 35 66 1 1387796080 233385104 8427840
09:18:25 77707 8192000000 10082371744 10600255488 1103433168 11.870 97 17 34 0 1192158176 84457744 4267264
09:20:45 108976 8192000000 10125430368 10564968448 1109185616 11.925 97 17 31 0 1189706128 77104080 3416432
09:23:43 76731 8192000000 10095744160 10551545856 1133072736 12.150 99 14 27 0 1181589856 45776368 2740752
09:26:05 200697 8192000000 10064042784 10557620224 1097127056 11.810 96 6 12 0 1173528528 74790208 1611264
09:30:30 99709 8192000000 10063448544 10505654272 1118366992 12.012 99 41 81 0 1149170800 22224976 8578832
09:33:13 112128 8192000000 10108688192 10536189952 1096551024 11.805 95 76 148 2 1172244752 58629200 17064528
09:36:06 34835 8192000000 10176782000 10618626048 1146369744 12.275 100 38 74 2 1266432800 111754272 8308784
09:37:50 12597 8192000000 10160019760 10622935040 1138094800 12.198 99 61 118 2 1252497040 99806624 14595616
09:39:09 55404 8192000000 10134886416 10630803456 1121140656 12.038 98 22 44 1 1223304768 96757184 5406928
09:41:38 78777 8192000000 10075680992 10540933120 1093078880 11.772 96 14 28 0 1168062384 72029696 2953808
09:44:47 87265 8192000000 10092495168 10534187008 1082751776 11.674 95 24 49 1 1141205760 53580800 4873184
09:47:15 63526 8192000000 10141810832 10574438400 1128708144 12.109 99 41 82 1 1217677744 80236656 8732944
09:49:23 76293 8192000000 10098790576 10598719488 1132052656 12.141 100 19 36 1 1216634592 80987120 3594816
09:51:50 59391 8192000000 10106151952 10552659968 1105377040 11.889 98 23 45 1 1162531600 52008112 5146448
09:54:00 55627 8192000000 10143183808 10644484096 1115433920 11.984 98 23 46 0 1237985248 117960112 4591216
09:55:58 64858 8192000000 10117700448 10614960128 1106017200 11.895 97 14 28 0 1153433568 44481728 2934640
09:58:06 125938 8192000000 10056254784 10538299392 1128158736 12.104 100 21 42 2 1162078864 28952640 4967488
10:01:28 36907 8192000000 10083668848 10547793920 1101088448 11.848 97 43 86 1 1161818928 52263888 8466592
10:03:07 60828 8192000000 10054792416 10508709888 1101107088 11.848 98 17 34 1 1133021728 28213056 3701584
10:05:08 59908 8192000000 10094250400 10545389568 1118302416 12.011 99 24 48 0 1191197024 67806544 5088064
10:07:09 47841 8192000000 10089551600 10556887040 1113026016 11.961 98 20 40 1 1194488448 76972160 4490272
10:09:31 69202 8192000000 10112885168 10573381632 1137156144 12.189 100 15 28 1 1208756848 68627232 2973472
10:11:41 66904 8192000000 10078823760 10539438080 1123133328 12.057 99 12 24 0 1158352784 32886320 2333136
10:13:55 39998 8192000000 10116271872 10562883584 1143525408 12.249 100 45 89 2 1206089488 53424832 9139248
10:15:38 53897 8192000000 10125346912 10580766720 1142265344 12.237 99 33 66 1 1228519600 79518736 6735520
10:17:37 23622 8192000000 10140689376 10564632576 1137063792 12.188 100 48 93 0 1222598912 75688592 9846528
10:19:04 41717 8192000000 10117276272 10593157120 1139881248 12.214 100 16 32 1 1219750976 76402960 3466768
10:20:47 60158 8192000000 10182569440 10670546944 1149794944 12.308 100 25 50 1 1287502080 131609104 6098032
10:22:48 70315 8192000000 10096434672 10566463488 1134730496 12.166 99 14 28 0 1188205568 50889184 2585888
10:25:01 91189 8192000000 10092685504 10529837056 1127579680 12.099 99 49 95 2 1178858336 41886640 9392016
10:27:35 49566 8192000000 10120527360 10596626432 1157280752 12.378 100 15 30 3 1219389728 58933776 3175200
10:29:31 62508 8192000000 10077435184 10534469632 1119826496 12.025 98 10 21 0 1173076992 50984400 2266096
10:31:43 113360 8192000000 10089476688 10549280768 1138989328 12.206 100 21 42 2 1184183472 40363712 4830432
10:34:38 72159 8192000000 10061708656 10526060544 1128322752 12.106 99 19 38 0 1164029648 31827008 3879888
10:36:50 31138 8192000000 9084297136 9374420992 242978400 2.880 22 2 4 0 257280992 13988928 313664

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 08-04-2022 10:38:51 to 08-04-2022 10:39:07.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 129 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 08-04-2022 10:39:08 to 08-04-2022 10:39:10.

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          215020
MON$OLDEST_ACTIVE               215021
MON$OLDEST_SNAPSHOT             215021
MON$NEXT_TRANSACTION            215021
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                       2103808
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             763
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2757 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2757 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2757 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2757 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 ---
--- end of diff output ---

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

08.04.22 10:39:11. 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-04-08 10:39:14 No severe PSQL-related problems occured
08.04.22 10:39:15. End of report.