Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 27.02.2023 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 15294068 kB
MemAvailable 30477724 kB
Buffers 0 kB
Cached 16438808 kB
SwapCached 3160 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 2023-02-27 16:38:38
DTS_END                         2023-02-27 16:38:38.3960
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_AGGREGATE_PERF_DATA(83:5) ==> TMP_AGGREGATE_PERF_DATA_AUTOGEN(76:13) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/42062
TRN_ID                          244866
ATT_ID                          739
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: 9479.44 16988 1137533
customer order: creation 179.39 392 21527
customer order: refuse 15.43 365 1851
order to supplier: creation 48.12 1248 5774
order to supplier: removal 3.62 89869 434
invoice (draft): creation 47.98 3298 5758
invoice (draft): removal 53.13 753 6375
invoice accept: apply 44.28 16743 5314
invoice accept: cancel 47.65 758 5718
customer reserve: creation 4089.41 165 490729
customer reserve: removal 4641.09 54 556931
realization accept: apply 87.21 192 10465
realization accept: cancel 88.83 115 10659
payment from customer: creation 61.46 148 7375
payment from customer: removal 5.99 93 719
payment to supplier: creation 50.49 152 6059
payment to supplier: removal 3.23 77 387
service: total inventory turnovers 4.68 1208 562
service: total monetary turnovers 6.56 26 787
service: aggregate perf. data 0.88 19498 105
service: refresh index statistics 0.03 204608 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 27-02-2023 16:40:51 to 27-02-2023 16:40:53.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3254
TEST_TIME 2 3603
TEST_TIME 3 2703
TEST_TIME 4 2873
TEST_TIME 5 3035
TEST_TIME 6 3963
TEST_TIME 7 4733
TEST_TIME 8 5170
TEST_TIME 9 5482
TEST_TIME 10 5510
TEST_TIME 11 6016
TEST_TIME 12 6340
TEST_TIME 13 6447
TEST_TIME 14 6754
TEST_TIME 15 6869
TEST_TIME 16 7134
TEST_TIME 17 7401
TEST_TIME 18 7510
TEST_TIME 19 8119
TEST_TIME 20 8117
TEST_TIME 21 8806
TEST_TIME 22 9134
TEST_TIME 23 9248
TEST_TIME 24 9396
TEST_TIME 25 9390
TEST_TIME 26 9623
TEST_TIME 27 10413
TEST_TIME 28 10745
TEST_TIME 29 10945
TEST_TIME 30 10857
TEST_TIME 31 10655
TEST_TIME 32 10699
TEST_TIME 33 10555
TEST_TIME 34 10493
TEST_TIME 35 10768
TEST_TIME 36 10695
TEST_TIME 37 10769
TEST_TIME 38 10617
TEST_TIME 39 10504
TEST_TIME 40 10309
TEST_TIME 41 10208
TEST_TIME 42 10333
TEST_TIME 43 10124
TEST_TIME 44 10163
TEST_TIME 45 10118
TEST_TIME 46 10090
TEST_TIME 47 10136
TEST_TIME 48 10085
TEST_TIME 49 10013
TEST_TIME 50 9983
TEST_TIME 51 9913
TEST_TIME 52 9878
TEST_TIME 53 9830
TEST_TIME 54 9918
TEST_TIME 55 9981
TEST_TIME 56 9949
TEST_TIME 57 9923
TEST_TIME 58 10030
TEST_TIME 59 10033
TEST_TIME 60 10025
TEST_TIME 61 10121
TEST_TIME 62 10056
TEST_TIME 63 10157
TEST_TIME 64 10175
TEST_TIME 65 10218
TEST_TIME 66 10215
TEST_TIME 67 10197
TEST_TIME 68 10076
TEST_TIME 69 10002
TEST_TIME 70 10027
TEST_TIME 71 9960
TEST_TIME 72 10008
TEST_TIME 73 9942
TEST_TIME 74 9869
TEST_TIME 75 9842
TEST_TIME 76 9812
TEST_TIME 77 9786
TEST_TIME 78 9711
TEST_TIME 79 9668
TEST_TIME 80 9605
TEST_TIME 81 9628
TEST_TIME 82 9623
TEST_TIME 83 9575
TEST_TIME 84 9611
TEST_TIME 85 9632
TEST_TIME 86 9674
TEST_TIME 87 9692
TEST_TIME 88 9794
TEST_TIME 89 9788
TEST_TIME 90 9755
TEST_TIME 91 9825
TEST_TIME 92 9805
TEST_TIME 93 9824
TEST_TIME 94 9836
TEST_TIME 95 9830
TEST_TIME 96 9779
TEST_TIME 97 9713
TEST_TIME 98 9655
TEST_TIME 99 9661
TEST_TIME 100 9629
TEST_TIME 101 9562
TEST_TIME 102 9582
TEST_TIME 103 9604
TEST_TIME 104 9595
TEST_TIME 105 9590
TEST_TIME 106 9649
TEST_TIME 107 9622
TEST_TIME 108 9605
TEST_TIME 109 9664
TEST_TIME 110 9659
TEST_TIME 111 9671
TEST_TIME 112 9693
TEST_TIME 113 9681
TEST_TIME 114 9721
TEST_TIME 115 9672
TEST_TIME 116 9623
TEST_TIME 117 9603
TEST_TIME 118 9589
TEST_TIME 119 9541
TEST_TIME 120 9485

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 27-02-2023 16:40:54 to 27-02-2023 16:40:58.

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 1591186 1591186 0 0.00 0 41092 6 0 0
doc_list_biud 1591185 1591185 0 0.00 0 55860 25 0 0
sp_add_invoice_to_stock 5403 5314 89 1.64 42 201712 16743 0 89
sp_cancel_adding_invoice 5718 5718 0 0.00 4 83805 758 0 0
sp_cancel_client_order 1851 1851 0 0.00 4 12940 365 0 0
sp_cancel_customer_reserve 556931 556931 0 0.00 0 25477 55 0 0
sp_cancel_pay_from_customer 719 719 0 0.00 3 6003 94 0 0
sp_cancel_pay_to_supplier 387 387 0 0.00 3 6680 77 0 0
sp_cancel_supplier_invoice 6380 6375 5 0.07 2 15236 754 0 5
sp_cancel_supplier_order 434 434 0 0.00 5 961135 89869 0 0
sp_cancel_write_off 10659 10659 0 0.00 1 20102 116 0 0
sp_client_order 21527 21527 0 0.00 3 43366 392 0 0
sp_customer_reserve 490729 490729 0 0.00 0 97582 165 0 0
sp_fill_shopping_cart 33050 33050 0 0.00 1 10807 38 0 0
sp_fill_shopping_cart_clo_res 490722 490722 0 0.00 0 23989 13 0 0
sp_get_clo_for_invoice 5307 5307 0 0.00 0 25588 270 0 0
sp_kill_cost_storno 17483 17483 0 0.00 0 6247 53 0 0
sp_kill_qstorno_ret_qs2qd 1131330 1131330 0 0.00 0 25454 30 0 0
sp_kill_qty_storno 597739 597739 0 0.00 0 55858 65 0 0
sp_lock_selected_doc 21855 21855 0 0.00 0 11429 7 0 0
sp_make_cost_storno 29205 29205 0 0.00 0 15965 60 0 0
sp_multiply_rows_for_pdistr 29205 29205 0 0.00 0 13108 27 0 0
sp_multiply_rows_for_qdistr 21527 21527 0 0.00 0 19657 150 0 0
sp_pay_from_customer 7375 7375 0 0.00 4 22470 149 0 0
sp_pay_to_supplier 6059 6059 0 0.00 4 27299 152 0 0
sp_payment_common 13434 13434 0 0.00 1 13100 42 0 0
sp_qd_handle_on_cancel_clo 1851 1851 0 0.00 0 11218 330 0 0
sp_qd_handle_on_invoice_upd_sts 11024 11024 0 0.00 0 55857 341 0 0
sp_qd_handle_on_reserve_upd_sts 10465 10465 0 0.00 0 17633 60 0 0
sp_reserve_write_off 10465 10465 0 0.00 6 17684 193 0 0
sp_supplier_invoice 5758 5758 0 0.00 6 94523 3299 0 0
sp_supplier_order 5774 5774 0 0.00 5 60172 1249 0 0
srv_aggregate_perf_data 106 105 1 0.94 3043 234599 19498 0 1
srv_fill_mon_cache_memory 64 64 0 0.00 1191 114534 43982 0 0
srv_find_qd_qs_mism 1087198 1087198 0 0.00 0 41090 5 0 0
srv_make_invnt_saldo 562 562 0 0.00 24 101088 1208 0 0
srv_make_money_saldo 787 787 0 0.00 1 715 26 0 0
srv_recalc_idx_stat 4 4 0 0.00 123208 286054 204609 0 0
t$perf-abend:sp_add_invoice_to_stock 89 89 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 5314 5314 0 0.00 0 13490 186 0 0
t$perf-norm:sp_cancel_adding_invoice 429 429 0 0.00 0 2626 85 0 0
t$perf-norm:sp_cancel_client_order 1851 1851 0 0.00 0 2309 12 0 0
t$perf-norm:sp_cancel_customer_reserve 769 769 0 0.00 0 6261 23 0 0
t$perf-norm:sp_cancel_pay_from_customer 719 719 0 0.00 0 8197 22 0 0
t$perf-norm:sp_cancel_pay_to_supplier 387 387 0 0.00 0 7748 33 0 0
t$perf-norm:sp_cancel_supplier_invoice 420 420 0 0.00 0 498 11 0 0
t$perf-norm:sp_cancel_supplier_order 434 434 0 0.00 0 20583 1474 0 0
t$perf-norm:sp_cancel_write_off 1075 1075 0 0.00 0 2349 8 0 0
t$perf-norm:sp_client_order 21527 21527 0 0.00 0 13065 17 0 0
t$perf-norm:sp_customer_reserve 1021 1021 0 0.00 0 4625 13 0 0
t$perf-norm:sp_pay_from_customer 7375 7375 0 0.00 0 15836 15 0 0
t$perf-norm:sp_pay_to_supplier 6059 6059 0 0.00 0 13433 13 0 0
t$perf-norm:sp_reserve_write_off 10465 10465 0 0.00 0 7109 12 0 0
t$perf-norm:sp_supplier_invoice 5758 5758 0 0.00 0 5079 17 0 0
t$perf-norm:sp_supplier_order 5774 5774 0 0.00 0 9927 16 0 0
t$perf-norm:srv_aggregate_perf_data 105 105 0 0.00 0 160 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 190 6 0 0
t$perf-norm:srv_make_invnt_saldo 562 562 0 0.00 0 627 20 0 0
t$perf-norm:srv_make_money_saldo 787 787 0 0.00 0 1653 20 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 4 3 0 0
v_add_invoice_to_stock 10790 10612 178 1.64 0 13929 23 0 178
v_all_customers 39174 39174 0 0.00 0 30 1 0 0
v_all_suppliers 31858 31858 0 0.00 0 21 1 0 0
v_all_wares 43054 43054 0 0.00 0 25 1 0 0
v_cancel_adding_invoice 858 858 0 0.00 0 4975 17 0 0
v_cancel_client_order 3702 3702 0 0.00 0 12600 9 0 0
v_cancel_customer_prepayment 1438 1438 0 0.00 0 5980 16 0 0
v_cancel_customer_reserve 1538 1538 0 0.00 0 5253 13 0 0
v_cancel_payment_to_supplier 774 774 0 0.00 0 706 4 0 0
v_cancel_supplier_invoice 850 840 10 1.17 0 4660 29 0 10
v_cancel_supplier_order 868 868 0 0.00 0 3301 7 0 0
v_cancel_write_off 2150 2150 0 0.00 0 5218 20 0 0
v_max_id_clo_ord 5774 5774 0 0.00 0 780 1 0 0
v_max_id_clo_res 1021 1021 0 0.00 0 2418 15 0 0
v_max_id_ord_sup 5749 5749 0 0.00 0 5198 5 0 0
v_max_non_paid_invoice 1653 1653 0 0.00 0 595 3 0 0
v_max_non_paid_realizn 4004 4004 0 0.00 0 6985 8 0 0
v_min_id_clo_ord 5774 5774 0 0.00 0 10081 11 0 0
v_min_id_clo_res 1021 1021 0 0.00 0 1759 6 0 0
v_min_id_ord_sup 5749 5749 0 0.00 0 5738 14 0 0
v_min_non_paid_invoice 6059 6059 0 0.00 0 13090 33 0 0
v_min_non_paid_realizn 7375 7375 0 0.00 0 12684 33 0 0
v_our_firm 10622 10622 0 0.00 0 13 1 0 0
v_reserve_write_off 20930 20930 0 0.00 0 16830 10 0 0
x_lock_depdocs_on_canc_invoice 11673 11673 0 0.00 0 24059 107 0 0
x_lock_depdocs_on_canc_sup_ord 434 434 0 0.00 0 7286 332 0 0
x_make_qstorno_1000_1200 5774 5774 0 0.00 1 58288 1170 0 0
x_make_qstorno_1200_2000 5758 5758 0 0.00 2 94511 3161 0 0
x_make_qstorno_2100_3300 490729 490729 0 0.00 0 97579 149 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 27-02-2023 16:40:59 to 27-02-2023 16:41:03.

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:42 3 8192000000 8800003056 8811675648 6400944 0.078 2 1 2 0 6499216 55488 42784
14:09:43 12901 8192000000 9879118608 10184421376 971183456 10.598 100 90 181 0 1000058784 5431472 23443856
14:11:07 27558 8192000000 9949706224 10269491200 1025258064 11.123 100 92 184 0 1057167584 7914000 23995520
14:12:43 14487 8192000000 9978693568 10309230592 1061225168 11.468 100 89 178 0 1093662656 9710128 22727360
14:14:09 8501 8192000000 10040115392 10389839872 1128672080 12.109 100 86 172 0 1160107600 10857184 20578336
14:15:20 6094 8192000000 10095442560 10459529216 1190690112 12.690 100 74 148 0 1218052832 10443344 16919376
14:16:29 3237 8192000000 10140328704 10506444800 1222950512 12.989 100 88 176 0 1256503792 14334784 19218496
14:17:34 1548 8192000000 10163562512 10535931904 1248209904 13.222 100 85 171 0 1280472608 13116976 19145728
14:18:37 1964 8192000000 10181004832 10559156224 1274695888 13.465 100 91 177 1 1308961984 15056832 19209264
14:19:40 914 8192000000 10214348480 10597949440 1298305840 13.680 100 95 189 1 1332798208 13508480 20983888
14:20:43 1928 8192000000 10214223792 10607996928 1309582896 13.782 100 73 144 0 1336570256 10667424 16319936
14:21:46 3753 8192000000 10212187584 10619273216 1316393184 13.844 100 37 71 0 1332815216 8254544 8167488
14:22:51 10377 8192000000 10213242400 10627117056 1329157136 13.960 100 22 43 1 1339096480 5410224 4529120
14:24:01 7949 8192000000 10215957632 10635608064 1333780528 14.001 100 20 42 0 1344203184 5661360 4761296
14:25:10 50479 8192000000 10230020160 10642776064 1337360272 14.034 100 22 42 0 1347442256 4210848 5871136
14:27:02 8732 8192000000 10231484624 10656886784 1347077968 14.121 100 25 48 0 1358740112 6307072 5355072
14:28:11 5407 8192000000 10232193328 10654380032 1343000720 14.084 100 25 50 0 1354554944 6213840 5340384
14:29:18 9273 8192000000 10199702080 10611011584 1300092176 13.696 98 15 30 0 1308641424 5016528 3532720
14:30:27 32673 8192000000 10162529056 10579836928 1211796448 12.886 93 28 56 0 1223308960 5150880 6361632
14:32:08 4356 8192000000 10137786160 10534219776 1236315152 13.112 99 33 66 0 1249740960 5215552 8210256
14:33:13 8478 8192000000 10133271920 10533965824 1217637616 12.940 97 19 38 0 1227619872 5202512 4779744
14:34:22 10512 8192000000 10164561280 10565111808 1259103616 13.322 99 45 87 2 1276118800 5409984 11605200
14:35:34 4275 8192000000 10187268928 10580086784 1280323264 13.516 100 67 136 0 1302584976 5924480 16337232
14:36:38 7508 8192000000 10174692032 10592210944 1289546416 13.600 100 32 65 0 1303820128 6945248 7328464
14:37:46 9653 8192000000 10186137776 10604699648 1300899520 13.703 100 15 30 0 1309791936 5588432 3303984
14:39:05 16394 8192000000 10231402928 10641477632 1332349808 13.988 100 26 53 1 1343230224 4585504 6294912
14:40:28 15383 8192000000 10260966416 10672975872 1357744960 14.217 100 39 80 0 1374004864 7048768 9211136
14:41:56 89423 8192000000 10271760768 10699030528 1365018784 14.282 100 28 57 1 1386049248 14452448 6578016
14:44:36 12213 8192000000 10295381136 10720276480 1381462928 14.430 100 40 80 0 1411195616 19704480 10028208
14:45:51 2815 8192000000 10319850704 10734112768 1385809168 14.468 100 70 136 1 1425134704 22958432 16367104
14:46:57 8236 8192000000 10335057936 10755489792 1406272736 14.651 100 62 119 1 1446739296 26696944 13769616
14:48:12 23377 8192000000 10341774320 10768846848 1405672000 14.645 100 52 101 1 1450024160 31747712 12604448
14:49:48 2697 8192000000 10343374080 10758258688 1400331904 14.598 100 77 147 1 1448356592 28892480 19132208
14:50:55 1190 8192000000 10372527856 10787512320 1420607904 14.778 100 83 164 1 1474946528 34643728 19694896
14:52:03 1624 8192000000 10363021552 10788786176 1419300192 14.766 100 55 109 0 1468688160 36040496 13347472
14:53:10 1282 8192000000 10377647280 10794426368 1425172080 14.819 100 79 155 1 1482936720 38426768 19337872
14:54:23 3579 8192000000 10366239232 10778501120 1404604368 14.636 99 77 154 1 1470142528 46123296 19414864
14:55:33 5544 8192000000 10349259024 10767032320 1382263760 14.437 99 69 134 0 1448543520 49435072 16844688
14:56:45 1609 8192000000 10326561088 10733879296 1354928784 14.192 99 80 158 2 1431778896 54844880 22005232
14:57:51 14483 8192000000 10318384944 10742304768 1355914720 14.201 99 35 70 0 1418576080 53123584 9537776
14:59:16 8701 8192000000 10315249536 10749972480 1372481696 14.349 100 44 88 1 1427160720 43267216 11411808
15:00:32 9741 8192000000 10309986960 10735546368 1357517408 14.215 99 33 64 0 1405829104 39800848 8510848
15:01:45 13128 8192000000 10321843264 10748133376 1372917232 14.353 100 33 65 0 1424728672 42946448 8864992
15:03:06 11463 8192000000 10316890304 10745151488 1369066112 14.319 100 32 64 0 1421923840 44365840 8491888
15:04:22 21726 8192000000 10307280880 10750476288 1354815168 14.191 100 30 61 0 1411128256 47655968 8657120
15:05:52 28527 8192000000 10275721440 10736345088 1356850288 14.209 100 13 26 1 1396050768 35795120 3405360
15:07:36 61269 8192000000 10283250736 10726854656 1354035680 14.184 99 11 22 0 1372141360 15436032 2669648
15:10:08 47791 8192000000 10283071008 10710949888 1352341520 14.169 98 37 63 0 1367473792 8430432 6701840
15:11:56 66797 8192000000 10310799120 10755211264 1378304288 14.401 99 24 48 1 1400026640 16647200 5075152
15:14:08 61105 8192000000 10303175264 10764005376 1347540784 14.125 97 10 20 0 1373919824 24353264 2025776
15:16:12 29914 8192000000 10308525728 10750951424 1394976816 14.550 100 29 54 2 1410564672 8377840 7210016
15:17:48 95140 8192000000 10335663440 10778877952 1404542672 14.635 100 33 66 0 1420226656 8313216 7370768
15:20:26 114532 8192000000 10322857424 10768637952 1395678496 14.557 99 33 66 2 1419151712 15334064 8139152
15:23:47 21936 8192000000 10319812016 10766323712 1399582336 14.591 100 49 98 0 1422188864 11115600 11490928
15:25:13 56695 8192000000 10307178848 10775097344 1389899376 14.505 99 16 32 1 1411172336 17281232 3991728
15:27:10 51827 8192000000 10319414640 10786246656 1408374528 14.669 100 10 20 0 1421904944 11751616 1778800
15:29:05 35944 8192000000 10300161936 10762555392 1392406192 14.527 100 11 22 0 1406991888 11856784 2728912
15:30:48 47678 8192000000 10289553312 10749583360 1378328432 14.402 99 19 38 1 1395222432 12761936 4132064
15:32:45 46850 8192000000 10292679264 10753445888 1374858768 14.371 99 19 38 1 1390701168 11386640 4455760
15:34:37 52724 8192000000 10305212416 10759225344 1388269440 14.490 100 31 62 0 1410171680 14499200 7403040
15:36:39 37304 8192000000 10293291904 10755465216 1349796384 14.146 97 21 42 0 1369663824 15132256 4735184
15:38:20 72106 8192000000 10274446480 10724155392 1335249792 14.015 97 17 34 0 1350673488 11908096 3515600
15:40:32 52860 8192000000 10266386208 10723028992 1315274576 13.834 96 20 40 0 1331995584 12297536 4423472
15:42:26 56469 8192000000 10275402032 10711674880 1310542000 13.791 96 19 38 1 1326679392 12207472 3929920
15:44:25 17056 8192000000 10266950304 10700853248 1329666240 13.964 98 31 63 0 1343863488 6953152 7244096
15:45:50 91859 8192000000 10267351104 10711740416 1340016784 14.058 99 41 81 0 1359406080 9709328 9679968
15:49:09 35234 8192000000 10263604400 10713333760 1358148688 14.221 100 20 40 0 1372698688 10053040 4496960
15:50:47 37454 8192000000 10248302464 10703392768 1336806128 14.029 99 6 12 2 1344614464 6659760 1148576
15:52:25 71771 8192000000 10258147456 10712432640 1313278272 13.816 96 13 24 0 1325305264 9015856 3011136
15:54:38 75132 8192000000 10244850256 10708762624 1337522144 14.035 99 17 34 0 1352982368 11412544 4047680
15:56:54 41972 8192000000 10279306560 10729074688 1368059904 14.310 100 25 50 0 1381630864 7880784 5690176
15:58:38 99558 8192000000 10278759872 10756788224 1387140640 14.480 100 12 24 0 1400032976 10052560 2839776
16:01:18 44422 8192000000 10292831088 10748424192 1377998720 14.399 99 16 32 0 1396324944 14503744 3822480
16:03:05 68917 8192000000 10290258176 10751848448 1348359712 14.133 97 18 36 0 1369371232 16698112 4313408
16:05:22 58322 8192000000 10301575792 10771775488 1386969104 14.479 100 22 44 2 1408250784 16078768 5202912
16:07:33 64129 8192000000 10316929952 10768625664 1386105504 14.471 99 20 40 1 1402554208 12427360 4021344
16:09:43 55236 8192000000 10316617728 10783674368 1392040896 14.524 99 8 16 1 1407210416 13662320 1507200
16:11:39 92017 8192000000 10299696896 10753359872 1367335600 14.303 98 21 43 1 1381734576 9819952 4579024
16:14:13 79963 8192000000 10297417088 10750042112 1382629776 14.440 99 28 57 0 1397935376 8157568 7148032
16:16:43 14958 8192000000 10310229968 10758303744 1396780864 14.566 100 42 83 0 1414988080 8827744 9379472
16:17:58 51988 8192000000 10296176752 10762289152 1373951056 14.362 98 16 32 0 1386990016 9615296 3423664
16:19:51 67182 8192000000 10300807872 10774544384 1393216032 14.535 99 15 31 1 1413837776 16758608 3863136
16:22:09 68529 8192000000 10279027120 10739822592 1341502784 14.071 97 22 44 1 1357029600 10209856 5316960
16:24:22 88481 8192000000 10274626000 10730098688 1330511824 13.972 97 17 34 1 1348809360 14312480 3985056
16:27:28 66521 8192000000 10260671280 10716168192 1312945088 13.813 96 15 30 0 1329235024 12683120 3606816
16:29:50 51486 8192000000 10250992416 10708246528 1288280032 13.589 95 22 44 2 1304201856 11049872 4871952
16:31:46 68995 8192000000 10242761600 10699726848 1286591072 13.573 96 17 34 0 1301974560 11937360 3446128
16:35:31 46966 8192000000 10239602976 10690396160 1307297024 13.762 98 40 78 0 1334079920 18200960 8581936
16:37:19 54594 8192000000 10215915456 10672377856 1316199168 13.842 99 15 30 0 1327998896 8614640 3185088

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 27-02-2023 16:41:05 to 27-02-2023 16:41:20.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 89 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 27-02-2023 16:41:21 to 27-02-2023 16:41:23.

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          243264
MON$OLDEST_ACTIVE               244977
MON$OLDEST_SNAPSHOT             244977
MON$NEXT_TRANSACTION            244977
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                       2034592
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.33665 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33665 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33665 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33665 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 ---
:702: 
:703: mscs-test-161.budzdorov.ru    Mon Feb 27 16:38:39 2023
:704:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:705: 
:706: 
:707: mscs-test-161.budzdorov.ru    Mon Feb 27 16:39:01 2023
:708:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:709: 
--- end of diff output ---

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

27.02.23 16:41:24. 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
2023-02-27 16:41:27 No severe PSQL-related problems occured
27.02.23 16:41:28. End of report.