Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 29.06.2022 07:48
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 16057892 kB
MemAvailable 31226460 kB
Buffers 0 kB
Cached 15678952 kB
SwapCached 2500 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-29 07:47:02
DTS_END                         2022-06-29 07:47:02.6840
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/38112
TRN_ID                          224251
ATT_ID                          674
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: 8957.80 23620 1074936
customer order: creation 176.38 479 21166
customer order: refuse 15.45 430 1854
order to supplier: creation 46.68 1468 5601
order to supplier: removal 3.16 181965 379
invoice (draft): creation 46.79 3117 5615
invoice (draft): removal 49.13 1110 5895
invoice accept: apply 42.58 26391 5109
invoice accept: cancel 44.43 1280 5331
customer reserve: creation 3944.36 251 473323
customer reserve: removal 4285.92 104 514310
realization accept: apply 86.63 281 10395
realization accept: cancel 81.09 176 9731
payment from customer: creation 61.21 168 7345
payment from customer: removal 5.68 143 682
payment to supplier: creation 51.38 207 6165
payment to supplier: removal 3.74 119 449
service: total inventory turnovers 4.18 4027 502
service: total monetary turnovers 8.02 386 962
service: aggregate perf. data 0.98 14710 118
service: refresh index statistics 0.03 235584 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 29-06-2022 07:48:39 to 29-06-2022 07:48:41.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 12009
TEST_TIME 2 8684
TEST_TIME 3 8192
TEST_TIME 4 7177
TEST_TIME 5 6756
TEST_TIME 6 6253
TEST_TIME 7 6213
TEST_TIME 8 6227
TEST_TIME 9 6291
TEST_TIME 10 6126
TEST_TIME 11 6156
TEST_TIME 12 6426
TEST_TIME 13 6384
TEST_TIME 14 6480
TEST_TIME 15 6992
TEST_TIME 16 7068
TEST_TIME 17 7370
TEST_TIME 18 7557
TEST_TIME 19 7959
TEST_TIME 20 8055
TEST_TIME 21 8176
TEST_TIME 22 8231
TEST_TIME 23 8635
TEST_TIME 24 8948
TEST_TIME 25 9040
TEST_TIME 26 9085
TEST_TIME 27 9367
TEST_TIME 28 9725
TEST_TIME 29 9816
TEST_TIME 30 9952
TEST_TIME 31 10163
TEST_TIME 32 10349
TEST_TIME 33 10660
TEST_TIME 34 10893
TEST_TIME 35 10986
TEST_TIME 36 10960
TEST_TIME 37 10862
TEST_TIME 38 10724
TEST_TIME 39 10532
TEST_TIME 40 10386
TEST_TIME 41 10276
TEST_TIME 42 10364
TEST_TIME 43 10139
TEST_TIME 44 10113
TEST_TIME 45 10065
TEST_TIME 46 10019
TEST_TIME 47 10008
TEST_TIME 48 10026
TEST_TIME 49 9912
TEST_TIME 50 9896
TEST_TIME 51 9768
TEST_TIME 52 9671
TEST_TIME 53 9640
TEST_TIME 54 9864
TEST_TIME 55 10075
TEST_TIME 56 10048
TEST_TIME 57 10023
TEST_TIME 58 9942
TEST_TIME 59 10106
TEST_TIME 60 10065
TEST_TIME 61 9972
TEST_TIME 62 10136
TEST_TIME 63 10082
TEST_TIME 64 10030
TEST_TIME 65 10041
TEST_TIME 66 10120
TEST_TIME 67 9980
TEST_TIME 68 9904
TEST_TIME 69 9811
TEST_TIME 70 9789
TEST_TIME 71 9745
TEST_TIME 72 9752
TEST_TIME 73 9666
TEST_TIME 74 9586
TEST_TIME 75 9635
TEST_TIME 76 9590
TEST_TIME 77 9554
TEST_TIME 78 9510
TEST_TIME 79 9593
TEST_TIME 80 9539
TEST_TIME 81 9541
TEST_TIME 82 9548
TEST_TIME 83 9479
TEST_TIME 84 9485
TEST_TIME 85 9572
TEST_TIME 86 9639
TEST_TIME 87 9597
TEST_TIME 88 9548
TEST_TIME 89 9508
TEST_TIME 90 9484
TEST_TIME 91 9487
TEST_TIME 92 9397
TEST_TIME 93 9353
TEST_TIME 94 9355
TEST_TIME 95 9342
TEST_TIME 96 9345
TEST_TIME 97 9326
TEST_TIME 98 9283
TEST_TIME 99 9326
TEST_TIME 100 9329
TEST_TIME 101 9291
TEST_TIME 102 9347
TEST_TIME 103 9310
TEST_TIME 104 9301
TEST_TIME 105 9268
TEST_TIME 106 9384
TEST_TIME 107 9339
TEST_TIME 108 9319
TEST_TIME 109 9280
TEST_TIME 110 9255
TEST_TIME 111 9225
TEST_TIME 112 9224
TEST_TIME 113 9183
TEST_TIME 114 9137
TEST_TIME 115 9110
TEST_TIME 116 9085
TEST_TIME 117 9057
TEST_TIME 118 9049
TEST_TIME 119 9061
TEST_TIME 120 8987

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 29-06-2022 07:48:42 to 29-06-2022 07:48:46.

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 1510779 1510779 0 0.00 0 24902 14 0 0
doc_list_biud 1510767 1510767 0 0.00 0 31618 42 0 0
sp_add_invoice_to_stock 5329 5109 220 4.12 40 268206 26391 0 220
sp_cancel_adding_invoice 5331 5331 0 0.00 5 111424 1280 0 0
sp_cancel_client_order 1854 1854 0 0.00 6 12148 430 0 0
sp_cancel_customer_reserve 514310 514310 0 0.00 1 30704 105 0 0
sp_cancel_pay_from_customer 682 682 0 0.00 4 8690 144 0 0
sp_cancel_pay_to_supplier 449 449 0 0.00 4 3378 119 0 0
sp_cancel_supplier_invoice 5917 5895 22 0.37 2 31628 1110 0 22
sp_cancel_supplier_order 379 379 0 0.00 13 1217698 181966 0 0
sp_cancel_write_off 9731 9731 0 0.00 2 10845 176 0 0
sp_client_order 21166 21166 0 0.00 5 33424 480 0 0
sp_customer_reserve 473323 473323 0 0.00 0 119480 252 0 0
sp_fill_shopping_cart 32368 32368 0 0.00 1 20573 81 0 0
sp_fill_shopping_cart_clo_res 473282 473282 0 0.00 0 15981 20 0 0
sp_get_clo_for_invoice 5055 5055 0 0.00 0 36982 286 0 0
sp_kill_cost_storno 16193 16193 0 0.00 0 7102 75 0 0
sp_kill_qstorno_ret_qs2qd 1044625 1044625 0 0.00 0 31614 47 0 0
sp_kill_qty_storno 552949 552949 0 0.00 0 31617 107 0 0
sp_lock_selected_doc 21426 21426 0 0.00 0 10656 9 0 0
sp_make_cost_storno 28960 28960 0 0.00 0 19121 78 0 0
sp_multiply_rows_for_pdistr 28960 28960 0 0.00 0 10915 39 0 0
sp_multiply_rows_for_qdistr 21166 21166 0 0.00 0 12650 175 0 0
sp_pay_from_customer 7345 7345 0 0.00 5 14987 169 0 0
sp_pay_to_supplier 6165 6165 0 0.00 6 24917 207 0 0
sp_payment_common 13510 13510 0 0.00 2 9943 43 0 0
sp_qd_handle_on_cancel_clo 1854 1854 0 0.00 0 12137 343 0 0
sp_qd_handle_on_invoice_upd_sts 10386 10386 0 0.00 0 21868 525 0 0
sp_qd_handle_on_reserve_upd_sts 10394 10394 0 0.00 0 20302 68 0 0
sp_reserve_write_off 10395 10395 0 0.00 8 20471 282 0 0
sp_supplier_invoice 5615 5615 0 0.00 8 90921 3118 0 0
sp_supplier_order 5601 5601 0 0.00 7 30531 1469 0 0
srv_aggregate_perf_data 118 118 0 0.00 2065 120334 14711 0 0
srv_fill_mon_cache_memory 73 73 0 0.00 720 142357 33429 0 0
srv_find_qd_qs_mism 1024558 1024558 0 0.00 0 19052 12 0 0
srv_make_invnt_saldo 502 502 0 0.00 88 64225 4027 0 0
srv_make_money_saldo 962 962 0 0.00 1 18140 387 0 0
srv_recalc_idx_stat 4 4 0 0.00 72167 422159 235585 0 0
t$perf-abend:sp_add_invoice_to_stock 220 220 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 22 22 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5114 5114 0 0.00 1 18800 957 0 0
t$perf-norm:sp_cancel_adding_invoice 410 410 0 0.00 1 6051 457 0 0
t$perf-norm:sp_cancel_client_order 1854 1854 0 0.00 0 8056 34 0 0
t$perf-norm:sp_cancel_customer_reserve 742 742 0 0.00 0 3901 36 0 0
t$perf-norm:sp_cancel_pay_from_customer 682 682 0 0.00 0 2334 21 0 0
t$perf-norm:sp_cancel_pay_to_supplier 449 449 0 0.00 0 3937 27 0 0
t$perf-norm:sp_cancel_supplier_invoice 427 427 0 0.00 0 915 34 0 0
t$perf-norm:sp_cancel_supplier_order 379 379 0 0.00 0 101951 8162 0 0
t$perf-norm:sp_cancel_write_off 1036 1036 0 0.00 0 1779 20 0 0
t$perf-norm:sp_client_order 21166 21166 0 0.00 0 7226 37 0 0
t$perf-norm:sp_customer_reserve 1051 1051 0 0.00 0 2487 19 0 0
t$perf-norm:sp_pay_from_customer 7345 7345 0 0.00 0 10401 24 0 0
t$perf-norm:sp_pay_to_supplier 6165 6165 0 0.00 0 7302 24 0 0
t$perf-norm:sp_reserve_write_off 10396 10396 0 0.00 0 22816 26 0 0
t$perf-norm:sp_supplier_invoice 5615 5615 0 0.00 0 7239 34 0 0
t$perf-norm:sp_supplier_order 5601 5601 0 0.00 0 8054 32 0 0
t$perf-norm:srv_aggregate_perf_data 118 118 0 0.00 0 475 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 73 73 0 0.00 0 618 15 0 0
t$perf-norm:srv_make_invnt_saldo 502 502 0 0.00 0 1581 21 0 0
t$perf-norm:srv_make_money_saldo 962 962 0 0.00 0 1034 8 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 4 192 66 0 0
v_add_invoice_to_stock 10546 10106 440 4.17 0 11627 26 0 440
v_all_customers 38654 38654 0 0.00 0 183 4 0 0
v_all_suppliers 31494 31494 0 0.00 0 184 4 0 0
v_all_wares 42328 42328 0 0.00 0 93 4 0 0
v_cancel_adding_invoice 820 820 0 0.00 0 2120 16 0 0
v_cancel_client_order 3708 3708 0 0.00 0 4261 11 0 0
v_cancel_customer_prepayment 1364 1364 0 0.00 0 1769 13 0 0
v_cancel_customer_reserve 1484 1484 0 0.00 0 1894 12 0 0
v_cancel_payment_to_supplier 898 898 0 0.00 0 3292 16 0 0
v_cancel_supplier_invoice 898 854 44 4.89 0 3033 27 0 44
v_cancel_supplier_order 758 758 0 0.00 0 1569 17 0 0
v_cancel_write_off 2072 2072 0 0.00 0 3424 26 0 0
v_max_id_clo_ord 5595 5595 0 0.00 0 464 5 0 0
v_max_id_clo_res 1050 1050 0 0.00 0 2484 16 0 0
v_max_id_ord_sup 5609 5609 0 0.00 0 6879 9 0 0
v_max_non_paid_invoice 1621 1621 0 0.00 0 222 8 0 0
v_max_non_paid_realizn 3869 3869 0 0.00 0 9277 10 0 0
v_min_id_clo_ord 5595 5595 0 0.00 0 2505 8 0 0
v_min_id_clo_res 1050 1050 0 0.00 0 1265 8 0 0
v_min_id_ord_sup 5609 5609 0 0.00 0 3927 7 0 0
v_min_non_paid_invoice 6165 6165 0 0.00 0 9936 17 0 0
v_min_non_paid_realizn 7344 7344 0 0.00 0 3508 13 0 0
v_our_firm 10624 10624 0 0.00 0 61 4 0 0
v_reserve_write_off 20788 20788 0 0.00 0 5205 13 0 0
x_lock_depdocs_on_canc_invoice 10799 10799 0 0.00 0 16104 139 0 0
x_lock_depdocs_on_canc_sup_ord 379 379 0 0.00 0 13751 378 0 0
x_make_qstorno_1000_1200 5601 5601 0 0.00 3 30491 1309 0 0
x_make_qstorno_1200_2000 5615 5615 0 0.00 4 88861 2886 0 0
x_make_qstorno_2100_3300 473322 473322 0 0.00 0 119477 220 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 29-06-2022 07:48:47 to 29-06-2022 07:48:51.

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
05:17:06 2 8192000000 8796669104 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
05:18:07 34804 8192000000 9736275504 10035585024 810412384 9.002 100 92 184 0 841040848 8089840 22538624
05:19:47 19442 8192000000 9764054448 10073198592 838877792 9.288 100 89 178 0 872920528 12848352 21194384
05:21:12 15253 8192000000 9798871488 10117779456 862808096 9.528 100 80 160 0 904459088 23404240 18246752
05:22:31 8919 8192000000 9861168528 10196381696 910847728 10.006 100 72 144 0 969620400 43220816 15551856
05:23:42 4095 8192000000 9921222304 10268168192 966347792 10.551 100 82 164 0 1035237104 51822608 17066704
05:24:51 2064 8192000000 9967904032 10321125376 1000455936 10.883 100 89 176 0 1087590528 69198304 17936288
05:25:56 1050 8192000000 10003730928 10371878912 1025202432 11.122 100 94 176 2 1122030016 79577952 17249632
05:27:00 785 8192000000 10017343968 10386309120 1040617408 11.271 100 97 181 0 1142544576 84003616 17923552
05:28:02 1085 8192000000 10027367392 10403655680 1060277168 11.459 100 93 186 0 1144007264 65105632 18624464
05:29:06 1205 8192000000 10026406416 10402693120 1068495936 11.538 100 88 172 1 1149471584 64053344 16922304
05:30:08 882 8192000000 10054246480 10437062656 1077270736 11.621 100 98 184 0 1169902800 73512656 19119408
05:31:10 1079 8192000000 10075482160 10458939392 1084055408 11.686 100 95 187 0 1194787888 92237056 18495424
05:32:12 1133 8192000000 10036072080 10427772928 1090104864 11.744 100 76 141 2 1163503856 59619120 13779872
05:33:13 6543 8192000000 10054291088 10449489920 1097360464 11.813 100 70 135 1 1178663392 67450704 13852224
05:34:21 1396 8192000000 10066231392 10459033600 1099482576 11.833 100 76 136 3 1186317232 73623200 13211456
05:35:23 1094 8192000000 10064331408 10461396992 1102638368 11.863 100 77 154 1 1185713232 67501008 15573856
05:36:25 2215 8192000000 10044717168 10439905280 1100765184 11.845 100 71 129 1 1162780464 48567712 13447568
05:37:28 1536 8192000000 10057857152 10457239552 1105737456 11.892 100 63 115 0 1180267920 62391904 12138560
05:38:31 6804 8192000000 10059534496 10453975040 1101032880 11.847 100 81 155 0 1178370064 61076912 16260272
05:39:41 944 8192000000 10053949920 10442833920 1089457648 11.738 100 91 179 2 1176879776 68884320 18537808
05:40:43 1237 8192000000 10048735120 10430324736 1080216016 11.650 100 96 178 2 1171759216 73091168 18452032
05:41:45 1264 8192000000 10015546416 10405060608 1067510864 11.528 100 71 136 2 1142679840 61235296 13933680
05:42:47 2315 8192000000 10013878736 10401509376 1063710768 11.492 100 77 154 0 1135336464 56545952 15079744
05:43:51 1131 8192000000 9963183200 10336518144 1038399408 11.249 100 90 171 2 1090430736 33232656 18798672
05:44:53 11192 8192000000 9980218240 10360008704 1028469216 11.154 99 58 113 1 1094514864 53033104 13012544
05:46:05 1253 8192000000 9989918752 10360356864 1042923760 11.293 100 95 178 4 1114924256 52874272 19126224
05:47:11 2384 8192000000 10020201984 10400727040 1059319760 11.450 100 72 137 2 1132745888 60277904 13148224
05:48:20 4061 8192000000 10009745520 10397376512 1068967232 11.542 100 71 130 1 1142644416 58461360 15215824
05:49:31 4463 8192000000 10041444912 10426650624 1083108816 11.677 100 72 141 0 1148392832 51312752 13971264
05:50:41 6826 8192000000 10076579712 10469089280 1100789008 11.845 100 73 139 0 1197409248 82426656 14193584
05:51:58 2610 8192000000 10130474256 10520031232 1122125024 12.047 100 81 151 3 1244255296 105908112 16222160
05:53:10 10287 8192000000 10172704144 10576293888 1129349216 12.115 100 61 114 3 1289306144 147159936 12796992
05:54:26 2839 8192000000 10204851360 10606686208 1138148192 12.198 100 70 133 2 1321095008 168556816 14390000
05:55:35 2953 8192000000 10248631552 10661552128 1151288272 12.322 100 72 137 3 1367775344 200726768 15760304
05:56:50 2696 8192000000 10263137456 10659278848 1163050192 12.432 100 81 149 0 1371932816 192726416 16156208
05:58:00 715 8192000000 10319714416 10722992128 1172399856 12.519 100 89 169 0 1432567824 240884064 19283904
05:59:05 1356 8192000000 10360679216 10770894848 1184767392 12.635 100 88 168 0 1468909136 264018752 20122992
06:00:10 797 8192000000 10377206944 10779705344 1175771024 12.551 100 99 193 2 1488652688 290467248 22414416
06:01:15 1897 8192000000 10433161952 10847031296 1180730400 12.597 100 93 184 1 1541851968 339678384 21443184
06:02:21 1061 8192000000 10413846848 10849083392 1174005552 12.534 100 94 182 0 1523501296 328340016 21155728
06:03:25 959 8192000000 10441495824 10877923328 1184183136 12.629 100 92 180 0 1549607792 344832784 20591872
06:04:30 2376 8192000000 10423140912 10864009216 1176206688 12.555 100 86 158 2 1528511152 332735744 19568720
06:05:41 1496 8192000000 10409741232 10844028928 1177612912 12.568 100 94 184 1 1518171824 319186704 21372208
06:06:45 774 8192000000 10405102352 10826977280 1160830096 12.411 99 97 187 0 1512610064 329428176 22351792
06:07:50 836 8192000000 10443577728 10862440448 1173588576 12.530 100 97 190 0 1549656064 353392112 22675376
06:08:53 962 8192000000 10431373648 10888204288 1172240912 12.518 100 97 189 3 1539018096 344962432 21814752
06:09:58 1179 8192000000 10451219680 10913341440 1173513216 12.530 100 92 179 1 1558578992 363331376 21734400
06:11:02 1802 8192000000 10430236864 10881740800 1184282816 12.630 100 75 144 0 1533337280 331360288 17694176
06:12:06 5861 8192000000 10407921248 10877575168 1178983568 12.581 100 43 84 0 1512147312 321992096 11171648
06:13:17 13145 8192000000 10439474864 10919825408 1176296800 12.556 100 45 88 0 1564904224 377042800 11564624
06:14:40 7828 8192000000 10413183376 10874400768 1181624704 12.605 100 51 102 1 1521368992 327763328 11980960
06:16:00 31988 8192000000 10313993760 10787213312 1160089952 12.404 100 24 48 0 1438527264 272645008 5792304
06:17:47 15630 8192000000 10288661664 10765533184 1151727184 12.326 99 38 72 1 1390034368 229155008 9152176
06:19:04 24744 8192000000 10276102896 10732728320 1154502560 12.352 99 34 64 2 1366716288 204716336 7497392
06:20:32 45809 8192000000 10154132688 10642837504 1116269696 11.992 97 10 20 0 1269984192 150946256 2768240
06:22:21 37865 8192000000 10044145056 10500468736 1123237536 12.058 99 16 28 0 1180621488 54828256 2555696
06:24:00 51971 8192000000 10062781984 10514186240 1106411168 11.898 97 25 45 1 1136791136 26103344 4276624
06:25:53 86536 8192000000 10061041280 10512732160 1112686544 11.958 99 39 72 1 1148064736 28240768 7137424
06:28:43 26384 8192000000 10127609232 10560131072 1121280464 12.039 99 43 79 0 1213104672 82936464 8887744
06:30:12 70169 8192000000 10125797760 10553704448 1137059552 12.188 100 53 89 1 1202505024 56302672 9142800
06:32:24 22488 8192000000 10098341408 10535661568 1121296112 12.039 99 25 42 0 1178996832 53113328 4587392
06:33:55 13918 8192000000 10143771280 10579034112 1122762192 12.053 99 59 114 0 1234835312 98122176 13950944
06:35:10 25628 8192000000 10151532128 10621947904 1124179680 12.066 99 29 56 0 1247863552 116937008 6746864
06:37:17 142356 8192000000 10234221616 10687512576 1115536672 11.985 98 41 82 0 1273443072 147761120 10145280
06:40:40 74355 8192000000 10176033216 10662211584 1132302608 12.143 100 19 39 0 1300663760 163346320 5014832
06:42:55 51324 8192000000 10093122496 10520748032 1097094816 11.810 97 39 73 0 1153543344 47422272 9026256
06:44:48 6245 8192000000 10112115008 10538971136 1130813728 12.129 100 73 144 0 1203917440 57462848 15640864
06:45:57 55079 8192000000 10111047200 10585452544 1118725920 12.015 99 26 52 0 1252960320 128720096 5514304
06:47:57 16384 8192000000 10084738336 10510917632 1123716624 12.062 99 52 101 0 1173693312 38955808 11020880
06:49:14 66285 8192000000 10066162384 10547187712 1108722064 11.920 98 12 24 0 1177041392 65582320 2737008
06:51:21 52701 8192000000 10147408272 10578927616 1135401744 12.172 99 40 79 1 1222397552 79296576 7699232
06:53:22 72765 8192000000 10075889632 10572918784 1129647536 12.118 99 20 41 0 1180431536 46939568 3844432
06:56:04 57069 8192000000 10100610848 10547687424 1135272544 12.171 99 40 78 0 1200800528 56815568 8712416
06:58:02 69303 8192000000 10103723600 10573701120 1125694304 12.081 98 12 24 0 1198518416 69607696 3216416
07:00:24 83291 8192000000 10153679776 10604363776 1128143104 12.104 99 21 42 0 1229137280 96542144 4452032
07:02:47 32246 8192000000 10105375984 10603515904 1138205952 12.199 100 25 48 1 1214240032 71252416 4781664
07:04:20 49622 8192000000 10125047904 10599190528 1138469872 12.201 100 23 44 0 1236342432 93241824 4630736
07:06:12 60214 8192000000 10107247376 10577678336 1136367088 12.181 99 23 43 0 1186980016 45319200 5293728
07:08:13 31930 8192000000 10149613568 10622980096 1150181936 12.311 99 33 68 2 1242143168 84188096 7773136
07:09:47 36947 8192000000 10167189248 10610896896 1144349456 12.256 99 24 48 0 1246814672 97195456 5269760
07:11:24 21388 8192000000 10227632608 10703253504 1144079088 12.254 99 37 73 0 1305636304 153901584 7655632
07:12:46 40447 8192000000 10072346800 10519597056 1113333712 11.964 98 26 47 1 1203855728 85355008 5167008
07:14:28 5044 8192000000 10104947872 10518786048 1112619552 11.957 98 68 134 0 1175252064 48731104 13901408
07:15:34 96583 8192000000 10143796048 10602688512 1135003776 12.169 100 59 106 0 1268945520 123271472 10670272
07:18:28 34350 8192000000 10083580336 10581118976 1123567616 12.061 99 7 14 0 1176480064 51755920 1156528
07:20:07 54064 8192000000 10077107952 10533511168 1110429504 11.936 98 14 28 0 1159364064 46134096 2800464
07:22:02 57964 8192000000 10057538432 10503229440 1060913616 11.465 94 15 25 0 1113778976 50270016 2595344
07:24:00 22267 8192000000 10063163920 10493980672 1118030304 12.008 99 54 99 2 1161146000 33250672 9865024
07:25:27 19420 8192000000 10106200000 10537799680 1128960848 12.112 99 45 89 0 1185682944 47864672 8857424
07:26:48 69554 8192000000 10077593088 10537361408 1090060288 11.743 96 22 42 1 1148130912 53935392 4135232
07:29:04 93453 8192000000 10077066720 10509492224 1103859680 11.874 97 30 60 0 1133268976 23575376 5833920
07:31:38 25292 8192000000 10126684560 10569195520 1116430480 11.993 98 47 93 1 1186356480 60561664 9364336
07:33:06 78303 8192000000 10065408000 10516418560 1101563680 11.852 97 27 54 0 1200377856 93739504 5074672
07:35:25 87421 8192000000 10070918304 10537275392 1131769312 12.138 100 30 59 1 1190640960 52756752 6114896
07:37:57 34236 8192000000 10100398592 10551431168 1131906480 12.139 99 34 68 0 1189479760 50773280 6800000
07:39:33 39035 8192000000 10047199392 10515099648 1129336512 12.115 99 14 27 0 1150839728 18887360 2615856
07:41:12 56687 8192000000 10090313392 10544762880 1131772864 12.138 99 11 23 0 1173886784 39890784 2223136
07:43:16 71645 8192000000 10125822960 10582609920 1122320096 12.049 98 16 32 0 1213010192 86821200 3868896
07:45:31 39512 8192000000 10148435888 10588659712 1114734368 11.977 97 36 70 0 1218245200 95644368 7866464

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 29-06-2022 07:48:53 to 29-06-2022 07:49:09.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 220 v_add_invoice_to_stock 335544517
except 22 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 29-06-2022 07:49:10 to 29-06-2022 07:49:12.

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          215798
MON$OLDEST_ACTIVE               225107
MON$OLDEST_SNAPSHOT             225107
MON$NEXT_TRANSACTION            225107
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                       2052448
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             783
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2793 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2793 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2793 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2793 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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Wed Jun 29 07:47:08 2022
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Wed Jun 29 07:47:11 2022
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:13: 
:14: 
:15: mscs-test-161.budzdorov.ru    Wed Jun 29 07:47:12 2022
:16:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:17: 
--- end of diff output ---

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

29.06.22 07:49:13. 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-29 07:49:15 No severe PSQL-related problems occured
29.06.22 07:49:17. End of report.