Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 11.05.2022 16: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 16817136 kB
MemAvailable 31507068 kB
Buffers 0 kB
Cached 14936780 kB
SwapCached 2592 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.10
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-05-11 16:38:16
DTS_END                         2022-05-11 16:38:16.3280
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/38602
TRN_ID                          240657
ATT_ID                          652
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: 8994.24 21639 1079309
customer order: creation 178.46 355 21415
customer order: refuse 14.94 336 1793
order to supplier: creation 46.38 1260 5566
order to supplier: removal 3.17 95700 380
invoice (draft): creation 46.78 3443 5613
invoice (draft): removal 48.40 745 5808
invoice accept: apply 43.73 18888 5248
invoice accept: cancel 43.90 857 5268
customer reserve: creation 3958.67 192 475040
customer reserve: removal 4313.05 55 517566
realization accept: apply 85.58 168 10269
realization accept: cancel 77.68 103 9322
payment from customer: creation 60.83 104 7299
payment from customer: removal 5.92 71 710
payment to supplier: creation 50.24 117 6029
payment to supplier: removal 3.72 77 446
service: total inventory turnovers 5.14 928 617
service: total monetary turnovers 6.86 23 823
service: aggregate perf. data 0.78 16928 93
service: refresh index statistics 0.03 292425 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 11-05-2022 16:40:46 to 11-05-2022 16:40:48.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6104
TEST_TIME 2 6427
TEST_TIME 3 6205
TEST_TIME 4 5966
TEST_TIME 5 6210
TEST_TIME 6 5849
TEST_TIME 7 5984
TEST_TIME 8 6037
TEST_TIME 9 6113
TEST_TIME 10 6277
TEST_TIME 11 6280
TEST_TIME 12 7060
TEST_TIME 13 7228
TEST_TIME 14 7484
TEST_TIME 15 7942
TEST_TIME 16 7961
TEST_TIME 17 8375
TEST_TIME 18 8507
TEST_TIME 19 8728
TEST_TIME 20 8903
TEST_TIME 21 9163
TEST_TIME 22 9268
TEST_TIME 23 9519
TEST_TIME 24 9747
TEST_TIME 25 9826
TEST_TIME 26 10112
TEST_TIME 27 10344
TEST_TIME 28 10290
TEST_TIME 29 10175
TEST_TIME 30 10157
TEST_TIME 31 10011
TEST_TIME 32 9906
TEST_TIME 33 9814
TEST_TIME 34 9647
TEST_TIME 35 9545
TEST_TIME 36 9658
TEST_TIME 37 9563
TEST_TIME 38 9703
TEST_TIME 39 9526
TEST_TIME 40 9421
TEST_TIME 41 9315
TEST_TIME 42 9247
TEST_TIME 43 9230
TEST_TIME 44 9146
TEST_TIME 45 9224
TEST_TIME 46 9204
TEST_TIME 47 9065
TEST_TIME 48 9173
TEST_TIME 49 9084
TEST_TIME 50 8975
TEST_TIME 51 8920
TEST_TIME 52 8929
TEST_TIME 53 8904
TEST_TIME 54 8926
TEST_TIME 55 8886
TEST_TIME 56 8935
TEST_TIME 57 8938
TEST_TIME 58 8998
TEST_TIME 59 9067
TEST_TIME 60 9051
TEST_TIME 61 9088
TEST_TIME 62 9144
TEST_TIME 63 9174
TEST_TIME 64 9233
TEST_TIME 65 9244
TEST_TIME 66 9230
TEST_TIME 67 9214
TEST_TIME 68 9099
TEST_TIME 69 9090
TEST_TIME 70 9032
TEST_TIME 71 9023
TEST_TIME 72 9002
TEST_TIME 73 8949
TEST_TIME 74 8903
TEST_TIME 75 8836
TEST_TIME 76 8874
TEST_TIME 77 8980
TEST_TIME 78 8979
TEST_TIME 79 8958
TEST_TIME 80 8976
TEST_TIME 81 9027
TEST_TIME 82 9089
TEST_TIME 83 9078
TEST_TIME 84 9109
TEST_TIME 85 9181
TEST_TIME 86 9138
TEST_TIME 87 9181
TEST_TIME 88 9170
TEST_TIME 89 9201
TEST_TIME 90 9174
TEST_TIME 91 9088
TEST_TIME 92 9117
TEST_TIME 93 9105
TEST_TIME 94 9035
TEST_TIME 95 9026
TEST_TIME 96 9013
TEST_TIME 97 9019
TEST_TIME 98 9063
TEST_TIME 99 9088
TEST_TIME 100 9088
TEST_TIME 101 9057
TEST_TIME 102 9139
TEST_TIME 103 9142
TEST_TIME 104 9188
TEST_TIME 105 9187
TEST_TIME 106 9184
TEST_TIME 107 9280
TEST_TIME 108 9272
TEST_TIME 109 9246
TEST_TIME 110 9256
TEST_TIME 111 9270
TEST_TIME 112 9208
TEST_TIME 113 9196
TEST_TIME 114 9145
TEST_TIME 115 9138
TEST_TIME 116 9119
TEST_TIME 117 9078
TEST_TIME 118 9102
TEST_TIME 119 9072
TEST_TIME 120 9003

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 11-05-2022 16:40:50 to 11-05-2022 16:40:53.

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 1519088 1519088 0 0.00 0 25202 7 0 0
doc_list_biud 1519087 1519087 0 0.00 0 24885 24 0 0
sp_add_invoice_to_stock 5312 5248 64 1.20 43 228810 18889 0 64
sp_cancel_adding_invoice 5268 5268 0 0.00 5 56795 858 0 0
sp_cancel_client_order 1793 1793 0 0.00 4 16396 336 0 0
sp_cancel_customer_reserve 517566 517566 0 0.00 1 24886 56 0 0
sp_cancel_pay_from_customer 710 710 0 0.00 2 3223 71 0 0
sp_cancel_pay_to_supplier 446 446 0 0.00 3 6350 78 0 0
sp_cancel_supplier_invoice 5813 5808 5 0.08 2 14930 745 0 5
sp_cancel_supplier_order 380 380 0 0.00 4 1018129 95701 0 0
sp_cancel_write_off 9322 9322 0 0.00 1 8005 104 0 0
sp_client_order 21415 21415 0 0.00 3 18050 356 0 0
sp_customer_reserve 475040 475040 0 0.00 0 46447 193 0 0
sp_fill_shopping_cart 32592 32592 0 0.00 1 13565 39 0 0
sp_fill_shopping_cart_clo_res 475017 475017 0 0.00 0 16967 14 0 0
sp_get_clo_for_invoice 5224 5224 0 0.00 0 23530 295 0 0
sp_kill_cost_storno 15746 15746 0 0.00 0 25182 57 0 0
sp_kill_qstorno_ret_qs2qd 1050642 1050642 0 0.00 0 24860 30 0 0
sp_kill_qty_storno 555630 555630 0 0.00 0 24885 66 0 0
sp_lock_selected_doc 21315 21315 0 0.00 0 7301 3 0 0
sp_make_cost_storno 28821 28821 0 0.00 0 18708 50 0 0
sp_multiply_rows_for_pdistr 28821 28821 0 0.00 0 13505 29 0 0
sp_multiply_rows_for_qdistr 21415 21415 0 0.00 0 16253 157 0 0
sp_pay_from_customer 7299 7299 0 0.00 4 11225 105 0 0
sp_pay_to_supplier 6029 6029 0 0.00 4 18717 117 0 0
sp_payment_common 13328 13328 0 0.00 1 8583 27 0 0
sp_qd_handle_on_cancel_clo 1793 1793 0 0.00 0 16385 316 0 0
sp_qd_handle_on_invoice_upd_sts 10492 10492 0 0.00 0 15204 319 0 0
sp_qd_handle_on_reserve_upd_sts 10269 10269 0 0.00 0 13277 59 0 0
sp_reserve_write_off 10269 10269 0 0.00 6 13294 169 0 0
sp_supplier_invoice 5613 5613 0 0.00 6 86652 3444 0 0
sp_supplier_order 5566 5566 0 0.00 5 25647 1260 0 0
srv_aggregate_perf_data 94 93 1 1.06 2802 115554 16928 0 1
srv_fill_mon_cache_memory 63 63 0 0.00 2227 146956 46870 0 0
srv_find_qd_qs_mism 1030124 1030124 0 0.00 0 14328 6 0 0
srv_make_invnt_saldo 617 617 0 0.00 27 24681 928 0 0
srv_make_money_saldo 823 823 0 0.00 1 756 23 0 0
srv_recalc_idx_stat 4 4 0 0.00 134249 569270 292426 0 0
t$perf-abend:sp_add_invoice_to_stock 64 64 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 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 5248 5248 0 0.00 0 13411 158 0 0
t$perf-norm:sp_cancel_adding_invoice 431 431 0 0.00 1 4788 99 0 0
t$perf-norm:sp_cancel_client_order 1793 1793 0 0.00 0 3612 16 0 0
t$perf-norm:sp_cancel_customer_reserve 697 697 0 0.00 0 4979 16 0 0
t$perf-norm:sp_cancel_pay_from_customer 710 710 0 0.00 0 1219 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 446 446 0 0.00 0 650 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 398 398 0 0.00 0 4248 29 0 0
t$perf-norm:sp_cancel_supplier_order 380 380 0 0.00 0 28216 1743 0 0
t$perf-norm:sp_cancel_write_off 969 969 0 0.00 0 7296 21 0 0
t$perf-norm:sp_client_order 21415 21415 0 0.00 0 7407 17 0 0
t$perf-norm:sp_customer_reserve 1010 1010 0 0.00 0 2121 8 0 0
t$perf-norm:sp_pay_from_customer 7299 7299 0 0.00 0 7366 9 0 0
t$perf-norm:sp_pay_to_supplier 6029 6029 0 0.00 0 3992 11 0 0
t$perf-norm:sp_reserve_write_off 10269 10269 0 0.00 0 7616 10 0 0
t$perf-norm:sp_supplier_invoice 5613 5613 0 0.00 0 7071 17 0 0
t$perf-norm:sp_supplier_order 5566 5566 0 0.00 0 4922 15 0 0
t$perf-norm:srv_aggregate_perf_data 93 93 0 0.00 0 342 15 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 17 1 0 0
t$perf-norm:srv_make_invnt_saldo 617 617 0 0.00 0 1634 17 0 0
t$perf-norm:srv_make_money_saldo 823 823 0 0.00 0 3051 23 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 8 3 0 0
v_add_invoice_to_stock 10572 10444 128 1.21 0 4900 16 0 128
v_all_customers 38622 38622 0 0.00 0 14 1 0 0
v_all_suppliers 30794 30794 0 0.00 0 16 1 0 0
v_all_wares 42830 42830 0 0.00 0 22 1 0 0
v_cancel_adding_invoice 862 862 0 0.00 0 302 6 0 0
v_cancel_client_order 3586 3586 0 0.00 0 2585 4 0 0
v_cancel_customer_prepayment 1420 1420 0 0.00 0 2363 9 0 0
v_cancel_customer_reserve 1394 1394 0 0.00 0 454 3 0 0
v_cancel_payment_to_supplier 892 892 0 0.00 0 959 4 0 0
v_cancel_supplier_invoice 806 796 10 1.24 0 2179 15 0 10
v_cancel_supplier_order 760 760 0 0.00 0 2401 11 0 0
v_cancel_write_off 1938 1938 0 0.00 0 1783 12 0 0
v_max_id_clo_ord 5566 5566 0 0.00 0 291 1 0 0
v_max_id_clo_res 1010 1010 0 0.00 0 4365 23 0 0
v_max_id_ord_sup 5611 5611 0 0.00 0 5566 8 0 0
v_max_non_paid_invoice 1809 1809 0 0.00 0 1754 4 0 0
v_max_non_paid_realizn 4029 4029 0 0.00 0 1885 4 0 0
v_min_id_clo_ord 5566 5566 0 0.00 0 4433 7 0 0
v_min_id_clo_res 1010 1010 0 0.00 0 4547 8 0 0
v_min_id_ord_sup 5611 5611 0 0.00 0 6079 11 0 0
v_min_non_paid_invoice 6029 6029 0 0.00 0 6569 19 0 0
v_min_non_paid_realizn 7299 7299 0 0.00 0 6807 20 0 0
v_our_firm 10748 10748 0 0.00 0 42 1 0 0
v_reserve_write_off 20538 20538 0 0.00 0 4600 6 0 0
x_lock_depdocs_on_canc_invoice 10678 10678 0 0.00 0 15738 114 0 0
x_lock_depdocs_on_canc_sup_ord 380 380 0 0.00 0 10184 317 0 0
x_make_qstorno_1000_1200 5566 5566 0 0.00 2 25643 1183 0 0
x_make_qstorno_1200_2000 5613 5613 0 0.00 3 80562 3298 0 0
x_make_qstorno_2100_3300 475040 475040 0 0.00 0 46446 176 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 11-05-2022 16:40:54 to 11-05-2022 16:40:58.

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
14:08:19 11 8192000000 8799491408 8811085824 5315664 0.064 2 1 2 0 5415264 56816 42784
14:09:20 32412 8192000000 9897799680 10205536256 974206992 10.628 100 92 183 1 1004452896 6096416 24149488
14:10:56 19915 8192000000 9938551648 10259156992 1018369680 11.056 100 88 176 0 1049615936 8440320 22805936
14:12:24 20601 8192000000 9969254896 10302406656 1055589264 11.414 100 84 167 1 1086431504 9950464 20891776
14:13:48 10237 8192000000 10026407792 10371280896 1109704736 11.930 100 81 162 1 1140753616 11639792 19409088
14:15:08 7033 8192000000 10082671888 10441773056 1173556896 12.530 100 81 163 1 1205016416 12955200 18504320
14:16:18 4313 8192000000 10138101616 10506371072 1217232064 12.936 100 80 159 3 1247493456 12923488 17337904
14:17:25 2705 8192000000 10163807888 10548592640 1255032304 13.284 100 79 157 0 1284712368 12435648 17244416
14:18:29 2064 8192000000 10207417216 10589339648 1284464496 13.554 100 88 177 1 1317414208 13329232 19620480
14:19:33 2159 8192000000 10215034736 10599165952 1297781712 13.675 100 89 178 0 1331301424 13634720 19884992
14:20:37 4354 8192000000 10191013712 10601725952 1310338928 13.789 100 49 99 0 1328305536 6173264 11793344
14:21:45 10663 8192000000 10198923776 10612047872 1318865504 13.866 100 24 48 0 1329938896 5425344 5648048
14:22:58 9286 8192000000 10202988176 10621771776 1322022608 13.895 100 17 34 1 1331314368 5128192 4163568
14:24:11 9089 8192000000 10214938656 10628853760 1329184624 13.960 100 33 66 1 1342877680 6081168 7611888
14:25:21 17121 8192000000 10248147152 10645262336 1332907680 13.993 100 73 150 1 1356542672 5177104 18457888
14:26:38 5554 8192000000 10232137632 10656194560 1338319056 14.042 100 35 70 0 1357039072 10570576 8149440
14:27:44 50828 8192000000 10237092144 10652938240 1338038000 14.040 100 29 58 1 1350088448 5448240 6602208
14:29:36 6120 8192000000 10196573424 10603761664 1287331872 13.580 99 24 48 2 1302246384 9751056 5163456
14:30:46 6996 8192000000 10153955424 10558914560 1248926448 13.228 98 26 53 2 1261285664 5860784 6498432
14:31:54 4690 8192000000 10134373200 10530603008 1240561312 13.151 100 28 56 0 1254015472 6785280 6668880
14:33:02 14496 8192000000 10134389088 10535907328 1218791616 12.950 97 20 39 0 1228854128 5813120 4249392
14:34:17 7484 8192000000 10164836384 10575187968 1271008448 13.431 100 19 39 2 1285060976 9423840 4628688
14:35:25 8764 8192000000 10180834640 10589929472 1289717744 13.602 100 22 42 1 1299949552 4827040 5404768
14:36:34 10346 8192000000 10199836944 10602967040 1298183552 13.679 100 33 65 3 1311588528 6520320 6884656
14:37:45 15602 8192000000 10191228240 10608103424 1301026096 13.705 100 11 22 2 1308297680 4473392 2798192
14:39:06 12249 8192000000 10234288800 10644144128 1331311856 13.979 100 47 94 0 1350483568 8766672 10405040
14:40:40 29562 8192000000 10268957232 10679549952 1353259952 14.177 100 50 99 2 1377017232 12600992 11156288
14:42:15 49667 8192000000 10279951280 10705031168 1370291712 14.330 100 41 82 2 1395143712 15084464 9767536
14:44:08 6969 8192000000 10299894560 10717581312 1385335152 14.464 100 64 128 1 1416203856 15030272 15838432
14:45:24 23117 8192000000 10324177520 10744692736 1397714704 14.575 100 64 128 0 1431281888 17914944 15652240
14:46:55 6849 8192000000 10324195056 10750353408 1407815344 14.665 100 44 86 0 1438276816 19242240 11219232
14:48:05 6082 8192000000 10334629488 10756046848 1409020640 14.675 100 57 111 1 1444798720 21829680 13948400
14:49:15 8378 8192000000 10328704768 10746368000 1397718304 14.575 100 52 104 4 1435053744 24347888 12987552
14:50:28 2226 8192000000 10340810560 10749063168 1375014752 14.372 98 79 158 2 1421830416 27486032 19329632
14:51:36 2377 8192000000 10347379120 10759577600 1400316352 14.598 100 78 154 1 1446485776 27404032 18765392
14:52:42 4118 8192000000 10332922896 10746720256 1389162560 14.498 100 79 158 2 1437626624 28964064 19500000
14:53:50 5781 8192000000 10332531888 10750767104 1378971600 14.407 99 59 116 0 1423589280 29723168 14894512
14:54:59 3129 8192000000 10340834928 10760003584 1397269664 14.571 100 76 149 2 1448379296 32409648 18699984
14:56:09 3491 8192000000 10321277696 10724786176 1370907648 14.335 100 88 175 1 1423982160 31161648 21912864
14:57:20 6534 8192000000 10293775904 10715770880 1361894528 14.254 99 48 96 1 1395715472 21416224 12404720
14:58:42 8363 8192000000 10305069504 10730799104 1365203280 14.284 99 38 76 1 1403069152 27643840 10222032
15:00:19 11724 8192000000 10291623088 10718142464 1353900432 14.183 99 38 76 3 1395456144 31334448 10221264
15:01:32 16044 8192000000 10293144304 10733654016 1373973920 14.363 100 25 50 0 1407990720 26973984 7042816
15:02:49 13255 8192000000 10292456464 10723844096 1364748208 14.280 100 23 44 1 1392899648 22021744 6129696
15:04:05 41592 8192000000 10299014480 10718220288 1359599856 14.234 100 39 78 2 1393467168 22680144 11187168
15:05:47 52753 8192000000 10268468912 10704404480 1346926464 14.120 99 34 67 0 1370420336 15035920 8457952
15:07:41 20394 8192000000 10279695632 10720747520 1368561904 14.314 100 44 87 1 1392411392 13144848 10704640
15:09:02 25095 8192000000 10267189600 10709118976 1349687568 14.145 99 26 52 1 1372422448 16508240 6226640
15:10:33 99648 8192000000 10294111904 10743201792 1366103216 14.292 99 13 26 1 1387626736 18855856 2667664
15:13:14 58907 8192000000 10294592000 10736533504 1369652832 14.324 99 17 34 4 1398777072 24747200 4377040
15:15:19 68607 8192000000 10291154656 10732883968 1381321264 14.428 100 44 86 4 1406885280 15266144 10297872
15:17:44 24390 8192000000 10286817408 10739027968 1381244336 14.428 100 12 24 0 1399352720 14818592 3289792
15:19:09 38306 8192000000 10289040128 10739507200 1382782592 14.441 100 35 70 1 1406083600 14793760 8507248
15:20:54 75193 8192000000 10292085424 10742554624 1373427072 14.358 99 12 24 0 1391945824 15709504 2809248
15:23:11 64490 8192000000 10298399520 10743996416 1375196688 14.374 99 13 26 0 1390970000 12090832 3682480
15:25:26 68670 8192000000 10304851360 10754150400 1387886080 14.487 100 21 42 0 1408887136 15667952 5333104
15:27:35 70362 8192000000 10288025504 10749251584 1398525856 14.582 100 14 26 1 1411174736 9819424 2829456
15:30:39 53590 8192000000 10304807312 10764091392 1402489600 14.617 100 13 26 0 1414016672 8577664 2949408
15:32:34 43594 8192000000 10302602640 10761908224 1402768096 14.620 100 23 46 0 1416776272 8740208 5267968
15:34:22 49922 8192000000 10285696048 10740928512 1350634672 14.153 97 24 48 0 1368362272 12547904 5179696
15:36:12 105211 8192000000 10292773440 10764443648 1330983520 13.976 95 13 26 2 1355479232 21458944 3036768
15:38:58 76330 8192000000 10268407776 10708582400 1236184656 13.111 89 17 34 0 1248868576 9635952 3047968
15:41:20 95568 8192000000 10262280864 10719940608 1283465968 13.545 93 17 34 0 1302026208 14871312 3688928
15:43:56 59580 8192000000 10252908176 10697965568 1290774864 13.611 95 13 26 1 1303220016 9747072 2698080
15:45:55 90204 8192000000 10265254416 10704576512 1334768656 14.010 98 33 66 0 1351320736 9202144 7349936
15:48:28 59611 8192000000 10274746912 10709348352 1352743840 14.172 99 47 94 1 1373707584 10164752 10798992
15:50:29 41619 8192000000 10235295552 10685886464 1320373376 13.880 97 12 24 0 1329803744 6814688 2615680
15:52:11 72360 8192000000 10242068144 10682146816 1310983184 13.795 97 14 28 0 1325888800 11224320 3681296
15:54:27 60148 8192000000 10247311632 10705063936 1315209328 13.833 97 10 20 0 1329237392 11511440 2516624
15:56:31 42559 8192000000 10247403744 10691203072 1333289376 13.997 99 14 28 2 1343880240 7686480 2904384
15:58:13 47022 8192000000 10264245312 10707013632 1344370320 14.097 99 33 66 0 1364393792 12532176 7491296
16:00:01 75796 8192000000 10268233872 10703757312 1315542352 13.836 97 23 46 0 1332637488 11851696 5243440
16:02:20 56293 8192000000 10260932512 10720890880 1349369840 14.142 99 23 46 2 1369698992 15110192 5218960
16:04:33 88785 8192000000 10271527248 10712915968 1345966608 14.111 98 26 52 2 1363331664 10743248 6621808
16:07:02 76839 8192000000 10276937184 10727927808 1356096112 14.202 99 18 36 0 1374399040 13768432 4534496
16:10:40 51778 8192000000 10260262000 10724999168 1342320096 14.078 97 13 26 0 1362779600 17529168 2930336
16:13:45 53482 8192000000 10285792624 10738278400 1374921744 14.371 99 12 24 0 1384897184 7408672 2566768
16:15:39 70054 8192000000 10288720336 10746109952 1374491072 14.367 99 14 28 1 1387763296 9957152 3315072
16:17:49 64323 8192000000 10267225408 10708852736 1318291056 13.861 96 13 26 0 1336673344 15187072 3195216
16:19:54 60377 8192000000 10297992896 10759524352 1379368720 14.411 100 18 36 1 1401889664 18613568 3907376
16:22:04 146931 8192000000 10291763968 10737074176 1347732656 14.127 97 12 24 0 1363862672 13283056 2846960
16:25:55 94111 8192000000 10305018512 10743812096 1330660688 13.973 96 8 16 1 1350346784 17600304 2085792
16:28:35 68515 8192000000 10276686048 10719944704 1325607920 13.927 96 27 54 0 1349913024 18247952 6057152
16:30:50 60505 8192000000 10277244416 10724839424 1361865408 14.254 99 31 62 1 1377101424 7657344 7578672
16:32:51 49835 8192000000 10268963600 10722832384 1348074064 14.130 98 22 44 0 1364000240 11042848 4883328
16:34:42 50228 8192000000 10269240992 10726113280 1345384176 14.106 98 9 18 0 1363371328 15555968 2431184
16:36:34 40807 8192000000 10257426560 10710630400 1335637568 14.018 98 13 26 0 1355029392 16554768 2837056
16:38:15 18374 8192000000 9349594272 9651539968 500883840 5.761 37 4 8 0 514155376 12634320 637216

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 11-05-2022 16:41:00 to 11-05-2022 16:41:15.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 64 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 11-05-2022 16:41:16 to 11-05-2022 16:41:18.

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          240609
MON$OLDEST_ACTIVE               240959
MON$OLDEST_SNAPSHOT             240959
MON$NEXT_TRANSACTION            240959
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                       2004672
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33587 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33587 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33587 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33587 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 ---
:154: 
:155: mscs-test-161.budzdorov.ru    Wed May 11 16:38:42 2022
:156:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:157: 
:158: 
:159: mscs-test-161.budzdorov.ru    Wed May 11 16:38:54 2022
:160:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:161: 
--- end of diff output ---

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

11.05.22 16:41:19. 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-05-11 16:41:21 No severe PSQL-related problems occured
11.05.22 16:41:23. End of report.