Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 12.07.2022 16:39
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 15980712 kB
MemAvailable 31082712 kB
Buffers 0 kB
Cached 15695924 kB
SwapCached 2516 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-07-12 16:38:29
DTS_END                         2022-07-12 16:38:29.7810
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/53516
TRN_ID                          245915
ATT_ID                          723
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: 9677.65 23543 1161318
customer order: creation 183.58 346 22029
customer order: refuse 15.43 361 1852
order to supplier: creation 49.03 1243 5884
order to supplier: removal 3.65 101519 438
invoice (draft): creation 48.28 3008 5794
invoice (draft): removal 54.15 753 6498
invoice accept: apply 43.99 17575 5279
invoice accept: cancel 49.63 792 5955
customer reserve: creation 4021.68 176 482601
customer reserve: removal 4904.79 61 588575
realization accept: apply 89.48 166 10737
realization accept: cancel 75.56 109 9067
payment from customer: creation 64.19 92 7703
payment from customer: removal 6.46 65 775
payment to supplier: creation 51.88 106 6226
payment to supplier: removal 3.41 69 409
service: total inventory turnovers 5.06 1201 607
service: total monetary turnovers 6.48 40 777
service: aggregate perf. data 0.90 17655 108
service: refresh index statistics 0.03 325532 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 12-07-2022 16:39:27 to 12-07-2022 16:39:29.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3622
TEST_TIME 2 4905
TEST_TIME 3 4865
TEST_TIME 4 5072
TEST_TIME 5 5101
TEST_TIME 6 5223
TEST_TIME 7 5762
TEST_TIME 8 5575
TEST_TIME 9 6201
TEST_TIME 10 6481
TEST_TIME 11 7076
TEST_TIME 12 7739
TEST_TIME 13 7933
TEST_TIME 14 7820
TEST_TIME 15 7949
TEST_TIME 16 8288
TEST_TIME 17 8432
TEST_TIME 18 8373
TEST_TIME 19 8549
TEST_TIME 20 8906
TEST_TIME 21 9059
TEST_TIME 22 9328
TEST_TIME 23 9314
TEST_TIME 24 9377
TEST_TIME 25 9885
TEST_TIME 26 10227
TEST_TIME 27 10626
TEST_TIME 28 11823
TEST_TIME 29 11660
TEST_TIME 30 11522
TEST_TIME 31 11681
TEST_TIME 32 11452
TEST_TIME 33 11221
TEST_TIME 34 10996
TEST_TIME 35 11074
TEST_TIME 36 10998
TEST_TIME 37 10902
TEST_TIME 38 10695
TEST_TIME 39 10630
TEST_TIME 40 10566
TEST_TIME 41 10394
TEST_TIME 42 10387
TEST_TIME 43 10327
TEST_TIME 44 10348
TEST_TIME 45 10271
TEST_TIME 46 10391
TEST_TIME 47 10719
TEST_TIME 48 10558
TEST_TIME 49 10688
TEST_TIME 50 10712
TEST_TIME 51 10665
TEST_TIME 52 10630
TEST_TIME 53 10674
TEST_TIME 54 10686
TEST_TIME 55 10724
TEST_TIME 56 10663
TEST_TIME 57 10727
TEST_TIME 58 10654
TEST_TIME 59 10613
TEST_TIME 60 10671
TEST_TIME 61 10556
TEST_TIME 62 10488
TEST_TIME 63 10576
TEST_TIME 64 10503
TEST_TIME 65 10434
TEST_TIME 66 10428
TEST_TIME 67 10374
TEST_TIME 68 10315
TEST_TIME 69 10273
TEST_TIME 70 10210
TEST_TIME 71 10303
TEST_TIME 72 10247
TEST_TIME 73 10232
TEST_TIME 74 10231
TEST_TIME 75 10260
TEST_TIME 76 10240
TEST_TIME 77 10223
TEST_TIME 78 10212
TEST_TIME 79 10248
TEST_TIME 80 10311
TEST_TIME 81 10312
TEST_TIME 82 10248
TEST_TIME 83 10211
TEST_TIME 84 10144
TEST_TIME 85 10069
TEST_TIME 86 10093
TEST_TIME 87 10029
TEST_TIME 88 9997
TEST_TIME 89 9965
TEST_TIME 90 9903
TEST_TIME 91 9921
TEST_TIME 92 9886
TEST_TIME 93 9859
TEST_TIME 94 9891
TEST_TIME 95 9901
TEST_TIME 96 9971
TEST_TIME 97 9950
TEST_TIME 98 9929
TEST_TIME 99 9947
TEST_TIME 100 9918
TEST_TIME 101 9917
TEST_TIME 102 9961
TEST_TIME 103 9953
TEST_TIME 104 9987
TEST_TIME 105 10020
TEST_TIME 106 9965
TEST_TIME 107 9901
TEST_TIME 108 9872
TEST_TIME 109 9846
TEST_TIME 110 9803
TEST_TIME 111 9754
TEST_TIME 112 9716
TEST_TIME 113 9730
TEST_TIME 114 9735
TEST_TIME 115 9692
TEST_TIME 116 9684
TEST_TIME 117 9746
TEST_TIME 118 9746
TEST_TIME 119 9748
TEST_TIME 120 9683

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 12-07-2022 16:39:30 to 12-07-2022 16:39:33.

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 1609498 1609498 0 0.00 0 23023 6 0 0
doc_list_biud 1609498 1609498 0 0.00 0 54764 28 0 0
sp_add_invoice_to_stock 5447 5279 168 3.08 48 193719 17576 0 168
sp_cancel_adding_invoice 5955 5955 0 0.00 4 82134 792 0 0
sp_cancel_client_order 1852 1852 0 0.00 4 17707 362 0 0
sp_cancel_customer_reserve 588575 588575 0 0.00 0 54770 61 0 0
sp_cancel_pay_from_customer 775 775 0 0.00 2 4303 65 0 0
sp_cancel_pay_to_supplier 409 409 0 0.00 2 1961 70 0 0
sp_cancel_supplier_invoice 6505 6498 7 0.10 1 16001 753 0 7
sp_cancel_supplier_order 438 438 0 0.00 10 1229021 101520 0 0
sp_cancel_write_off 9067 9067 0 0.00 1 17417 109 0 0
sp_client_order 22029 22029 0 0.00 3 27570 347 0 0
sp_customer_reserve 482601 482601 0 0.00 0 66577 176 0 0
sp_fill_shopping_cart 33702 33702 0 0.00 1 18605 35 0 0
sp_fill_shopping_cart_clo_res 482586 482586 0 0.00 0 15873 13 0 0
sp_get_clo_for_invoice 5264 5264 0 0.00 0 22855 293 0 0
sp_kill_cost_storno 16206 16206 0 0.00 0 8237 51 0 0
sp_kill_qstorno_ret_qs2qd 1193153 1193153 0 0.00 0 54762 33 0 0
sp_kill_qty_storno 628386 628386 0 0.00 0 54763 71 0 0
sp_lock_selected_doc 22014 22014 0 0.00 0 8419 4 0 0
sp_make_cost_storno 29930 29930 0 0.00 0 23009 44 0 0
sp_multiply_rows_for_pdistr 29930 29930 0 0.00 0 5127 20 0 0
sp_multiply_rows_for_qdistr 22029 22029 0 0.00 0 27559 168 0 0
sp_pay_from_customer 7703 7703 0 0.00 4 17488 93 0 0
sp_pay_to_supplier 6226 6226 0 0.00 4 9617 107 0 0
sp_payment_common 13929 13929 0 0.00 1 17483 20 0 0
sp_qd_handle_on_cancel_clo 1852 1852 0 0.00 0 11815 330 0 0
sp_qd_handle_on_invoice_upd_sts 11219 11219 0 0.00 0 18523 320 0 0
sp_qd_handle_on_reserve_upd_sts 10737 10737 0 0.00 0 22020 72 0 0
sp_reserve_write_off 10737 10737 0 0.00 6 22031 167 0 0
sp_supplier_invoice 5794 5794 0 0.00 5 58765 3009 0 0
sp_supplier_order 5884 5884 0 0.00 5 45771 1244 0 0
srv_aggregate_perf_data 109 108 1 0.91 2551 163938 17655 0 1
srv_fill_mon_cache_memory 66 66 0 0.00 2068 139007 42456 0 0
srv_find_qd_qs_mism 1111389 1111389 0 0.00 0 15400 5 0 0
srv_make_invnt_saldo 607 607 0 0.00 39 87750 1201 0 0
srv_make_money_saldo 777 777 0 0.00 1 7011 41 0 0
srv_recalc_idx_stat 4 4 0 0.00 166934 583226 325533 0 0
t$perf-abend:sp_add_invoice_to_stock 168 168 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 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 5279 5279 0 0.00 0 13804 164 0 0
t$perf-norm:sp_cancel_adding_invoice 449 449 0 0.00 0 3829 126 0 0
t$perf-norm:sp_cancel_client_order 1852 1852 0 0.00 0 3065 13 0 0
t$perf-norm:sp_cancel_customer_reserve 690 690 0 0.00 0 14011 31 0 0
t$perf-norm:sp_cancel_pay_from_customer 775 775 0 0.00 0 819 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 409 409 0 0.00 0 1584 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 402 402 0 0.00 0 479 10 0 0
t$perf-norm:sp_cancel_supplier_order 438 438 0 0.00 0 26153 1578 0 0
t$perf-norm:sp_cancel_write_off 999 999 0 0.00 0 1516 6 0 0
t$perf-norm:sp_client_order 22029 22029 0 0.00 0 16902 19 0 0
t$perf-norm:sp_customer_reserve 1113 1113 0 0.00 0 5119 16 0 0
t$perf-norm:sp_pay_from_customer 7703 7703 0 0.00 0 6007 9 0 0
t$perf-norm:sp_pay_to_supplier 6226 6226 0 0.00 0 11078 10 0 0
t$perf-norm:sp_reserve_write_off 10737 10737 0 0.00 0 11853 13 0 0
t$perf-norm:sp_supplier_invoice 5794 5794 0 0.00 0 13860 20 0 0
t$perf-norm:sp_supplier_order 5884 5884 0 0.00 0 5270 19 0 0
t$perf-norm:srv_aggregate_perf_data 108 108 0 0.00 0 2360 27 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 1023 17 0 0
t$perf-norm:srv_make_invnt_saldo 607 607 0 0.00 0 1315 18 0 0
t$perf-norm:srv_make_money_saldo 777 777 0 0.00 0 9057 32 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 2 0 0
v_add_invoice_to_stock 10862 10526 336 3.09 0 9484 18 0 336
v_all_customers 39992 39992 0 0.00 0 58 1 0 0
v_all_suppliers 32440 32440 0 0.00 0 21 1 0 0
v_all_wares 44058 44058 0 0.00 0 35 1 0 0
v_cancel_adding_invoice 898 898 0 0.00 0 2584 15 0 0
v_cancel_client_order 3704 3704 0 0.00 0 4247 7 0 0
v_cancel_customer_prepayment 1550 1550 0 0.00 0 4234 7 0 0
v_cancel_customer_reserve 1380 1380 0 0.00 0 1843 7 0 0
v_cancel_payment_to_supplier 818 818 0 0.00 0 1953 6 0 0
v_cancel_supplier_invoice 818 804 14 1.71 0 7576 27 0 14
v_cancel_supplier_order 876 876 0 0.00 0 2667 7 0 0
v_cancel_write_off 1998 1998 0 0.00 0 4974 16 0 0
v_max_id_clo_ord 5884 5884 0 0.00 0 2054 2 0 0
v_max_id_clo_res 1113 1113 0 0.00 0 2363 11 0 0
v_max_id_ord_sup 5789 5789 0 0.00 0 7190 9 0 0
v_max_non_paid_invoice 1679 1679 0 0.00 0 592 2 0 0
v_max_non_paid_realizn 4220 4220 0 0.00 0 4476 4 0 0
v_min_id_clo_ord 5884 5884 0 0.00 0 1887 5 0 0
v_min_id_clo_res 1113 1113 0 0.00 0 1453 5 0 0
v_min_id_ord_sup 5789 5789 0 0.00 0 5776 7 0 0
v_min_non_paid_invoice 6226 6226 0 0.00 0 4572 11 0 0
v_min_non_paid_realizn 7703 7703 0 0.00 0 7788 14 0 0
v_our_firm 11032 11032 0 0.00 0 21 1 0 0
v_reserve_write_off 21474 21474 0 0.00 0 4242 6 0 0
x_lock_depdocs_on_canc_invoice 12051 12051 0 0.00 0 21811 116 0 0
x_lock_depdocs_on_canc_sup_ord 438 438 0 0.00 0 7189 365 0 0
x_make_qstorno_1000_1200 5884 5884 0 0.00 2 45753 1168 0 0
x_make_qstorno_1200_2000 5794 5794 0 0.00 2 58753 2884 0 0
x_make_qstorno_2100_3300 482601 482601 0 0.00 0 66577 160 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 12-07-2022 16:39:34 to 12-07-2022 16:39:38.

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:32 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:09:33 14620 8192000000 9896792448 10206367744 979180720 10.676 100 87 175 0 1008078864 5751008 23147136
14:10:52 17081 8192000000 9944415712 10265714688 1028809168 11.157 100 85 170 0 1058061968 7530784 21722016
14:12:18 17919 8192000000 9984655200 10312671232 1059498368 11.452 100 88 176 0 1090929440 9232448 22198624
14:13:58 7795 8192000000 10020455520 10372481024 1115991984 11.989 100 66 132 0 1141212192 9438512 15781696
14:15:09 6006 8192000000 10085005056 10443894784 1172868032 12.524 100 76 153 0 1201425584 11763424 16794128
14:16:18 3192 8192000000 10123542176 10490060800 1212959040 12.897 100 80 158 0 1243900448 13325808 17615600
14:17:23 2193 8192000000 10162396656 10534010880 1244188320 13.185 100 93 184 0 1278362544 13836928 20337296
14:18:28 1528 8192000000 10189449616 10566025216 1274031808 13.458 100 87 174 0 1307518976 14540928 18946240
14:19:31 983 8192000000 10209139536 10592215040 1294937232 13.649 100 96 193 1 1329152208 12265952 21949024
14:20:33 14095 8192000000 10207323504 10607190016 1301143584 13.706 100 41 78 1 1316399328 5495456 9760288
14:21:49 5036 8192000000 10193483920 10610577408 1312325312 13.807 100 28 56 0 1326233728 7654112 6254304
14:22:55 10709 8192000000 10200687632 10623135744 1322022720 13.895 100 17 34 0 1331833440 5914400 3896320
14:24:11 40777 8192000000 10214271584 10637393920 1324723792 13.919 100 30 59 0 1337331712 5288416 7319504
14:25:52 7188 8192000000 10223938288 10645721088 1333237504 13.996 100 20 40 0 1343980400 6233744 4509152
14:27:00 7285 8192000000 10231671920 10654056448 1334836928 14.011 100 19 39 1 1349106576 9797424 4472224
14:28:08 54947 8192000000 10206440544 10622492672 1263298064 13.360 95 32 64 0 1280722480 9868832 7555584
14:30:07 5616 8192000000 10177692496 10583621632 1223792608 12.997 94 31 62 0 1237516864 6503904 7220352
14:31:13 5523 8192000000 10144653424 10549137408 1244599008 13.189 99 17 34 0 1257858592 9441168 3818416
14:32:19 48027 8192000000 10147698768 10548002816 1202923984 12.803 95 32 64 0 1216408432 5031760 8452688
14:34:14 3761 8192000000 10164912768 10564108288 1262849856 13.356 100 38 75 0 1278843696 7506576 8487264
14:35:23 10638 8192000000 10179524144 10597875712 1293396096 13.635 100 19 37 0 1303386656 5110272 4880288
14:36:37 6990 8192000000 10198043536 10615111680 1305630592 13.746 100 23 45 0 1316372864 5166464 5575808
14:37:44 33963 8192000000 10198094432 10621849600 1316616736 13.846 100 27 50 0 1327616928 5033088 5967104
14:39:22 20531 8192000000 10246177184 10661343232 1338401664 14.043 100 38 77 0 1354619216 7427824 8789728
14:40:47 12521 8192000000 10270550848 10687234048 1360703968 14.244 100 52 104 0 1388771552 16017120 12050464
14:42:05 19100 8192000000 10281620160 10703589376 1371058288 14.337 100 42 83 0 1393729472 12578896 10092288
14:43:40 15781 8192000000 10306170336 10725281792 1389354592 14.500 100 46 92 1 1414019840 13614288 11050960
14:45:02 8330 8192000000 10309467056 10738089984 1395622688 14.556 100 38 74 0 1422418576 17630320 9165568
14:46:15 4500 8192000000 10335828656 10759946240 1410698656 14.690 100 62 124 1 1446797920 21360736 14738528
14:47:31 4837 8192000000 10348217536 10772193280 1414983200 14.728 100 66 127 2 1454145632 24614784 14547648
14:48:45 18448 8192000000 10342533568 10769076224 1404466704 14.635 100 62 123 1 1448232032 28977232 14788096
14:50:12 4769 8192000000 10338653424 10765455360 1393117568 14.534 99 63 126 2 1438721152 30684000 14919584
14:51:24 4095 8192000000 10335559312 10753921024 1384207920 14.454 99 65 129 0 1430772528 30694848 15869760
14:52:33 2811 8192000000 10344289280 10752581632 1388435568 14.492 100 85 169 2 1444684816 36025152 20224096
14:53:42 2068 8192000000 10347122624 10755993600 1390196688 14.508 99 73 138 2 1447178912 40126528 16855696
14:54:51 2302 8192000000 10331759648 10740617216 1359606064 14.234 98 79 159 1 1419635104 40441376 19587664
14:55:56 7966 8192000000 10345792816 10772303872 1396188336 14.561 100 46 88 2 1450977840 43409472 11380032
14:57:13 11100 8192000000 10353352976 10781396992 1396496560 14.564 100 51 100 0 1461075728 51082784 13496384
14:58:28 3130 8192000000 10355269568 10768011264 1387203904 14.481 100 75 148 0 1456618512 50741072 18673536
14:59:35 6106 8192000000 10353928288 10781560832 1386962480 14.479 99 39 78 0 1450507824 52658848 10886496
15:00:45 7967 8192000000 10365964320 10795618304 1408240976 14.668 100 35 69 0 1468353952 50576048 9536928
15:02:23 5966 8192000000 10379373136 10807930880 1398967776 14.586 99 44 88 1 1472214720 60844704 12402240
15:03:37 22339 8192000000 10377295984 10820190208 1419571776 14.769 100 25 50 0 1483375440 56777328 7026336
15:05:01 23767 8192000000 10345794848 10794729472 1382346496 14.438 100 24 48 1 1452445296 62817568 7281232
15:06:27 60262 8192000000 10293373008 10738286592 1357987440 14.219 98 13 25 0 1370706208 10132304 2586464
15:08:28 54801 8192000000 10286274592 10743771136 1370546880 14.332 100 17 35 0 1387831392 14082176 3202336
15:10:34 96348 8192000000 10305950784 10754535424 1386176528 14.472 100 37 73 2 1405710880 12136992 7397360
15:13:19 55191 8192000000 10297877024 10747678720 1352153024 14.167 97 24 45 0 1371552144 15116144 4282976
15:15:23 21380 8192000000 10288281728 10731687936 1362836960 14.263 99 34 68 0 1380402112 8872896 8692256
15:16:51 5976 8192000000 10290356896 10730143744 1374632400 14.369 99 42 81 1 1394378816 10259664 9486752
15:17:58 30507 8192000000 10302790832 10753646592 1371621856 14.342 98 22 44 0 1394183136 16961872 5599408
15:19:33 12478 8192000000 10336165440 10773536768 1393338512 14.536 99 60 117 2 1427710304 20847632 13524160
15:20:48 6547 8192000000 10329247552 10776502272 1398835136 14.585 100 62 124 0 1442366848 27457440 16074272
15:22:05 60193 8192000000 10309094336 10767708160 1366010384 14.291 98 21 41 0 1401340304 30196256 5133664
15:24:37 46874 8192000000 10312518880 10787258368 1390239648 14.508 100 14 28 0 1426214944 31628000 4347296
15:26:31 55993 8192000000 10290705344 10767478784 1373222528 14.356 99 6 12 0 1391061920 16432944 1406448
15:28:27 60507 8192000000 10288413216 10741096448 1347102400 14.121 97 29 59 0 1361089328 7337744 6649184
15:30:33 76819 8192000000 10294999760 10753855488 1348055648 14.130 97 7 14 0 1360393888 10745504 1592736
15:32:53 69468 8192000000 10284486544 10743820288 1348549328 14.134 97 11 22 0 1361583936 10500016 2534592
15:35:08 121143 8192000000 10271014848 10715766784 1294442128 13.645 94 17 34 1 1314000336 15826976 3731232
15:38:09 95244 8192000000 10262295008 10721423360 1339041328 14.049 98 17 33 0 1355786880 13071248 3674304
15:41:21 95816 8192000000 10260638176 10708799488 1309283968 13.780 95 34 64 1 1327401856 10063520 8054368
15:43:58 67973 8192000000 10273254096 10732519424 1358679280 14.225 99 21 42 1 1370990784 7690480 4621024
15:46:06 38555 8192000000 10272748320 10728554496 1361146848 14.248 99 11 18 0 1371139040 7634384 2357808
15:47:45 52593 8192000000 10283249200 10730213376 1362420688 14.259 99 18 36 0 1376237248 9114832 4701728
15:49:52 26416 8192000000 10271973120 10718740480 1333974736 14.003 97 23 46 1 1345856032 6134512 5746784
15:51:26 21165 8192000000 10294297920 10733527040 1348664704 14.135 98 47 92 1 1372049552 13149840 10235008
15:52:49 32333 8192000000 10286899328 10735931392 1364288944 14.276 99 25 50 0 1382328896 12543296 5496656
15:54:35 47777 8192000000 10283571904 10727215104 1317932928 13.858 95 20 40 0 1331984176 9276160 4775088
15:56:27 32156 8192000000 10293494144 10738802688 1355931088 14.201 98 24 47 0 1369844080 8692448 5220544
15:58:04 102937 8192000000 10296656208 10751406080 1342420832 14.079 97 9 18 0 1365073728 19923840 2729056
16:00:48 95584 8192000000 10290373168 10755629056 1390704368 14.512 100 14 28 0 1409015072 14847776 3462928
16:03:27 3605 8192000000 10300892992 10749259776 1388975680 14.497 100 60 122 1 1411544240 8773680 13794880
16:04:33 53911 8192000000 10283033280 10750189568 1363348768 14.267 98 14 26 2 1374590032 7933040 3308224
16:06:30 82223 8192000000 10281406448 10737848320 1379878464 14.415 99 14 29 0 1392755360 9965728 2911168
16:09:12 26631 8192000000 10278392784 10743214080 1360985200 14.246 98 7 14 0 1373485088 11188720 1311168
16:10:39 56012 8192000000 10287718592 10744872960 1363012496 14.264 98 21 42 1 1375372496 7518496 4841504
16:12:59 91549 8192000000 10272298896 10733084672 1297624656 13.674 93 12 24 1 1313886624 13517216 2744752
16:16:02 61947 8192000000 10283948640 10730123264 1344310768 14.096 97 26 52 0 1358466576 8300432 5855376
16:18:07 25156 8192000000 10293685120 10735472640 1366019440 14.291 99 34 69 0 1383342976 10109200 7214336
16:19:47 53993 8192000000 10291753328 10750599168 1377291792 14.392 100 35 70 0 1400785840 16013616 7480432
16:21:41 138995 8192000000 10289950480 10762018816 1322312080 13.898 96 20 40 0 1343386448 16669568 4404800
16:25:00 109256 8192000000 10265987808 10719834112 1327910944 13.948 97 28 56 0 1348592656 14624992 6056720
16:27:51 60571 8192000000 10235927920 10699055104 1322068400 13.895 98 4 8 0 1333037568 10209584 759584
16:29:52 117830 8192000000 10254348544 10722799616 1322349328 13.898 98 13 26 0 1336955232 12203248 2402656
16:32:50 63601 8192000000 10273382848 10727223296 1352169712 14.167 99 31 62 0 1371581312 12371712 7039888
16:34:54 46282 8192000000 10276819200 10730782720 1343680048 14.091 98 12 24 0 1362098160 15825872 2592240
16:36:41 39737 8192000000 10289760752 10741440512 1355150896 14.194 99 17 34 0 1376339264 17547360 3641008
16:38:21 19277 8192000000 9036719840 9246081024 199057024 2.372 15 1 2 0 203667952 4568576 42352

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 12-07-2022 16:39:40 to 12-07-2022 16:39:56.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 168 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 12-07-2022 16:39:56 to 12-07-2022 16:39:58.

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          247874
MON$OLDEST_ACTIVE               247875
MON$OLDEST_SNAPSHOT             247875
MON$NEXT_TRANSACTION            247875
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                       2044000
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.33608 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33608 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33608 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33608 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 ---
:946: 
:947: mscs-test-161.budzdorov.ru    Tue Jul 12 16:38:48 2022
:948:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:949: 
--- end of diff output ---

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

12.07.22 16:39:59. 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-07-12 16:40:02 No severe PSQL-related problems occured
12.07.22 16:40:03. End of report.