Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 03.12.2021 01: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 16940640 kB
MemAvailable 31490056 kB
Buffers 0 kB
Cached 14722736 kB
SwapCached 0 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.8
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 2021-12-03 01:38:00
DTS_END                         2021-12-03 01:38:00.5910
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/36628
TRN_ID                          241929
ATT_ID                          635
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: 8863.92 23276 1063671
customer order: creation 171.55 374 20586
customer order: refuse 15.20 355 1824
order to supplier: creation 44.85 1255 5382
order to supplier: removal 3.10 109487 372
invoice (draft): creation 45.78 3516 5494
invoice (draft): removal 47.50 841 5700
invoice accept: apply 42.47 19248 5096
invoice accept: cancel 43.01 803 5161
customer reserve: creation 3894.68 192 467362
customer reserve: removal 4261.97 63 511436
realization accept: apply 84.23 190 10108
realization accept: cancel 79.20 117 9504
payment from customer: creation 59.20 153 7104
payment from customer: removal 5.57 127 668
payment to supplier: creation 49.56 144 5947
payment to supplier: removal 3.23 75 387
service: total inventory turnovers 5.05 907 606
service: total monetary turnovers 6.85 70 822
service: aggregate perf. data 0.90 18230 108
service: refresh index statistics 0.03 309373 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 03-12-2021 01:38:54 to 03-12-2021 01:38:56.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5555
TEST_TIME 2 3915
TEST_TIME 3 4503
TEST_TIME 4 4835
TEST_TIME 5 5765
TEST_TIME 6 5720
TEST_TIME 7 5664
TEST_TIME 8 5505
TEST_TIME 9 5374
TEST_TIME 10 5471
TEST_TIME 11 5550
TEST_TIME 12 5712
TEST_TIME 13 5783
TEST_TIME 14 6102
TEST_TIME 15 6125
TEST_TIME 16 6540
TEST_TIME 17 6669
TEST_TIME 18 7019
TEST_TIME 19 7144
TEST_TIME 20 7837
TEST_TIME 21 8180
TEST_TIME 22 8957
TEST_TIME 23 9396
TEST_TIME 24 9734
TEST_TIME 25 10632
TEST_TIME 26 10543
TEST_TIME 27 10319
TEST_TIME 28 10299
TEST_TIME 29 10450
TEST_TIME 30 10238
TEST_TIME 31 10061
TEST_TIME 32 9870
TEST_TIME 33 9808
TEST_TIME 34 9820
TEST_TIME 35 9636
TEST_TIME 36 9718
TEST_TIME 37 9571
TEST_TIME 38 9595
TEST_TIME 39 9457
TEST_TIME 40 9264
TEST_TIME 41 9226
TEST_TIME 42 9126
TEST_TIME 43 9141
TEST_TIME 44 9298
TEST_TIME 45 9197
TEST_TIME 46 9198
TEST_TIME 47 9117
TEST_TIME 48 9081
TEST_TIME 49 9072
TEST_TIME 50 9072
TEST_TIME 51 9161
TEST_TIME 52 9204
TEST_TIME 53 9107
TEST_TIME 54 9148
TEST_TIME 55 9180
TEST_TIME 56 9242
TEST_TIME 57 9172
TEST_TIME 58 9162
TEST_TIME 59 9206
TEST_TIME 60 9336
TEST_TIME 61 9311
TEST_TIME 62 9277
TEST_TIME 63 9209
TEST_TIME 64 9111
TEST_TIME 65 9083
TEST_TIME 66 9063
TEST_TIME 67 9001
TEST_TIME 68 8991
TEST_TIME 69 8902
TEST_TIME 70 8931
TEST_TIME 71 8989
TEST_TIME 72 8930
TEST_TIME 73 8859
TEST_TIME 74 8905
TEST_TIME 75 8885
TEST_TIME 76 8889
TEST_TIME 77 8942
TEST_TIME 78 8982
TEST_TIME 79 8948
TEST_TIME 80 9029
TEST_TIME 81 9019
TEST_TIME 82 9009
TEST_TIME 83 9054
TEST_TIME 84 9018
TEST_TIME 85 9078
TEST_TIME 86 9062
TEST_TIME 87 9094
TEST_TIME 88 9053
TEST_TIME 89 8999
TEST_TIME 90 8968
TEST_TIME 91 8954
TEST_TIME 92 8918
TEST_TIME 93 8851
TEST_TIME 94 8890
TEST_TIME 95 8871
TEST_TIME 96 8887
TEST_TIME 97 8877
TEST_TIME 98 8853
TEST_TIME 99 8902
TEST_TIME 100 8876
TEST_TIME 101 8907
TEST_TIME 102 9004
TEST_TIME 103 9006
TEST_TIME 104 8965
TEST_TIME 105 9050
TEST_TIME 106 9022
TEST_TIME 107 9058
TEST_TIME 108 9004
TEST_TIME 109 8949
TEST_TIME 110 8954
TEST_TIME 111 8941
TEST_TIME 112 8888
TEST_TIME 113 8887
TEST_TIME 114 8864
TEST_TIME 115 8877
TEST_TIME 116 8931
TEST_TIME 117 8905
TEST_TIME 118 8917
TEST_TIME 119 8936
TEST_TIME 120 8865

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 03-12-2021 01:38:58 to 03-12-2021 01:39:01.

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 1496197 1496197 0 0.00 0 33393 7 0 0
doc_list_biud 1496197 1496197 0 0.00 0 34010 27 0 0
sp_add_invoice_to_stock 5198 5096 102 1.96 40 206688 19249 0 102
sp_cancel_adding_invoice 5161 5161 0 0.00 5 66483 804 0 0
sp_cancel_client_order 1824 1824 0 0.00 4 11766 356 0 0
sp_cancel_customer_reserve 511436 511436 0 0.00 1 23440 63 0 0
sp_cancel_pay_from_customer 668 668 0 0.00 2 18346 128 0 0
sp_cancel_pay_to_supplier 387 387 0 0.00 3 5388 75 0 0
sp_cancel_supplier_invoice 5706 5700 6 0.10 2 16093 842 0 6
sp_cancel_supplier_order 372 372 0 0.00 6 1202295 109487 0 0
sp_cancel_write_off 9504 9504 0 0.00 1 14769 117 0 0
sp_client_order 20586 20586 0 0.00 3 17311 375 0 0
sp_customer_reserve 467362 467362 0 0.00 0 67050 192 0 0
sp_fill_shopping_cart 31461 31461 0 0.00 1 20570 45 0 0
sp_fill_shopping_cart_clo_res 467358 467358 0 0.00 0 14503 15 0 0
sp_get_clo_for_invoice 5092 5092 0 0.00 0 26044 300 0 0
sp_kill_cost_storno 15720 15720 0 0.00 0 18333 57 0 0
sp_kill_qstorno_ret_qs2qd 1038448 1038448 0 0.00 0 23418 34 0 0
sp_kill_qty_storno 549197 549197 0 0.00 0 34008 74 0 0
sp_lock_selected_doc 20970 20970 0 0.00 0 8335 5 0 0
sp_make_cost_storno 28251 28251 0 0.00 0 24810 68 0 0
sp_multiply_rows_for_pdistr 28251 28251 0 0.00 0 25110 34 0 0
sp_multiply_rows_for_qdistr 20586 20586 0 0.00 0 16968 150 0 0
sp_pay_from_customer 7104 7104 0 0.00 4 27098 153 0 0
sp_pay_to_supplier 5947 5947 0 0.00 4 28832 145 0 0
sp_payment_common 13051 13051 0 0.00 1 28823 44 0 0
sp_qd_handle_on_cancel_clo 1824 1824 0 0.00 0 9694 324 0 0
sp_qd_handle_on_invoice_upd_sts 10253 10253 0 0.00 0 34006 338 0 0
sp_qd_handle_on_reserve_upd_sts 10108 10108 0 0.00 0 15909 56 0 0
sp_reserve_write_off 10108 10108 0 0.00 6 21915 190 0 0
sp_supplier_invoice 5494 5494 0 0.00 6 103478 3516 0 0
sp_supplier_order 5382 5382 0 0.00 5 33510 1256 0 0
srv_aggregate_perf_data 109 108 1 0.91 2668 215803 18231 0 1
srv_fill_mon_cache_memory 65 65 0 0.00 1807 152384 46494 0 0
srv_find_qd_qs_mism 1015645 1015645 0 0.00 0 23379 6 0 0
srv_make_invnt_saldo 606 606 0 0.00 41 56356 908 0 0
srv_make_money_saldo 822 822 0 0.00 1 26346 71 0 0
srv_recalc_idx_stat 4 4 0 0.00 212047 378632 309373 0 0
t$perf-abend:sp_add_invoice_to_stock 102 102 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 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 5097 5097 0 0.00 0 22396 156 0 0
t$perf-norm:sp_cancel_adding_invoice 422 422 0 0.00 0 3115 87 0 0
t$perf-norm:sp_cancel_client_order 1824 1824 0 0.00 0 2285 13 0 0
t$perf-norm:sp_cancel_customer_reserve 703 703 0 0.00 0 1385 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 668 668 0 0.00 0 15190 39 0 0
t$perf-norm:sp_cancel_pay_to_supplier 387 387 0 0.00 0 2421 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 383 383 0 0.00 0 2057 25 0 0
t$perf-norm:sp_cancel_supplier_order 372 372 0 0.00 0 31032 1866 0 0
t$perf-norm:sp_cancel_write_off 1011 1011 0 0.00 0 4382 12 0 0
t$perf-norm:sp_client_order 20586 20586 0 0.00 0 8647 21 0 0
t$perf-norm:sp_customer_reserve 967 967 0 0.00 0 1384 7 0 0
t$perf-norm:sp_pay_from_customer 7104 7104 0 0.00 0 12059 12 0 0
t$perf-norm:sp_pay_to_supplier 5947 5947 0 0.00 0 6915 12 0 0
t$perf-norm:sp_reserve_write_off 10108 10108 0 0.00 0 15856 14 0 0
t$perf-norm:sp_supplier_invoice 5494 5494 0 0.00 0 7476 19 0 0
t$perf-norm:sp_supplier_order 5382 5382 0 0.00 0 6707 18 0 0
t$perf-norm:srv_aggregate_perf_data 108 108 0 0.00 0 398 15 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 4431 76 0 0
t$perf-norm:srv_make_invnt_saldo 606 606 0 0.00 0 2533 36 0 0
t$perf-norm:srv_make_money_saldo 822 822 0 0.00 0 5956 20 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 6 4 0 0
v_add_invoice_to_stock 10388 10184 204 1.96 0 7518 21 0 204
v_all_customers 37220 37220 0 0.00 0 14 0 0 0
v_all_suppliers 30342 30342 0 0.00 0 56 1 0 0
v_all_wares 41172 41172 0 0.00 0 174 1 0 0
v_cancel_adding_invoice 844 844 0 0.00 0 2834 12 0 0
v_cancel_client_order 3648 3648 0 0.00 0 5018 6 0 0
v_cancel_customer_prepayment 1336 1336 0 0.00 0 3834 8 0 0
v_cancel_customer_reserve 1406 1406 0 0.00 0 5333 10 0 0
v_cancel_payment_to_supplier 774 774 0 0.00 0 825 4 0 0
v_cancel_supplier_invoice 778 766 12 1.54 0 3819 25 0 12
v_cancel_supplier_order 744 744 0 0.00 0 2801 7 0 0
v_cancel_write_off 2022 2022 0 0.00 0 4008 16 0 0
v_max_id_clo_ord 5382 5382 0 0.00 0 266 1 0 0
v_max_id_clo_res 967 967 0 0.00 0 2439 12 0 0
v_max_id_ord_sup 5493 5493 0 0.00 0 6182 7 0 0
v_max_non_paid_invoice 1651 1651 0 0.00 0 2341 4 0 0
v_max_non_paid_realizn 4002 4002 0 0.00 0 6022 5 0 0
v_min_id_clo_ord 5382 5382 0 0.00 0 4802 9 0 0
v_min_id_clo_res 967 967 0 0.00 0 4221 14 0 0
v_min_id_ord_sup 5493 5493 0 0.00 0 9475 10 0 0
v_min_non_paid_invoice 5947 5947 0 0.00 0 28817 32 0 0
v_min_non_paid_realizn 7104 7104 0 0.00 0 18345 32 0 0
v_our_firm 10156 10156 0 0.00 0 15 1 0 0
v_reserve_write_off 20216 20216 0 0.00 0 8516 8 0 0
x_lock_depdocs_on_canc_invoice 10478 10478 0 0.00 0 18728 116 0 0
x_lock_depdocs_on_canc_sup_ord 372 372 0 0.00 0 3839 317 0 0
x_make_qstorno_1000_1200 5382 5382 0 0.00 1 33437 1179 0 0
x_make_qstorno_1200_2000 5494 5494 0 0.00 3 103464 3341 0 0
x_make_qstorno_2100_3300 467362 467362 0 0.00 0 67049 175 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 03-12-2021 01:39:02 to 03-12-2021 01:39:06.

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
23:08:04 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:04 19659 8192000000 9891275568 10200367104 969178272 10.579 100 84 168 0 996764928 5371792 22214864
23:10:30 25997 8192000000 9945998272 10265919488 1025961104 11.130 100 90 180 0 1056692704 7715968 23015632
23:12:04 18360 8192000000 9994286528 10328653824 1074701152 11.597 100 86 170 1 1104454768 9139600 20614016
23:13:25 13768 8192000000 10042466912 10393923584 1127466704 12.097 100 75 149 0 1155551312 11025664 17058944
23:14:43 7729 8192000000 10100304032 10461057024 1179504688 12.586 100 77 154 1 1209407632 12254336 17648608
23:15:53 4359 8192000000 10134870368 10508042240 1223447280 12.994 100 84 169 0 1255759584 12696064 19616240
23:16:59 4062 8192000000 10166205968 10541596672 1247653120 13.217 100 84 168 0 1281691696 15480128 18558448
23:18:04 2384 8192000000 10197623072 10578739200 1285335104 13.562 100 91 182 2 1318116400 13007216 19774080
23:19:08 1217 8192000000 10220181216 10609958912 1306665520 13.756 100 93 186 0 1341268672 13165936 21437216
23:20:10 15895 8192000000 10194370512 10609795072 1311314400 13.798 100 24 48 1 1323461072 6041168 6105504
23:21:27 8769 8192000000 10198165648 10619392000 1317049712 13.850 100 15 30 0 1326060512 6128688 2882112
23:22:37 8788 8192000000 10214891136 10630385664 1330567424 13.972 100 15 30 0 1339253360 5575280 3110656
23:23:51 38447 8192000000 10235013392 10647179264 1332134384 13.986 100 38 76 1 1347261312 5967936 9158992
23:25:39 7039 8192000000 10223864992 10646335488 1331891360 13.984 100 25 50 0 1343602000 6244736 5465904
23:26:48 7644 8192000000 10211967376 10634792960 1316477200 13.845 98 22 43 2 1327322112 5368560 5476352
23:27:56 13550 8192000000 10198241760 10617245696 1240076256 13.147 93 14 28 1 1248666496 5185712 3404528
23:29:10 27952 8192000000 10164087488 10571726848 1223069104 12.990 95 19 35 1 1231960016 4787440 4103472
23:30:40 3894 8192000000 10144016528 10536796160 1232817840 13.080 99 33 66 1 1247230736 6791568 7621328
23:31:45 8639 8192000000 10140965024 10533298176 1230426928 13.058 98 22 44 0 1244552256 9247456 4877872
23:32:54 6372 8192000000 10170943408 10567090176 1254631680 13.281 99 34 69 1 1268928752 5773568 8523504
23:34:02 26166 8192000000 10166323360 10571919360 1261360704 13.342 99 43 86 1 1277947760 7256432 9330624
23:35:29 7793 8192000000 10197516960 10598739968 1288926736 13.594 100 38 76 3 1302895312 6208768 7759808
23:36:41 10928 8192000000 10188977648 10606895104 1302520112 13.718 100 18 36 0 1311964624 5401008 4043504
23:37:53 15015 8192000000 10229837312 10626273280 1313414304 13.817 100 19 38 1 1323020544 5163536 4442704
23:39:16 22638 8192000000 10249681984 10657796096 1340985456 14.066 100 42 84 0 1361663056 10952736 9724864
23:40:42 45780 8192000000 10253724496 10674470912 1350466336 14.152 100 40 80 0 1370616272 10675920 9474016
23:42:35 10106 8192000000 10274550048 10688598016 1357932256 14.219 100 53 102 0 1389414256 20174656 11307344
23:43:56 24052 8192000000 10295470144 10714587136 1374879568 14.371 100 50 100 1 1404620832 18099856 11641408
23:45:25 20389 8192000000 10308772528 10733903872 1391334992 14.518 100 47 92 2 1424316624 21649360 11332272
23:46:51 8855 8192000000 10323988432 10751524864 1400768816 14.602 100 51 99 2 1437923792 24737312 12417664
23:48:06 6987 8192000000 10337980016 10758504448 1396743936 14.566 99 56 112 1 1438677392 28244576 13688880
23:49:17 18089 8192000000 10342688560 10769252352 1412420992 14.705 100 54 107 0 1458667696 32715520 13531184
23:50:39 5845 8192000000 10369209600 10793316352 1432264272 14.881 100 58 114 3 1483700016 37446944 13988800
23:51:50 5387 8192000000 10378389280 10802540544 1431869232 14.878 100 63 126 0 1488590912 40692496 16029184
23:53:02 7430 8192000000 10351805104 10773561344 1398229696 14.579 99 45 89 2 1454074400 43526080 12318624
23:54:15 1808 8192000000 10358060992 10768052224 1395929040 14.559 100 73 145 0 1464550160 49589968 19031152
23:55:20 1806 8192000000 10374073296 10779140096 1399373600 14.589 100 87 170 2 1475796944 55263424 21159920
23:56:27 3915 8192000000 10380786176 10791112704 1397287296 14.571 99 85 167 4 1476004224 57709632 21007296
23:57:37 2873 8192000000 10350261840 10755051520 1350308192 14.150 98 89 177 5 1434294592 60516208 23470192
23:58:43 10153 8192000000 10336381360 10759057408 1365748464 14.289 100 44 88 2 1440226256 62228816 12248976
23:59:56 22363 8192000000 10328558608 10772914176 1376177488 14.382 100 31 62 1 1446523760 61560384 8785888
00:01:27 16132 8192000000 10297246896 10743394304 1354834720 14.191 99 20 40 0 1398065104 37623488 5606896
00:02:45 17988 8192000000 10309916624 10757316608 1365169776 14.284 100 23 46 0 1414929024 43127040 6632208
00:04:04 49162 8192000000 10279958352 10723835904 1349733664 14.145 99 19 38 0 1372692048 17789232 5169152
00:06:11 47547 8192000000 10269389664 10706845696 1341957776 14.075 99 37 74 0 1366111568 15609040 8544752
00:07:59 31847 8192000000 10244887360 10703192064 1347019440 14.121 100 10 20 0 1360409184 11701040 1688704
00:09:32 72066 8192000000 10270039696 10719121408 1343309936 14.087 98 14 26 0 1357596704 11869600 2417168
00:11:45 72978 8192000000 10280726864 10732417024 1350884512 14.155 98 23 46 1 1376765744 20685920 5195312
00:14:15 40031 8192000000 10282840976 10746646528 1377064080 14.390 100 20 39 1 1402471520 20726560 4680880
00:15:56 109113 8192000000 10292861680 10740764672 1342872752 14.083 97 19 38 0 1358098608 11466704 3759152
00:18:46 34876 8192000000 10288168912 10736177152 1374788240 14.370 99 48 96 1 1401327248 15539824 10999184
00:20:27 23169 8192000000 10299526272 10743832576 1377815616 14.397 99 38 76 3 1401060384 14134832 9109936
00:21:58 58517 8192000000 10284269648 10754166784 1363559664 14.269 98 19 38 2 1387416864 18852464 5004736
00:24:01 93525 8192000000 10281065792 10747752448 1380235200 14.419 100 15 30 1 1399986608 15858560 3892848
00:26:35 78307 8192000000 10282009440 10746023936 1384447776 14.456 100 19 38 1 1398708000 9333872 4926352
00:29:01 90748 8192000000 10297078640 10758017024 1379864176 14.415 99 9 18 1 1393385328 11463856 2057296
00:31:32 19914 8192000000 10296904816 10739503104 1361303232 14.249 98 42 83 2 1378671824 7898704 9469888
00:32:57 37660 8192000000 10293409296 10744356864 1363323536 14.267 98 20 39 2 1379447648 11961232 4162880
00:34:43 55413 8192000000 10273799008 10722099200 1301770416 13.711 94 21 42 1 1318861360 12098896 4992048
00:36:46 28498 8192000000 10273833888 10716524544 1341536624 14.071 98 29 57 0 1358480560 10450240 6493696
00:38:18 152384 8192000000 10273713856 10730885120 1280217488 13.515 93 19 38 0 1297227152 13086448 3923216
00:42:17 52103 8192000000 10241734096 10677964800 1261170768 13.341 92 26 50 1 1278538048 11954784 5412496
00:44:10 79126 8192000000 10253737664 10704224256 1326636880 13.937 98 10 20 0 1338314784 9513280 2164624
00:46:29 50525 8192000000 10265370240 10709065728 1310880000 13.794 96 16 32 0 1331209296 16511584 3817712
00:48:33 58050 8192000000 10251721728 10703278080 1331955360 13.985 98 21 42 1 1353945680 17171888 4818432
00:50:32 65797 8192000000 10256133744 10711953408 1338013760 14.039 98 24 49 1 1357024528 13411936 5598832
00:52:46 47525 8192000000 10278713216 10739097600 1358022480 14.220 99 10 20 1 1375488896 15259280 2207136
00:54:33 59804 8192000000 10270509184 10723151872 1317595408 13.855 96 17 34 0 1338927680 17084064 4248208
00:56:42 24936 8192000000 10270006128 10708385792 1349332400 14.141 99 30 60 1 1364335152 8741584 6261168
00:58:10 103940 8192000000 10278855168 10719952896 1329233328 13.960 97 17 34 0 1347347360 14465344 3648688
01:00:58 37604 8192000000 10269180144 10717392896 1349504832 14.143 99 31 62 0 1367959680 11732592 6722256
01:02:41 34819 8192000000 10286261872 10725662720 1362234608 14.257 99 42 84 1 1386583280 14174144 10174528
01:04:20 33054 8192000000 10295019552 10733887488 1347273024 14.123 97 29 56 0 1362855488 9142048 6440416
01:05:58 96082 8192000000 10282278160 10733326336 1369053216 14.319 99 25 50 0 1385056864 10288032 5715616
01:08:39 33808 8192000000 10308669136 10755002368 1396353616 14.563 100 35 70 1 1414409168 10764544 7291008
01:10:14 73376 8192000000 10290617072 10749886464 1383929776 14.452 99 8 15 1 1395068144 9512064 1626304
01:12:46 55194 8192000000 10285437280 10738618368 1340613664 14.063 96 19 35 0 1354755168 9067936 5073568
01:14:41 31248 8192000000 10278281504 10721697792 1331433584 13.980 96 29 58 0 1345624096 7934704 6255808
01:16:23 48629 8192000000 10299675616 10757963776 1390493440 14.510 100 25 50 1 1413342272 16631056 6217776
01:18:22 78597 8192000000 10313504112 10775347200 1344218688 14.095 96 14 28 0 1370002912 22709200 3075024
01:20:44 61764 8192000000 10303530384 10755215360 1358991568 14.228 98 19 37 1 1372282336 9406160 3884608
01:22:46 54378 8192000000 10264485008 10713907200 1315882848 13.839 96 34 68 2 1338225744 14639424 7703472
01:24:51 89114 8192000000 10263626656 10701549568 1247813040 13.218 91 23 44 1 1261922624 8990432 5119152
01:27:21 118877 8192000000 10247096944 10701463552 1338564016 14.044 99 35 70 0 1362878192 15543536 8770640
01:30:22 104196 8192000000 10245112016 10704097280 1322722192 13.901 97 9 18 0 1340428928 15759760 1946976
01:33:07 41384 8192000000 10256750816 10713980928 1350882096 14.155 100 14 28 1 1366337792 12065984 3389712
01:34:49 65461 8192000000 10281844256 10733031424 1359615088 14.234 99 21 43 1 1374283392 9638496 5029808
01:36:56 59286 8192000000 10271683648 10720919552 1340117552 14.058 98 12 24 1 1352875760 10106448 2651760

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 03-12-2021 01:39:08 to 03-12-2021 01:39:23.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 102 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 03-12-2021 01:39:24 to 03-12-2021 01:39:26.

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          241516
MON$OLDEST_ACTIVE               242312
MON$OLDEST_SNAPSHOT             242312
MON$NEXT_TRANSACTION            242312
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                       2004544
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33540 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33540 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33540 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33540 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 ---
:741: 
:742: mscs-test-161.budzdorov.ru    Fri Dec  3 01:38:02 2021
:743:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:744: 
:745: 
:746: mscs-test-161.budzdorov.ru    Fri Dec  3 01:38:05 2021
:747:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:748: 
:749: 
:750: mscs-test-161.budzdorov.ru    Fri Dec  3 01:38:05 2021
:751:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:752: 
--- end of diff output ---

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

03.12.21 01:39:27. 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
2021-12-03 01:39:29 No severe PSQL-related problems occured
03.12.21 01:39:31. End of report.