Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 29.12.2022 22:40
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 15216112 kB
MemAvailable 30386712 kB
Buffers 0 kB
Cached 16512848 kB
SwapCached 11308 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 3.0.11
DB_NAME                         /home/bases/oltp30-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/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/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=3333
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/oltp30-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-oltp30
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-12-29 22:38:33
DTS_END                         2022-12-29 22:38:33.8330
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/56498
TRN_ID                          247821
ATT_ID                          733
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: 9966.96 18453 1196035
customer order: creation 185.57 366 22268
customer order: refuse 15.89 340 1907
order to supplier: creation 48.62 1140 5834
order to supplier: removal 3.53 91626 423
invoice (draft): creation 48.68 2655 5841
invoice (draft): removal 56.43 631 6772
invoice accept: apply 44.36 15163 5323
invoice accept: cancel 50.68 648 6081
customer reserve: creation 4167.13 147 500055
customer reserve: removal 5031.73 51 603807
realization accept: apply 89.93 155 10791
realization accept: cancel 85.73 106 10287
payment from customer: creation 63.73 89 7648
payment from customer: removal 6.02 77 722
payment to supplier: creation 52.53 100 6304
payment to supplier: removal 3.53 65 423
service: total inventory turnovers 5.05 1040 606
service: total monetary turnovers 7.03 78 843
service: aggregate perf. data 0.80 24309 96
service: refresh index statistics 0.03 230267 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 29-12-2022 22:40:44 to 29-12-2022 22:40:46.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1737
TEST_TIME 2 3485
TEST_TIME 3 3693
TEST_TIME 4 4133
TEST_TIME 5 4551
TEST_TIME 6 4464
TEST_TIME 7 4371
TEST_TIME 8 4478
TEST_TIME 9 4552
TEST_TIME 10 5289
TEST_TIME 11 5616
TEST_TIME 12 6216
TEST_TIME 13 6863
TEST_TIME 14 7193
TEST_TIME 15 8088
TEST_TIME 16 7923
TEST_TIME 17 8259
TEST_TIME 18 8421
TEST_TIME 19 8657
TEST_TIME 20 8522
TEST_TIME 21 8750
TEST_TIME 22 8960
TEST_TIME 23 9225
TEST_TIME 24 9542
TEST_TIME 25 9612
TEST_TIME 26 9464
TEST_TIME 27 9805
TEST_TIME 28 10414
TEST_TIME 29 10753
TEST_TIME 30 10810
TEST_TIME 31 10609
TEST_TIME 32 10564
TEST_TIME 33 10626
TEST_TIME 34 10667
TEST_TIME 35 10689
TEST_TIME 36 10634
TEST_TIME 37 10594
TEST_TIME 38 10556
TEST_TIME 39 10534
TEST_TIME 40 10485
TEST_TIME 41 10654
TEST_TIME 42 10464
TEST_TIME 43 10308
TEST_TIME 44 10247
TEST_TIME 45 10248
TEST_TIME 46 10184
TEST_TIME 47 10091
TEST_TIME 48 10081
TEST_TIME 49 9998
TEST_TIME 50 10034
TEST_TIME 51 10043
TEST_TIME 52 10007
TEST_TIME 53 10026
TEST_TIME 54 9944
TEST_TIME 55 9917
TEST_TIME 56 9960
TEST_TIME 57 9960
TEST_TIME 58 9999
TEST_TIME 59 9964
TEST_TIME 60 10019
TEST_TIME 61 10044
TEST_TIME 62 10138
TEST_TIME 63 10123
TEST_TIME 64 10234
TEST_TIME 65 10198
TEST_TIME 66 10233
TEST_TIME 67 10224
TEST_TIME 68 10238
TEST_TIME 69 10313
TEST_TIME 70 10321
TEST_TIME 71 10265
TEST_TIME 72 10141
TEST_TIME 73 10203
TEST_TIME 74 10152
TEST_TIME 75 10137
TEST_TIME 76 10100
TEST_TIME 77 10074
TEST_TIME 78 10033
TEST_TIME 79 9962
TEST_TIME 80 9942
TEST_TIME 81 9887
TEST_TIME 82 9972
TEST_TIME 83 9976
TEST_TIME 84 9918
TEST_TIME 85 10045
TEST_TIME 86 10046
TEST_TIME 87 10038
TEST_TIME 88 10058
TEST_TIME 89 10074
TEST_TIME 90 10112
TEST_TIME 91 10064
TEST_TIME 92 10082
TEST_TIME 93 10156
TEST_TIME 94 10162
TEST_TIME 95 10126
TEST_TIME 96 10131
TEST_TIME 97 10081
TEST_TIME 98 10058
TEST_TIME 99 10022
TEST_TIME 100 9976
TEST_TIME 101 9976
TEST_TIME 102 9922
TEST_TIME 103 9918
TEST_TIME 104 9919
TEST_TIME 105 9888
TEST_TIME 106 9838
TEST_TIME 107 9848
TEST_TIME 108 9816
TEST_TIME 109 9843
TEST_TIME 110 9857
TEST_TIME 111 9904
TEST_TIME 112 9929
TEST_TIME 113 9907
TEST_TIME 114 9946
TEST_TIME 115 9923
TEST_TIME 116 9968
TEST_TIME 117 9977
TEST_TIME 118 9977
TEST_TIME 119 10015
TEST_TIME 120 9971

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 29-12-2022 22:40:48 to 29-12-2022 22:40:51.

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 1657220 1657220 0 0.00 0 17385 5 0 0
doc_list_biud 1657220 1657220 0 0.00 0 54719 24 0 0
sp_add_invoice_to_stock 5465 5323 142 2.59 28 136093 15164 0 142
sp_cancel_adding_invoice 6081 6081 0 0.00 2 56421 648 0 0
sp_cancel_client_order 1907 1907 0 0.00 4 12448 340 0 0
sp_cancel_customer_reserve 603807 603807 0 0.00 0 54720 51 0 0
sp_cancel_pay_from_customer 722 722 0 0.00 3 10752 77 0 0
sp_cancel_pay_to_supplier 423 423 0 0.00 2 2339 65 0 0
sp_cancel_supplier_invoice 6783 6772 11 0.16 1 18011 631 0 11
sp_cancel_supplier_order 423 423 0 0.00 8 821632 91626 0 0
sp_cancel_write_off 10287 10287 0 0.00 1 17401 106 0 0
sp_client_order 22268 22268 0 0.00 3 34426 367 0 0
sp_customer_reserve 500055 500055 0 0.00 0 57201 148 0 0
sp_fill_shopping_cart 33939 33939 0 0.00 1 18159 32 0 0
sp_fill_shopping_cart_clo_res 500047 500047 0 0.00 0 16339 13 0 0
sp_get_clo_for_invoice 5315 5315 0 0.00 0 29690 257 0 0
sp_kill_cost_storno 17513 17513 0 0.00 0 16989 49 0 0
sp_kill_qstorno_ret_qs2qd 1225096 1225096 0 0.00 0 54682 28 0 0
sp_kill_qty_storno 645383 645383 0 0.00 0 54719 60 0 0
sp_lock_selected_doc 22263 22263 0 0.00 0 10712 6 0 0
sp_make_cost_storno 30058 30058 0 0.00 0 14291 43 0 0
sp_multiply_rows_for_pdistr 30058 30058 0 0.00 0 9437 27 0 0
sp_multiply_rows_for_qdistr 22268 22268 0 0.00 0 16256 173 0 0
sp_pay_from_customer 7648 7648 0 0.00 4 12744 90 0 0
sp_pay_to_supplier 6304 6304 0 0.00 3 13257 101 0 0
sp_payment_common 13952 13952 0 0.00 1 9352 24 0 0
sp_qd_handle_on_cancel_clo 1907 1907 0 0.00 0 12440 321 0 0
sp_qd_handle_on_invoice_upd_sts 11396 11396 0 0.00 0 21186 280 0 0
sp_qd_handle_on_reserve_upd_sts 10791 10791 0 0.00 0 26735 54 0 0
sp_reserve_write_off 10791 10791 0 0.00 6 26763 156 0 0
sp_supplier_invoice 5841 5841 0 0.00 6 78681 2656 0 0
sp_supplier_order 5834 5834 0 0.00 5 32364 1140 0 0
srv_aggregate_perf_data 97 96 1 1.03 2625 507585 24310 0 1
srv_fill_mon_cache_memory 64 64 0 0.00 1208 127219 46242 0 0
srv_find_qd_qs_mism 1143757 1143757 0 0.00 0 17379 4 0 0
srv_make_invnt_saldo 606 606 0 0.00 41 82684 1041 0 0
srv_make_money_saldo 843 843 0 0.00 1 42568 78 0 0
srv_recalc_idx_stat 4 4 0 0.00 165330 324503 230268 0 0
t$perf-abend:sp_add_invoice_to_stock 142 142 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 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 5323 5323 0 0.00 0 10224 160 0 0
t$perf-norm:sp_cancel_adding_invoice 431 431 0 0.00 0 2125 73 0 0
t$perf-norm:sp_cancel_client_order 1907 1907 0 0.00 0 4160 19 0 0
t$perf-norm:sp_cancel_customer_reserve 690 690 0 0.00 0 742 7 0 0
t$perf-norm:sp_cancel_pay_from_customer 722 722 0 0.00 0 774 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 423 423 0 0.00 0 767 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 469 469 0 0.00 0 1122 10 0 0
t$perf-norm:sp_cancel_supplier_order 423 423 0 0.00 0 28072 1620 0 0
t$perf-norm:sp_cancel_write_off 1092 1092 0 0.00 0 5452 18 0 0
t$perf-norm:sp_client_order 22268 22268 0 0.00 0 15261 15 0 0
t$perf-norm:sp_customer_reserve 1058 1058 0 0.00 0 5128 11 0 0
t$perf-norm:sp_pay_from_customer 7648 7648 0 0.00 0 11223 11 0 0
t$perf-norm:sp_pay_to_supplier 6304 6304 0 0.00 0 11533 12 0 0
t$perf-norm:sp_reserve_write_off 10791 10791 0 0.00 0 8269 11 0 0
t$perf-norm:sp_supplier_invoice 5841 5841 0 0.00 0 5304 13 0 0
t$perf-norm:sp_supplier_order 5834 5834 0 0.00 0 5330 13 0 0
t$perf-norm:srv_aggregate_perf_data 96 96 0 0.00 0 598 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 78 2 0 0
t$perf-norm:srv_make_invnt_saldo 606 606 0 0.00 0 1642 15 0 0
t$perf-norm:srv_make_money_saldo 843 843 0 0.00 0 958 11 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 6 3 0 0
v_add_invoice_to_stock 10914 10630 284 2.60 0 4078 15 0 284
v_all_customers 40366 40366 0 0.00 0 83 0 0 0
v_all_suppliers 32888 32888 0 0.00 0 22 1 0 0
v_all_wares 44534 44534 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 862 862 0 0.00 0 4621 12 0 0
v_cancel_client_order 3814 3814 0 0.00 0 2236 4 0 0
v_cancel_customer_prepayment 1444 1444 0 0.00 0 9770 11 0 0
v_cancel_customer_reserve 1380 1380 0 0.00 0 3968 7 0 0
v_cancel_payment_to_supplier 846 846 0 0.00 0 1001 5 0 0
v_cancel_supplier_invoice 960 938 22 2.29 0 4930 23 0 22
v_cancel_supplier_order 846 846 0 0.00 0 5468 18 0 0
v_cancel_write_off 2184 2184 0 0.00 0 3054 15 0 0
v_max_id_clo_ord 5832 5832 0 0.00 0 1886 2 0 0
v_max_id_clo_res 1058 1058 0 0.00 0 7919 28 0 0
v_max_id_ord_sup 5839 5839 0 0.00 0 3219 5 0 0
v_max_non_paid_invoice 1531 1531 0 0.00 0 373 2 0 0
v_max_non_paid_realizn 4044 4044 0 0.00 0 1126 3 0 0
v_min_id_clo_ord 5832 5832 0 0.00 0 5834 6 0 0
v_min_id_clo_res 1058 1058 0 0.00 0 5010 16 0 0
v_min_id_ord_sup 5839 5839 0 0.00 0 4838 5 0 0
v_min_non_paid_invoice 6304 6304 0 0.00 0 9348 17 0 0
v_min_non_paid_realizn 7648 7648 0 0.00 0 6274 16 0 0
v_our_firm 11376 11376 0 0.00 0 12 1 0 0
v_reserve_write_off 21582 21582 0 0.00 0 5399 5 0 0
x_lock_depdocs_on_canc_invoice 12384 12384 0 0.00 0 10719 87 0 0
x_lock_depdocs_on_canc_sup_ord 423 423 0 0.00 0 22379 380 0 0
x_make_qstorno_1000_1200 5834 5834 0 0.00 1 32359 1075 0 0
x_make_qstorno_1200_2000 5841 5841 0 0.00 2 78671 2537 0 0
x_make_qstorno_2100_3300 500055 500055 0 0.00 0 57200 132 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 29-12-2022 22:40:52 to 29-12-2022 22:40:56.

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
20:08:37 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:38 31979 8192000000 9889579056 10197966848 972879296 10.615 100 84 168 0 1001025952 5875344 22271312
20:11:18 20852 8192000000 9939406352 10260656128 1024204224 11.113 100 89 178 0 1055824160 8664992 22954944
20:12:57 15351 8192000000 9994575808 10327252992 1074771104 11.598 100 85 170 0 1105903344 10200432 20931808
20:14:20 10138 8192000000 10045132864 10392932352 1128928688 12.111 100 83 166 0 1160298080 11792368 19577024
20:15:34 5610 8192000000 10090892608 10451095552 1179868880 12.589 100 83 166 0 1213010944 14159840 18982224
20:16:43 2176 8192000000 10134467472 10504818688 1219833584 12.960 100 80 160 0 1251308144 12456928 19017632
20:17:47 2769 8192000000 10162635488 10540445696 1253413744 13.270 100 80 160 0 1283851792 13371760 17066288
20:18:53 1790 8192000000 10189272544 10569711616 1280406896 13.517 100 87 174 1 1313983280 14193648 19382736
20:19:56 18309 8192000000 10207072816 10599641088 1305505744 13.745 100 53 106 0 1327596224 10107072 11983408
20:21:17 10056 8192000000 10197109088 10607927296 1307755440 13.766 100 35 68 1 1322722992 7555968 7411584
20:22:28 9005 8192000000 10206378912 10623188992 1320486992 13.881 100 25 51 0 1333102512 7413152 5202368
20:23:43 48810 8192000000 10212485696 10633633792 1325603568 13.927 100 34 69 1 1339174272 5652160 7918544
20:25:33 10599 8192000000 10220529840 10639912960 1329663168 13.964 100 23 46 0 1344689456 10042352 4983936
20:26:44 12288 8192000000 10216740288 10640457728 1332557184 13.990 100 12 22 0 1340835968 5448112 2830672
20:27:57 76786 8192000000 10207689264 10629234688 1293049504 13.632 97 20 41 0 1303001024 5386224 4565296
20:30:15 8245 8192000000 10156821360 10563084288 1247193552 13.212 98 26 52 1 1259066400 5551904 6320944
20:31:25 12354 8192000000 10133210432 10525958144 1187577968 12.661 94 22 43 0 1201318688 8821536 4919184
20:32:38 51656 8192000000 10128305984 10526302208 1169875632 12.496 93 21 42 0 1180531904 4925568 5730704
20:34:30 4648 8192000000 10167085632 10566754304 1269354576 13.416 100 34 69 1 1282727792 5953888 7419328
20:35:36 9786 8192000000 10186166832 10596257792 1288925392 13.594 100 25 50 1 1309473744 14718528 5829824
20:36:46 18874 8192000000 10189672592 10603855872 1302487232 13.718 100 23 46 0 1312839616 5083584 5268800
20:38:07 20047 8192000000 10202611264 10623184896 1316507104 13.845 100 15 29 0 1324230464 4846464 2876896
20:39:34 37426 8192000000 10249169360 10658852864 1342572960 14.081 100 45 90 0 1362461168 9481744 10406464
20:41:26 56565 8192000000 10274321904 10701934592 1371731904 14.343 100 41 82 2 1394460368 13282000 9446464
20:43:42 25304 8192000000 10296466336 10720567296 1389912240 14.505 100 44 88 1 1415640352 15162400 10565712
20:45:10 15858 8192000000 10315757872 10737610752 1402577776 14.618 100 54 106 0 1434689792 18606208 13505808
20:46:39 5496 8192000000 10325550064 10745679872 1404872048 14.638 100 60 118 1 1439373968 20017664 14484256
20:47:50 13873 8192000000 10342575328 10764492800 1413429168 14.714 100 58 114 0 1453827584 25752496 14645920
20:49:09 14175 8192000000 10354388672 10782081024 1423506544 14.804 100 54 105 2 1467135616 30194784 13434288
20:50:27 1207 8192000000 10372325200 10793947136 1420496352 14.777 100 66 131 0 1473609088 37056032 16056704
20:51:32 1952 8192000000 10370738976 10796163072 1420482400 14.777 100 70 139 0 1475966304 38753376 16730528
20:52:37 3857 8192000000 10372224160 10794741760 1418169232 14.756 100 73 145 0 1477545392 41951520 17424640
20:53:47 40933 8192000000 10341608592 10777559040 1381452672 14.430 97 39 76 0 1421723120 30697488 9572960
20:55:41 10833 8192000000 10334251744 10770784256 1400448048 14.599 100 31 63 0 1444885792 35919296 8518448
20:57:02 14562 8192000000 10319733696 10749325312 1364631712 14.279 98 29 58 0 1408029456 35409904 7987840
20:58:20 26175 8192000000 10310857664 10736599040 1353380816 14.178 98 22 44 0 1396980400 37474640 6124944
21:00:00 11538 8192000000 10316161376 10749243392 1371453648 14.340 100 51 102 1 1431940576 47300176 13186752
21:01:25 12903 8192000000 10316737840 10754109440 1372891776 14.353 100 31 62 0 1432197072 50919168 8386128
21:02:41 12933 8192000000 10333600864 10767818752 1366633728 14.297 98 31 62 0 1424079760 49094432 8351600
21:03:54 17358 8192000000 10323953152 10760871936 1380015056 14.417 100 20 40 0 1424371424 38986816 5369552
21:05:16 30112 8192000000 10315341216 10764312576 1364489680 14.278 100 15 30 0 1419793760 50747280 4556800
21:06:53 28277 8192000000 10279587232 10725584896 1335105264 14.013 99 12 24 0 1370390320 32374688 2910368
21:08:23 127219 8192000000 10256958032 10690170880 1255816208 13.292 92 20 40 0 1277427360 17146768 4464384
21:11:39 60838 8192000000 10267500144 10706391040 1335244528 14.015 99 8 16 0 1352006688 15026192 1735968
21:13:41 42454 8192000000 10254157904 10694176768 1331856128 13.984 98 17 33 0 1344003696 8618656 3528912
21:15:51 33412 8192000000 10259589920 10697625600 1345929056 14.111 99 19 38 1 1358252320 8213808 4109456
21:17:26 28386 8192000000 10281593536 10721599488 1370378448 14.330 100 21 43 0 1385327200 9330960 5617792
21:19:08 112812 8192000000 10292366592 10741039104 1322778544 13.902 96 11 23 0 1339771088 14768064 2224480
21:22:01 72142 8192000000 10283098064 10721034240 1358560912 14.224 98 37 74 0 1376698704 9896272 8241520
21:24:18 70013 8192000000 10296295264 10741919744 1374985920 14.372 99 31 60 0 1389932608 7906864 7039824
21:26:45 29797 8192000000 10323260528 10764652544 1397055200 14.569 100 26 53 0 1414032560 10952304 6025056
21:28:16 37907 8192000000 10326721760 10781519872 1407266992 14.660 100 43 86 0 1435989168 18929472 9792704
21:30:05 24455 8192000000 10309268688 10776367104 1408628544 14.672 100 13 25 0 1426053936 14695392 2730000
21:31:38 26537 8192000000 10321320960 10789142528 1413910496 14.719 100 14 28 1 1432539760 15118976 3510288
21:33:06 93004 8192000000 10313289472 10782326784 1395129904 14.552 99 15 31 0 1412482976 13829520 3523552
21:35:40 60833 8192000000 10295603248 10750234624 1337147936 14.032 95 16 32 0 1349583952 9164848 3271168
21:37:47 42538 8192000000 10307349568 10763071488 1381690320 14.432 99 32 64 0 1398631728 10062272 6879136
21:39:39 54911 8192000000 10306051040 10769039360 1383581040 14.449 99 18 36 1 1402965856 15820880 3563936
21:41:35 90157 8192000000 10265830560 10721468416 1293598032 13.637 93 18 36 0 1311192688 13894272 3700384
21:44:06 20088 8192000000 10285879600 10729099264 1370246944 14.329 100 25 51 0 1384431664 8807904 5376816
21:45:57 19868 8192000000 10293506992 10728787968 1324762096 13.920 96 39 77 1 1348275552 15036016 8477440
21:47:18 57478 8192000000 10272749216 10718646272 1333153264 13.996 98 13 25 0 1349573856 13908544 2512048
21:49:38 92292 8192000000 10264192704 10714234880 1347884464 14.128 99 24 48 0 1362336400 8922160 5529776
21:52:12 53248 8192000000 10231057616 10665668608 1257367568 13.306 93 30 60 0 1273307184 9208256 6731360
21:54:20 22904 8192000000 10243680256 10686873600 1297710816 13.674 97 33 62 1 1310891184 5504256 7676112
21:55:44 67237 8192000000 10256233584 10699956224 1331076192 13.977 98 18 36 1 1347611952 11714688 4821072
21:57:54 48514 8192000000 10259310208 10708549632 1339729504 14.055 99 14 28 0 1358846592 15643552 3473536
21:59:42 68945 8192000000 10245146768 10701991936 1325365008 13.925 98 21 41 0 1339878576 9179296 5334272
22:01:53 50739 8192000000 10262688528 10706210816 1333179792 13.996 99 16 32 0 1352814816 15991184 3643840
22:03:43 39949 8192000000 10263204848 10715701248 1358072688 14.220 100 22 44 0 1372203536 9493616 4637232
22:05:24 31829 8192000000 10281048320 10712887296 1353364336 14.178 99 20 40 0 1365299472 7410416 4524720
22:06:56 37997 8192000000 10288641824 10738311168 1373479792 14.358 100 26 52 0 1391385696 12026816 5879088
22:08:34 75414 8192000000 10304938960 10747047936 1364036192 14.274 98 16 30 0 1375288096 8496800 2755104
22:10:53 47552 8192000000 10310686624 10782359552 1394328432 14.544 100 8 16 0 1413644208 17867104 1448672
22:12:43 59535 8192000000 10323125984 10766848000 1399222944 14.588 100 28 56 0 1414566192 9032448 6310800
22:14:45 123471 8192000000 10303664848 10769301504 1392103904 14.525 99 23 46 0 1407641056 10463808 5073344
22:17:58 23378 8192000000 10299728448 10758090752 1381573904 14.431 99 21 40 1 1396771056 10627600 4569552
22:19:24 50923 8192000000 10277022192 10744807424 1363754640 14.271 98 13 26 0 1381031264 14373792 2902832
22:21:15 38853 8192000000 10279580560 10735472640 1356461680 14.206 98 15 30 0 1371706400 12055024 3189696
22:22:57 88867 8192000000 10261372128 10718642176 1357203056 14.212 99 14 28 0 1370679392 10201008 3275328
22:25:27 66682 8192000000 10272609696 10737864704 1330918000 13.975 96 11 22 1 1341599472 8249504 2431968
22:27:35 59436 8192000000 10269805088 10731589632 1328758736 13.956 96 15 30 0 1344487968 12133536 3595696
22:29:35 74001 8192000000 10244868496 10697768960 1279900176 13.512 94 13 27 0 1292745200 9552848 3292176
22:31:56 114319 8192000000 10260651312 10710618112 1316184544 13.842 97 9 18 0 1336943104 18737696 2020864
22:34:52 90132 8192000000 10259906512 10717638656 1290339840 13.607 95 10 20 0 1306824800 14431840 2053120
22:37:34 46996 8192000000 10270658832 10713096192 1326138176 13.932 98 23 46 0 1344493536 13756736 4598624

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 29-12-2022 22:40:58 to 29-12-2022 22:41:13.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 142 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 29-12-2022 22:41:14 to 29-12-2022 22:41:16.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          246267
MON$OLDEST_ACTIVE               247936
MON$OLDEST_SNAPSHOT             247936
MON$NEXT_TRANSACTION            247936
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-03 16:41:55.3470
MON$PAGES                       2039968
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33650 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33650 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33650 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33650 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.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 ---
:1378: 
:1379: mscs-test-161.budzdorov.ru    Thu Dec 29 22:38:45 2022
:1380:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1381: 
:1382: 
:1383: mscs-test-161.budzdorov.ru    Thu Dec 29 22:39:04 2022
:1384:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1385: 
:1386: 
:1387: mscs-test-161.budzdorov.ru    Thu Dec 29 22:39:04 2022
:1388:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1389: 
:1390: 
:1391: mscs-test-161.budzdorov.ru    Thu Dec 29 22:39:04 2022
:1392:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1393: 
:1394: 
:1395: mscs-test-161.budzdorov.ru    Thu Dec 29 22:39:05 2022
:1396:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1397: 
--- end of diff output ---

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

29.12.22 22:41:17. There are 3 logs in /var/tmp/logs-oltp30 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-12-29 22:41:20 No severe PSQL-related problems occured
29.12.22 22:41:21. End of report.