Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 22.10.2022 07: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 15010760 kB
MemAvailable 30490468 kB
Buffers 12 kB
Cached 16715972 kB
SwapCached 2020 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.3
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-10-22 07:37:19
DTS_END                         2022-10-22 07:37:19.7140
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_PAY_TO_SUPPLIER(26:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/58930
TRN_ID                          232363
ATT_ID                          680
EXC_UNIT                        2


Test workload details

SETTING_NAME SETTING_VALUE
WORKING_MODE SMALL_03
C_WARES_MAX_ID 400
C_CUSTOMER_DOC_MAX_ROWS 10
C_SUPPLIER_DOC_MAX_ROWS 50
C_CUSTOMER_DOC_MAX_QTY 15
C_SUPPLIER_DOC_MAX_QTY 50
C_NUMBER_OF_AGENTS 50

Indexes for heavy-loaded tables

TAB_NAME IDX_NAME IDX_KEY
XQD_1000_1200 XQD_1000_1200_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_ASC WORKER_ID,DOC_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_DEC WORKER_ID,DOC_ID
XQD_1200_2000 XQD_1200_2000_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2000_3300 XQD_2000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2100_3300 XQD_2100_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_3300_3400 XQD_3300_3400_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID

Performance in TOTAL

ACTION AVG_TIMES_PER_MINUTE AVG_ELAPSED_MS SUCCESSFUL_TIMES_DONE
*** OVERALL *** for 120 minutes: 8917.54 23126 1070105
customer order: creation 183.50 450 22020
customer order: refuse 15.99 381 1919
order to supplier: creation 48.63 1338 5835
order to supplier: removal 3.16 157319 379
invoice (draft): creation 48.68 3265 5842
invoice (draft): removal 45.80 1013 5496
invoice accept: apply 44.45 24423 5334
invoice accept: cancel 41.96 1234 5035
customer reserve: creation 4084.01 234 490081
customer reserve: removal 4106.58 95 492789
realization accept: apply 88.80 289 10656
realization accept: cancel 68.76 188 8251
payment from customer: creation 62.18 174 7461
payment from customer: removal 5.63 105 676
payment to supplier: creation 52.59 194 6311
payment to supplier: removal 3.91 151 469
service: total inventory turnovers 4.08 4135 490
service: total monetary turnovers 7.76 410 931
service: aggregate perf. data 1.04 14055 125
service: refresh index statistics 0.04 253076 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 22-10-2022 07:38:52 to 22-10-2022 07:38:55.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7178
TEST_TIME 2 6369
TEST_TIME 3 5681
TEST_TIME 4 6524
TEST_TIME 5 6441
TEST_TIME 6 6343
TEST_TIME 7 6499
TEST_TIME 8 6774
TEST_TIME 9 7184
TEST_TIME 10 7048
TEST_TIME 11 7188
TEST_TIME 12 7249
TEST_TIME 13 7280
TEST_TIME 14 7297
TEST_TIME 15 7319
TEST_TIME 16 7436
TEST_TIME 17 7578
TEST_TIME 18 7811
TEST_TIME 19 7903
TEST_TIME 20 8089
TEST_TIME 21 8085
TEST_TIME 22 8124
TEST_TIME 23 8421
TEST_TIME 24 8616
TEST_TIME 25 8733
TEST_TIME 26 9009
TEST_TIME 27 8964
TEST_TIME 28 9478
TEST_TIME 29 9593
TEST_TIME 30 10585
TEST_TIME 31 10851
TEST_TIME 32 10717
TEST_TIME 33 10597
TEST_TIME 34 10994
TEST_TIME 35 10877
TEST_TIME 36 10733
TEST_TIME 37 11017
TEST_TIME 38 10959
TEST_TIME 39 10838
TEST_TIME 40 10698
TEST_TIME 41 10557
TEST_TIME 42 10459
TEST_TIME 43 10343
TEST_TIME 44 10288
TEST_TIME 45 10155
TEST_TIME 46 10087
TEST_TIME 47 10153
TEST_TIME 48 10224
TEST_TIME 49 10155
TEST_TIME 50 10111
TEST_TIME 51 10152
TEST_TIME 52 10160
TEST_TIME 53 10024
TEST_TIME 54 9860
TEST_TIME 55 9826
TEST_TIME 56 9794
TEST_TIME 57 9693
TEST_TIME 58 9623
TEST_TIME 59 9623
TEST_TIME 60 9611
TEST_TIME 61 9529
TEST_TIME 62 9651
TEST_TIME 63 9565
TEST_TIME 64 9520
TEST_TIME 65 9467
TEST_TIME 66 9459
TEST_TIME 67 9443
TEST_TIME 68 9494
TEST_TIME 69 9471
TEST_TIME 70 9516
TEST_TIME 71 9511
TEST_TIME 72 9562
TEST_TIME 73 9556
TEST_TIME 74 9543
TEST_TIME 75 9515
TEST_TIME 76 9562
TEST_TIME 77 9615
TEST_TIME 78 9621
TEST_TIME 79 9592
TEST_TIME 80 9580
TEST_TIME 81 9478
TEST_TIME 82 9485
TEST_TIME 83 9465
TEST_TIME 84 9404
TEST_TIME 85 9403
TEST_TIME 86 9367
TEST_TIME 87 9314
TEST_TIME 88 9335
TEST_TIME 89 9256
TEST_TIME 90 9218
TEST_TIME 91 9146
TEST_TIME 92 9137
TEST_TIME 93 9109
TEST_TIME 94 9084
TEST_TIME 95 9093
TEST_TIME 96 9125
TEST_TIME 97 9152
TEST_TIME 98 9125
TEST_TIME 99 9095
TEST_TIME 100 9104
TEST_TIME 101 9130
TEST_TIME 102 9153
TEST_TIME 103 9188
TEST_TIME 104 9217
TEST_TIME 105 9297
TEST_TIME 106 9330
TEST_TIME 107 9263
TEST_TIME 108 9212
TEST_TIME 109 9212
TEST_TIME 110 9167
TEST_TIME 111 9137
TEST_TIME 112 9107
TEST_TIME 113 9056
TEST_TIME 114 8989
TEST_TIME 115 8933
TEST_TIME 116 8942
TEST_TIME 117 8953
TEST_TIME 118 8985
TEST_TIME 119 9003
TEST_TIME 120 8936

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 22-10-2022 07:38:56 to 22-10-2022 07:39:00.

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 1520877 1520877 0 0.00 0 12695 13 0 0
doc_list_biud 1520870 1520870 0 0.00 0 28095 37 0 0
sp_add_invoice_to_stock 5486 5334 152 2.77 69 205417 24424 0 152
sp_cancel_adding_invoice 5035 5035 0 0.00 7 76240 1234 0 0
sp_cancel_client_order 1919 1919 0 0.00 6 9832 381 0 0
sp_cancel_customer_reserve 492789 492789 0 0.00 1 27525 95 0 0
sp_cancel_pay_from_customer 676 676 0 0.00 4 5732 105 0 0
sp_cancel_pay_to_supplier 469 469 0 0.00 4 6116 151 0 0
sp_cancel_supplier_invoice 5504 5496 8 0.14 3 28125 1013 0 8
sp_cancel_supplier_order 379 379 0 0.00 9 1411697 157319 0 0
sp_cancel_write_off 8251 8251 0 0.00 2 15899 189 0 0
sp_client_order 22020 22020 0 0.00 5 18435 450 0 0
sp_customer_reserve 490081 490081 0 0.00 0 100639 234 0 0
sp_fill_shopping_cart 33685 33685 0 0.00 1 14267 89 0 0
sp_fill_shopping_cart_clo_res 490047 490047 0 0.00 0 16165 17 0 0
sp_get_clo_for_invoice 5297 5297 0 0.00 0 27046 272 0 0
sp_kill_cost_storno 14431 14431 0 0.00 0 9696 71 0 0
sp_kill_qstorno_ret_qs2qd 999704 999704 0 0.00 0 28090 41 0 0
sp_kill_qty_storno 529822 529822 0 0.00 0 28094 96 0 0
sp_lock_selected_doc 22077 22077 0 0.00 0 9708 9 0 0
sp_make_cost_storno 29725 29725 0 0.00 0 12666 78 0 0
sp_multiply_rows_for_pdistr 29725 29725 0 0.00 0 8370 35 0 0
sp_multiply_rows_for_qdistr 22020 22020 0 0.00 0 15721 147 0 0
sp_pay_from_customer 7461 7461 0 0.00 6 9497 174 0 0
sp_pay_to_supplier 6311 6311 0 0.00 6 9525 194 0 0
sp_payment_common 13770 13770 0 0.00 2 8195 45 0 0
sp_qd_handle_on_cancel_clo 1919 1919 0 0.00 1 7223 297 0 0
sp_qd_handle_on_invoice_upd_sts 10332 10332 0 0.00 0 22095 515 0 0
sp_qd_handle_on_reserve_upd_sts 10656 10656 0 0.00 0 20334 72 0 0
sp_reserve_write_off 10656 10656 0 0.00 8 20431 290 0 0
sp_supplier_invoice 5842 5842 0 0.00 11 81140 3265 0 0
sp_supplier_order 5835 5835 0 0.00 7 31073 1339 0 0
srv_aggregate_perf_data 126 125 1 0.79 2484 189177 14056 0 1
srv_fill_mon_cache_memory 70 70 0 0.00 783 116663 35719 0 0
srv_find_qd_qs_mism 1017907 1017907 0 0.00 0 12133 10 0 0
srv_make_invnt_saldo 490 490 0 0.00 44 113752 4136 0 0
srv_make_money_saldo 931 931 0 0.00 1 11939 411 0 0
srv_recalc_idx_stat 5 5 0 0.00 58361 475944 253076 0 0
t$perf-abend:sp_add_invoice_to_stock 152 152 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-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5336 5336 0 0.00 1 10567 949 0 0
t$perf-norm:sp_cancel_adding_invoice 429 429 0 0.00 1 21875 479 0 0
t$perf-norm:sp_cancel_client_order 1919 1919 0 0.00 0 1662 28 0 0
t$perf-norm:sp_cancel_customer_reserve 803 803 0 0.00 0 2579 27 0 0
t$perf-norm:sp_cancel_pay_from_customer 676 676 0 0.00 0 1862 17 0 0
t$perf-norm:sp_cancel_pay_to_supplier 469 469 0 0.00 0 3681 26 0 0
t$perf-norm:sp_cancel_supplier_invoice 399 399 0 0.00 0 309 23 0 0
t$perf-norm:sp_cancel_supplier_order 379 379 0 0.00 0 90659 6503 0 0
t$perf-norm:sp_cancel_write_off 1056 1056 0 0.00 0 4271 21 0 0
t$perf-norm:sp_client_order 22020 22020 0 0.00 0 10278 37 0 0
t$perf-norm:sp_customer_reserve 1067 1067 0 0.00 0 6781 26 0 0
t$perf-norm:sp_pay_from_customer 7461 7461 0 0.00 0 2599 17 0 0
t$perf-norm:sp_pay_to_supplier 6311 6311 0 0.00 0 16838 23 0 0
t$perf-norm:sp_reserve_write_off 10656 10656 0 0.00 0 8632 24 0 0
t$perf-norm:sp_supplier_invoice 5842 5842 0 0.00 0 4665 30 0 0
t$perf-norm:sp_supplier_order 5835 5835 0 0.00 0 6918 34 0 0
t$perf-norm:srv_aggregate_perf_data 125 125 0 0.00 0 246 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 337 9 0 0
t$perf-norm:srv_make_invnt_saldo 490 490 0 0.00 0 1887 16 0 0
t$perf-norm:srv_make_money_saldo 931 931 0 0.00 0 3159 10 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 3 189 82 0 0
v_add_invoice_to_stock 10894 10590 304 2.79 0 8341 29 0 304
v_all_customers 40020 40020 0 0.00 0 159 4 0 0
v_all_suppliers 32452 32452 0 0.00 0 163 4 0 0
v_all_wares 44036 44036 0 0.00 0 77 4 0 0
v_cancel_adding_invoice 858 858 0 0.00 0 2323 15 0 0
v_cancel_client_order 3838 3838 0 0.00 0 5765 12 0 0
v_cancel_customer_prepayment 1352 1352 0 0.00 0 1129 10 0 0
v_cancel_customer_reserve 1606 1606 0 0.00 0 2357 14 0 0
v_cancel_payment_to_supplier 938 938 0 0.00 0 6013 29 0 0
v_cancel_supplier_invoice 814 798 16 1.96 0 1738 28 0 16
v_cancel_supplier_order 758 758 0 0.00 0 555 11 0 0
v_cancel_write_off 2112 2112 0 0.00 0 9214 31 0 0
v_max_id_clo_ord 5833 5833 0 0.00 0 4291 6 0 0
v_max_id_clo_res 1067 1067 0 0.00 0 2030 19 0 0
v_max_id_ord_sup 5834 5834 0 0.00 0 6578 10 0 0
v_max_non_paid_invoice 1751 1751 0 0.00 0 1707 9 0 0
v_max_non_paid_realizn 3951 3951 0 0.00 0 1686 8 0 0
v_min_id_clo_ord 5833 5833 0 0.00 0 3559 10 0 0
v_min_id_clo_res 1067 1067 0 0.00 0 3437 15 0 0
v_min_id_ord_sup 5834 5834 0 0.00 0 5271 14 0 0
v_min_non_paid_invoice 6309 6309 0 0.00 0 5396 17 0 0
v_min_non_paid_realizn 7460 7460 0 0.00 0 8143 17 0 0
v_our_firm 11034 11034 0 0.00 0 91 5 0 0
v_reserve_write_off 21302 21302 0 0.00 0 7680 12 0 0
x_lock_depdocs_on_canc_invoice 10132 10132 0 0.00 0 10774 124 0 0
x_lock_depdocs_on_canc_sup_ord 379 379 0 0.00 0 2819 324 0 0
x_make_qstorno_1000_1200 5835 5835 0 0.00 2 31059 1178 0 0
x_make_qstorno_1200_2000 5842 5842 0 0.00 4 81130 3007 0 0
x_make_qstorno_2100_3300 490081 490081 0 0.00 0 100635 204 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 22-10-2022 07:39:01 to 22-10-2022 07:39:05.

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:07:23 902 8192000000 8921945248 8978243584 17139840 0.208 4 3 5 0 17578528 154144 284544
05:08:25 23042 8192000000 9719288992 10020184064 800165440 8.898 100 87 174 0 829177152 7187936 21823776
05:09:53 23063 8192000000 9776789952 10087096320 848894448 9.389 100 92 184 0 881705472 10830848 21980176
05:11:23 14905 8192000000 9805159408 10129334272 879390960 9.694 100 81 162 0 914432944 16424064 18617920
05:12:45 9982 8192000000 9872264592 10207137792 926764144 10.163 100 83 163 0 977406608 32991232 17651232
05:13:58 2913 8192000000 9923529632 10266722304 959291952 10.482 100 86 166 0 1037796416 60996800 17507664
05:15:05 2068 8192000000 9972938448 10327306240 989224160 10.774 100 92 170 0 1088911808 81926688 17760960
05:16:10 1804 8192000000 9977032032 10340077568 1013205760 11.006 100 90 171 0 1097986880 67324048 17457072
05:17:14 1415 8192000000 10012597360 10381365248 1033949168 11.206 100 90 174 0 1135690512 84614128 17127216
05:18:17 1143 8192000000 10038564736 10414313472 1051647328 11.376 100 95 180 0 1157894400 88372080 17874992
05:19:19 5808 8192000000 10023457024 10403840000 1057793936 11.435 100 79 153 0 1140943600 67253872 15895792
05:20:27 2116 8192000000 10029059968 10409852928 1066900128 11.522 100 88 167 2 1152212624 67553760 17758736
05:21:31 4148 8192000000 10019128496 10403373056 1072201776 11.573 100 78 151 1 1140945440 53698144 15045520
05:22:36 670 8192000000 10046015456 10430369792 1075186416 11.602 100 83 162 0 1166689280 74247664 17255200
05:23:39 1164 8192000000 10041617376 10426511360 1079516544 11.643 100 94 178 2 1165995264 68366144 18112576
05:24:41 1161 8192000000 10056661168 10443501568 1088532976 11.729 100 96 188 0 1174961728 66560368 19868384
05:25:43 1147 8192000000 10089430752 10476883968 1089164704 11.735 100 94 186 1 1207701232 100582816 17953712
05:26:45 3622 8192000000 10042673744 10432380928 1074684656 11.597 99 63 124 0 1164302992 77180224 12438112
05:27:49 1001 8192000000 10028807824 10418679808 1086333440 11.708 100 90 176 0 1152182624 47384320 18464864
05:28:51 1045 8192000000 10036438336 10426138624 1076481520 11.614 99 79 151 0 1153902560 61981680 15439360
05:29:53 1157 8192000000 10021409472 10412138496 1078675248 11.635 100 82 158 1 1144560656 48254432 17630976
05:30:55 1151 8192000000 10057015248 10442321920 1077061488 11.619 100 94 174 1 1178934496 81146336 20726672
05:31:57 1771 8192000000 10006202272 10391973888 1063648416 11.491 100 71 137 0 1122768976 44930272 14190288
05:33:01 1185 8192000000 9975817872 10354225152 1036543056 11.231 99 74 142 0 1106023888 53083088 16397744
05:34:03 1019 8192000000 9968972592 10344587264 1042048544 11.284 99 84 165 1 1093009264 32184176 18776544
05:35:05 1828 8192000000 10003546112 10384961536 1055442768 11.413 100 89 170 1 1132710912 59416384 17851760
05:36:07 1106 8192000000 10019249536 10399592448 1053934336 11.398 100 98 184 1 1138384576 65652192 18798048
05:37:11 1185 8192000000 9990846288 10369540096 1043258080 11.296 99 86 164 1 1118100752 58564032 16278640
05:38:17 4910 8192000000 9998851056 10376216576 1048196544 11.343 99 77 146 1 1114792352 52717984 13877824
05:39:32 1491 8192000000 10038267200 10421583872 1082071552 11.667 100 90 176 1 1158965552 57642528 19251472
05:40:39 2994 8192000000 10063245440 10453135360 1086952592 11.714 100 73 141 1 1179432112 77610560 14868960
05:41:45 864 8192000000 10076621104 10463252480 1098780064 11.826 100 78 155 1 1194614000 79143232 16690704
05:42:49 1039 8192000000 10110028336 10499469312 1110821696 11.940 100 96 179 2 1227532272 98024896 18685680
05:43:53 5276 8192000000 10128598992 10535804928 1122387840 12.050 100 63 121 1 1247128752 111834144 12906768
05:45:02 11865 8192000000 10170855680 10579865600 1134011696 12.159 100 77 154 0 1297165680 146675216 16478768
05:46:18 3239 8192000000 10184475312 10584260608 1144170496 12.255 100 78 155 0 1285954640 125442624 16341520
05:47:27 3132 8192000000 10184614288 10586202112 1153363104 12.341 100 81 154 1 1301765520 132019456 16382960
05:48:34 1046 8192000000 10227135280 10627256320 1164632672 12.447 100 98 194 0 1338779504 152859152 21287680
05:49:39 1837 8192000000 10259906512 10673061888 1174203936 12.536 100 86 168 0 1376055632 183468368 18383328
05:50:44 3615 8192000000 10296574640 10698391552 1185761072 12.644 100 87 170 0 1408462784 204092656 18609056
05:51:51 1137 8192000000 10328300336 10730926080 1175911088 12.552 99 96 181 1 1437124224 240935696 20277440
05:52:55 1249 8192000000 10389284224 10796507136 1189927760 12.683 100 97 187 0 1496837632 285247696 21662176
05:53:59 1460 8192000000 10412935328 10833182720 1186703024 12.653 100 89 174 2 1528020064 320697712 20619328
05:55:02 966 8192000000 10438146704 10858627072 1181860176 12.608 100 96 185 1 1542934432 339595760 21478496
05:56:06 1379 8192000000 10454424160 10869403648 1190236752 12.686 100 96 190 0 1558770064 345489056 23044256
05:57:12 1067 8192000000 10485898064 10911084544 1200764016 12.783 100 93 177 3 1590415504 368374080 21277408
05:58:16 806 8192000000 10521062992 10934820864 1187568736 12.661 100 98 188 0 1624702256 414602832 22530688
05:59:20 1328 8192000000 10487451280 10924306432 1172758720 12.523 99 90 175 1 1589890032 395467760 21663552
06:00:27 774 8192000000 10534364832 10965078016 1191586832 12.698 100 99 196 0 1637377568 422174400 23616336
06:01:29 1341 8192000000 10499055312 10951200768 1173528976 12.530 99 83 163 2 1606132112 412676160 19926976
06:02:34 3599 8192000000 10540929872 10989596672 1181763056 12.607 100 63 124 1 1645429152 448181808 15484288
06:03:44 16262 8192000000 10512540560 10968866816 1172998032 12.525 100 38 76 0 1624209728 441219232 9992464
06:05:11 30286 8192000000 10466026208 10984808448 1136092672 12.179 99 30 58 0 1643244432 498631984 8519776
06:06:50 13385 8192000000 10292886416 10865639424 1134688240 12.166 100 24 47 0 1432255360 291001776 6565344
06:08:07 55133 8192000000 10181347264 10627305472 1122412416 12.050 98 27 49 0 1267146048 138752896 5980736
06:10:04 30071 8192000000 10249179728 10724646912 1147223696 12.283 99 32 64 0 1365173216 209956272 7993248
06:11:50 65402 8192000000 10169819088 10614636544 1116570688 11.995 97 33 62 2 1252277856 129715136 5992032
06:14:13 61725 8192000000 10096302512 10631491584 1139689040 12.213 100 9 18 0 1234629504 93045120 1895344
06:16:20 49557 8192000000 10051289296 10504445952 1113911648 11.969 99 17 34 0 1141827232 24771616 3143968
06:18:16 25788 8192000000 10070507744 10507534336 1094779552 11.788 97 47 95 1 1136108704 30099328 11229824
06:20:29 80132 8192000000 10107455856 10548117504 1122723648 12.053 99 24 46 1 1190389968 62345008 5321312
06:22:57 35241 8192000000 10109352624 10561163264 1126869392 12.092 99 20 40 0 1200623968 69800592 3953984
06:24:34 65904 8192000000 10084707712 10574217216 1116707072 11.996 98 12 23 0 1154426160 34808752 2910336
06:26:45 116662 8192000000 10082913680 10588168192 1100751840 11.845 97 14 23 0 1143790720 41014624 2024256
06:29:46 58447 8192000000 10064703904 10507329536 1128694080 12.109 100 19 38 0 1153444000 19970816 4779104
06:31:56 86036 8192000000 10100391712 10541121536 1129257264 12.114 99 40 80 0 1186056704 48603360 8196080
06:34:26 63459 8192000000 10100136880 10529837056 1139703232 12.213 99 65 114 3 1197170112 45121152 12345728
06:36:31 57111 8192000000 10104144704 10574368768 1123008176 12.055 98 22 39 1 1203514096 75985056 4520864
06:38:29 16813 8192000000 10116060672 10546192384 1121984400 12.046 97 41 76 0 1204223648 74380688 7858560
06:39:47 25740 8192000000 10052276448 10519314432 1116979904 11.998 98 16 30 0 1138168608 18294016 2894688
06:41:15 57447 8192000000 10103612896 10571231232 1138407520 12.201 99 11 21 0 1192308240 51798096 2102624
06:43:13 47819 8192000000 10075084688 10513858560 1102733408 11.864 96 27 48 0 1147983696 39859760 5390528
06:45:03 45824 8192000000 10060305952 10517004288 1120950400 12.036 98 17 32 1 1161149520 37149968 3049152
06:46:50 48996 8192000000 10093636800 10535141376 1101220240 11.849 97 27 55 2 1163385184 56559120 5605824
06:49:20 50159 8192000000 10079370848 10539634688 1090857648 11.751 96 24 48 0 1160419456 64330496 5231312
06:51:11 74756 8192000000 10089517760 10542190592 1101645072 11.853 98 22 43 0 1173464048 67190416 4628560
06:53:31 28884 8192000000 10086813792 10541670400 1114248368 11.973 99 28 56 0 1195697856 75018480 6431008
06:55:01 40195 8192000000 10077830448 10526527488 1071621968 11.568 96 32 64 0 1126068000 48278160 6167872
06:57:00 109605 8192000000 10100011184 10539823104 1118478128 12.013 100 41 82 0 1184519152 57595376 8445648
06:59:52 48632 8192000000 10076112464 10517753856 1080146576 11.649 96 41 77 0 1138106496 49248784 8711136
07:01:53 29528 8192000000 10094633936 10534391808 1104751616 11.883 99 44 88 0 1176563216 62825328 8986272
07:03:32 86362 8192000000 10069511280 10523992064 1107965152 11.913 99 8 16 0 1153189680 43674576 1549952
07:06:07 31337 8192000000 10024930400 10462846976 1109372304 11.926 99 19 32 1 1141493856 28735760 3385792
07:07:41 83491 8192000000 10071845280 10527555584 1121579888 12.042 99 15 30 0 1163274992 39139280 2555824
07:11:32 59251 8192000000 10107586512 10579361792 1129988832 12.121 99 22 44 0 1209382080 75202432 4190816
07:13:32 20864 8192000000 10110668128 10546270208 1132049696 12.141 99 54 107 0 1195110736 52119984 10941056
07:15:03 19565 8192000000 10127541232 10556522496 1144119584 12.254 100 55 106 1 1213549616 58301776 11128256
07:16:24 21096 8192000000 10184501504 10620391424 1158762880 12.392 100 43 85 0 1271544128 103843312 8937936
07:17:45 34290 8192000000 10161656976 10625904640 1104199408 11.877 96 34 68 1 1241673152 130057248 7416496
07:19:20 17770 8192000000 10161647408 10600095744 1142555376 12.240 99 35 70 0 1236242832 86303904 7383552
07:20:43 19263 8192000000 10182898752 10637033472 1140312736 12.218 99 48 96 0 1275128976 124690912 10125328
07:22:13 94309 8192000000 10085792800 10510336000 1091208128 11.754 95 10 20 0 1153828688 60568272 2052288
07:24:50 94061 8192000000 10074813296 10536632320 1132054192 12.141 99 40 78 1 1200171200 60031280 8085728
07:27:25 71308 8192000000 10057984256 10508124160 1098822736 11.826 97 9 18 0 1127850496 27365280 1662480
07:29:45 70366 8192000000 10106224752 10556596224 1132418880 12.144 99 18 36 0 1183530672 46727392 4384400
07:31:59 50622 8192000000 10192548832 10644586496 1150187520 12.311 100 21 43 0 1285668976 130484864 4996592
07:33:52 86912 8192000000 10123367488 10582708224 1098279024 11.821 96 22 44 0 1214496816 111223232 4994560
07:36:43 47790 8192000000 9121447968 9421537280 270261792 3.193 24 2 4 0 311985408 41338864 384752

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 22-10-2022 07:39:07 to 22-10-2022 07:39:23.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 152 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 22-10-2022 07:39:24 to 22-10-2022 07:39:26.

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          232625
MON$OLDEST_ACTIVE               232626
MON$OLDEST_SNAPSHOT             232626
MON$NEXT_TRANSACTION            232626
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                       2087392
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             792
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2861 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2861 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2861 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2861 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 ---
:702: 
:703: mscs-test-161.budzdorov.ru    Sat Oct 22 07:37:48 2022
:704:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:705: 
--- end of diff output ---

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

22.10.22 07:39:27. 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-10-22 07:39:30 No severe PSQL-related problems occured
22.10.22 07:39:31. End of report.