Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 19.02.2023 19: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 15365224 kB
MemAvailable 30408460 kB
Buffers 0 kB
Cached 16323028 kB
SwapCached 3140 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-19 19:38:40
DTS_END                         2023-02-19 19:38:40.6910
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/34296
TRN_ID                          243958
ATT_ID                          712
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: 9223.75 24053 1106850
customer order: creation 170.38 362 20446
customer order: refuse 15.23 331 1827
order to supplier: creation 46.08 1231 5529
order to supplier: removal 3.26 102123 391
invoice (draft): creation 46.18 3267 5541
invoice (draft): removal 51.91 701 6229
invoice accept: apply 42.47 17793 5096
invoice accept: cancel 46.84 723 5621
customer reserve: creation 3913.65 175 469638
customer reserve: removal 4589.94 57 550793
realization accept: apply 83.20 172 9984
realization accept: cancel 85.38 110 10245
payment from customer: creation 59.05 103 7086
payment from customer: removal 5.86 62 703
payment to supplier: creation 48.92 135 5870
payment to supplier: removal 3.53 66 423
service: total inventory turnovers 4.72 1175 567
service: total monetary turnovers 6.18 38 741
service: aggregate perf. data 0.97 17316 116
service: refresh index statistics 0.03 335116 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 19-02-2023 19:39:49 to 19-02-2023 19:39:51.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3427
TEST_TIME 2 3193
TEST_TIME 3 3534
TEST_TIME 4 3998
TEST_TIME 5 3737
TEST_TIME 6 4249
TEST_TIME 7 5088
TEST_TIME 8 5163
TEST_TIME 9 5279
TEST_TIME 10 5753
TEST_TIME 11 5905
TEST_TIME 12 6428
TEST_TIME 13 6520
TEST_TIME 14 7269
TEST_TIME 15 7694
TEST_TIME 16 7629
TEST_TIME 17 7811
TEST_TIME 18 7688
TEST_TIME 19 7922
TEST_TIME 20 8145
TEST_TIME 21 8432
TEST_TIME 22 8283
TEST_TIME 23 8594
TEST_TIME 24 9043
TEST_TIME 25 9006
TEST_TIME 26 8948
TEST_TIME 27 9784
TEST_TIME 28 10257
TEST_TIME 29 10569
TEST_TIME 30 10788
TEST_TIME 31 10549
TEST_TIME 32 10365
TEST_TIME 33 10446
TEST_TIME 34 10368
TEST_TIME 35 10284
TEST_TIME 36 10127
TEST_TIME 37 10033
TEST_TIME 38 9894
TEST_TIME 39 9882
TEST_TIME 40 10002
TEST_TIME 41 9931
TEST_TIME 42 9897
TEST_TIME 43 9758
TEST_TIME 44 9601
TEST_TIME 45 9504
TEST_TIME 46 9454
TEST_TIME 47 9525
TEST_TIME 48 9516
TEST_TIME 49 9403
TEST_TIME 50 9326
TEST_TIME 51 9188
TEST_TIME 52 9270
TEST_TIME 53 9228
TEST_TIME 54 9155
TEST_TIME 55 9094
TEST_TIME 56 9061
TEST_TIME 57 9043
TEST_TIME 58 9031
TEST_TIME 59 9121
TEST_TIME 60 9066
TEST_TIME 61 9168
TEST_TIME 62 9197
TEST_TIME 63 9174
TEST_TIME 64 9362
TEST_TIME 65 9354
TEST_TIME 66 9353
TEST_TIME 67 9348
TEST_TIME 68 9398
TEST_TIME 69 9428
TEST_TIME 70 9447
TEST_TIME 71 9506
TEST_TIME 72 9478
TEST_TIME 73 9364
TEST_TIME 74 9323
TEST_TIME 75 9251
TEST_TIME 76 9297
TEST_TIME 77 9273
TEST_TIME 78 9241
TEST_TIME 79 9208
TEST_TIME 80 9169
TEST_TIME 81 9132
TEST_TIME 82 9157
TEST_TIME 83 9114
TEST_TIME 84 9122
TEST_TIME 85 9127
TEST_TIME 86 9243
TEST_TIME 87 9236
TEST_TIME 88 9268
TEST_TIME 89 9227
TEST_TIME 90 9228
TEST_TIME 91 9311
TEST_TIME 92 9279
TEST_TIME 93 9381
TEST_TIME 94 9421
TEST_TIME 95 9404
TEST_TIME 96 9359
TEST_TIME 97 9291
TEST_TIME 98 9299
TEST_TIME 99 9241
TEST_TIME 100 9212
TEST_TIME 101 9238
TEST_TIME 102 9218
TEST_TIME 103 9198
TEST_TIME 104 9137
TEST_TIME 105 9127
TEST_TIME 106 9125
TEST_TIME 107 9174
TEST_TIME 108 9205
TEST_TIME 109 9204
TEST_TIME 110 9221
TEST_TIME 111 9222
TEST_TIME 112 9284
TEST_TIME 113 9266
TEST_TIME 114 9273
TEST_TIME 115 9264
TEST_TIME 116 9271
TEST_TIME 117 9317
TEST_TIME 118 9340
TEST_TIME 119 9283
TEST_TIME 120 9226

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 19-02-2023 19:39:52 to 19-02-2023 19:39:56.

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 1541837 1541837 0 0.00 0 19169 6 0 0
doc_list_biud 1541837 1541837 0 0.00 0 61741 26 0 0
sp_add_invoice_to_stock 5225 5096 129 2.46 26 158626 17794 0 129
sp_cancel_adding_invoice 5621 5621 0 0.00 4 84101 724 0 0
sp_cancel_client_order 1827 1827 0 0.00 4 10784 331 0 0
sp_cancel_customer_reserve 550793 550793 0 0.00 0 61742 58 0 0
sp_cancel_pay_from_customer 703 703 0 0.00 3 3352 63 0 0
sp_cancel_pay_to_supplier 423 423 0 0.00 3 4053 66 0 0
sp_cancel_supplier_invoice 6240 6229 11 0.17 1 13244 701 0 11
sp_cancel_supplier_order 391 391 0 0.00 4 877208 102123 0 0
sp_cancel_write_off 10245 10245 0 0.00 1 8353 111 0 0
sp_client_order 20446 20446 0 0.00 3 61682 362 0 0
sp_customer_reserve 469638 469638 0 0.00 0 70926 176 0 0
sp_fill_shopping_cart 31504 31504 0 0.00 1 16702 40 0 0
sp_fill_shopping_cart_clo_res 469625 469625 0 0.00 0 16247 16 0 0
sp_get_clo_for_invoice 5081 5081 0 0.00 0 37197 379 0 0
sp_kill_cost_storno 16992 16992 0 0.00 0 7302 53 0 0
sp_kill_qstorno_ret_qs2qd 1118451 1118451 0 0.00 0 61730 31 0 0
sp_kill_qty_storno 590170 590170 0 0.00 0 61741 67 0 0
sp_lock_selected_doc 21093 21093 0 0.00 0 7697 6 0 0
sp_make_cost_storno 28020 28020 0 0.00 0 15428 54 0 0
sp_multiply_rows_for_pdistr 28020 28020 0 0.00 0 11129 30 0 0
sp_multiply_rows_for_qdistr 20446 20446 0 0.00 0 61677 148 0 0
sp_pay_from_customer 7086 7086 0 0.00 4 20422 103 0 0
sp_pay_to_supplier 5870 5870 0 0.00 4 15936 135 0 0
sp_payment_common 12956 12956 0 0.00 1 17106 24 0 0
sp_qd_handle_on_cancel_clo 1827 1827 0 0.00 0 10777 302 0 0
sp_qd_handle_on_invoice_upd_sts 10701 10701 0 0.00 0 23070 292 0 0
sp_qd_handle_on_reserve_upd_sts 9984 9984 0 0.00 0 13497 51 0 0
sp_reserve_write_off 9984 9984 0 0.00 6 20206 172 0 0
sp_supplier_invoice 5541 5541 0 0.00 7 74980 3268 0 0
sp_supplier_order 5529 5529 0 0.00 5 39260 1232 0 0
srv_aggregate_perf_data 117 116 1 0.85 2642 220475 17316 0 1
srv_fill_mon_cache_memory 66 66 0 0.00 1458 140740 43684 0 0
srv_find_qd_qs_mism 1058968 1058968 0 0.00 0 19166 5 0 0
srv_make_invnt_saldo 567 567 0 0.00 16 96342 1176 0 0
srv_make_money_saldo 741 741 0 0.00 1 7932 38 0 0
srv_recalc_idx_stat 4 4 0 0.00 195570 673669 335117 0 0
t$perf-abend:sp_add_invoice_to_stock 129 129 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5096 5096 0 0.00 0 13936 150 0 0
t$perf-norm:sp_cancel_adding_invoice 404 404 0 0.00 0 3319 83 0 0
t$perf-norm:sp_cancel_client_order 1827 1827 0 0.00 0 4160 16 0 0
t$perf-norm:sp_cancel_customer_reserve 709 709 0 0.00 0 1410 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 703 703 0 0.00 0 1061 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 423 423 0 0.00 0 3942 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 443 443 0 0.00 0 1247 17 0 0
t$perf-norm:sp_cancel_supplier_order 391 391 0 0.00 0 16421 1501 0 0
t$perf-norm:sp_cancel_write_off 1129 1129 0 0.00 0 1333 7 0 0
t$perf-norm:sp_client_order 20446 20446 0 0.00 0 6226 14 0 0
t$perf-norm:sp_customer_reserve 1034 1034 0 0.00 0 4719 9 0 0
t$perf-norm:sp_pay_from_customer 7086 7086 0 0.00 0 13599 9 0 0
t$perf-norm:sp_pay_to_supplier 5870 5870 0 0.00 0 19858 16 0 0
t$perf-norm:sp_reserve_write_off 9984 9984 0 0.00 0 5103 12 0 0
t$perf-norm:sp_supplier_invoice 5541 5541 0 0.00 0 6397 17 0 0
t$perf-norm:sp_supplier_order 5529 5529 0 0.00 0 5315 15 0 0
t$perf-norm:srv_aggregate_perf_data 116 116 0 0.00 0 447 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 528 8 0 0
t$perf-norm:srv_make_invnt_saldo 567 567 0 0.00 0 5084 29 0 0
t$perf-norm:srv_make_money_saldo 741 741 0 0.00 0 4527 20 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 93 24 0 0
v_add_invoice_to_stock 10418 10160 258 2.47 0 6999 19 0 258
v_all_customers 37600 37600 0 0.00 0 24 0 0 0
v_all_suppliers 30618 30618 0 0.00 0 35 1 0 0
v_all_wares 40892 40892 0 0.00 0 23 1 0 0
v_cancel_adding_invoice 808 808 0 0.00 0 1119 11 0 0
v_cancel_client_order 3654 3654 0 0.00 0 3334 6 0 0
v_cancel_customer_prepayment 1406 1406 0 0.00 0 3334 8 0 0
v_cancel_customer_reserve 1418 1418 0 0.00 0 830 4 0 0
v_cancel_payment_to_supplier 846 846 0 0.00 0 3649 8 0 0
v_cancel_supplier_invoice 908 886 22 2.42 0 3785 19 0 22
v_cancel_supplier_order 782 782 0 0.00 0 2006 6 0 0
v_cancel_write_off 2258 2258 0 0.00 0 3843 14 0 0
v_max_id_clo_ord 5529 5529 0 0.00 0 541 1 0 0
v_max_id_clo_res 1034 1034 0 0.00 0 1386 9 0 0
v_max_id_ord_sup 5529 5529 0 0.00 0 4947 8 0 0
v_max_non_paid_invoice 1619 1619 0 0.00 0 2449 7 0 0
v_max_non_paid_realizn 3656 3656 0 0.00 0 1653 3 0 0
v_min_id_clo_ord 5529 5529 0 0.00 0 4012 7 0 0
v_min_id_clo_res 1034 1034 0 0.00 0 2880 8 0 0
v_min_id_ord_sup 5529 5529 0 0.00 0 3177 6 0 0
v_min_non_paid_invoice 5870 5870 0 0.00 0 5415 14 0 0
v_min_non_paid_realizn 7086 7086 0 0.00 0 17101 18 0 0
v_our_firm 10152 10152 0 0.00 0 17 1 0 0
v_reserve_write_off 19968 19968 0 0.00 0 7468 6 0 0
x_lock_depdocs_on_canc_invoice 11407 11407 0 0.00 0 26996 117 0 0
x_lock_depdocs_on_canc_sup_ord 391 391 0 0.00 0 5747 353 0 0
x_make_qstorno_1000_1200 5529 5529 0 0.00 1 39251 1158 0 0
x_make_qstorno_1200_2000 5541 5541 0 0.00 3 74000 3111 0 0
x_make_qstorno_2100_3300 469638 469638 0 0.00 0 70925 158 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 19-02-2023 19:39:57 to 19-02-2023 19:40:01.

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
17:08:44 1 8192000000 8797597312 8808398848 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:46 23890 8192000000 9902262192 10212712448 985890416 10.742 100 86 172 0 1014461360 5411136 23159808
17:11:15 22602 8192000000 9961254176 10284118016 1039074224 11.256 100 87 174 0 1069874784 8060496 22740064
17:12:42 13365 8192000000 9990552368 10324750336 1074536720 11.595 100 77 154 0 1103280208 9694080 19049408
17:14:00 8436 8192000000 10044850432 10390016000 1136423200 12.182 100 88 174 0 1168450944 11430656 20597088
17:15:21 5070 8192000000 10095846960 10456064000 1182466464 12.613 100 77 154 0 1213829472 13241856 18121152
17:16:29 2880 8192000000 10128112832 10496565248 1218041920 12.944 100 79 156 0 1249387776 14046304 17299552
17:17:36 3700 8192000000 10141255136 10520313856 1237146624 13.120 100 74 149 1 1265528608 11343072 17038912
17:18:44 1707 8192000000 10189429376 10568040448 1275734512 13.474 100 90 180 0 1310549616 15412432 19402672
17:19:48 1353 8192000000 10214954016 10600513536 1299699232 13.693 100 84 166 2 1331398976 13113824 18585920
17:20:50 3657 8192000000 10211014768 10609520640 1305112592 13.742 100 68 132 0 1330102064 9445024 15544448
17:21:57 6738 8192000000 10196761488 10611318784 1312759792 13.811 100 33 66 0 1326526720 6294576 7472352
17:23:05 11726 8192000000 10204506464 10621349888 1321016704 13.886 100 20 40 0 1331187360 6074768 4095888
17:24:20 54655 8192000000 10234464672 10646573056 1328567440 13.954 100 36 70 3 1342817552 5560896 8689216
17:26:21 6275 8192000000 10224462768 10647539712 1333738496 14.001 100 24 48 1 1345330176 6152208 5439472
17:27:31 6663 8192000000 10226089104 10651680768 1336936032 14.030 100 18 36 0 1347242720 6384128 3922560
17:28:40 6389 8192000000 10190698240 10608447488 1296352944 13.662 99 21 42 0 1306666976 5394688 4919344
17:29:53 32885 8192000000 10160624128 10562605056 1157195696 12.377 89 22 45 1 1166767648 5161392 4410560
17:31:26 5188 8192000000 10133002400 10536144896 1238050992 13.128 99 19 38 0 1248772368 6347600 4373776
17:32:33 10579 8192000000 10151059424 10550595584 1243978448 13.183 99 23 43 0 1254026592 4932000 5116144
17:33:44 67986 8192000000 10160005232 10570092544 1273318336 13.452 100 38 74 1 1287448032 5332352 8797344
17:35:52 6302 8192000000 10183476576 10593959936 1298197504 13.679 100 20 37 0 1307557344 4728256 4631584
17:36:59 12668 8192000000 10200274384 10612625408 1305155184 13.742 100 17 34 0 1318876656 9671936 4049536
17:38:12 42826 8192000000 10228631472 10641870848 1330209664 13.969 100 23 44 0 1339913760 4398880 5305216
17:39:59 14630 8192000000 10243432480 10661617664 1345845488 14.110 100 27 52 1 1357952720 5983712 6123520
17:41:18 19425 8192000000 10275500736 10692325376 1366834544 14.299 100 57 109 2 1389905200 10094592 12976064
17:42:41 9886 8192000000 10287709456 10709962752 1379352288 14.411 100 40 80 0 1400146032 11955872 8837872
17:44:00 29657 8192000000 10305391776 10732064768 1392534624 14.528 100 44 88 0 1418644320 15679200 10430496
17:45:36 7606 8192000000 10295143760 10715357184 1374995104 14.372 99 42 79 2 1402210176 15995552 11219520
17:46:47 3948 8192000000 10323535024 10744213504 1401996048 14.613 100 55 108 1 1433862240 18984112 12882080
17:47:58 1456 8192000000 10338039008 10757820416 1411589024 14.698 100 74 140 1 1448189968 20092992 16507952
17:49:07 9042 8192000000 10347482304 10775109632 1415137024 14.730 100 56 107 0 1459057584 30396512 13524048
17:50:24 3776 8192000000 10325092048 10743173120 1384102768 14.453 99 66 132 1 1429299536 29477328 15719440
17:51:32 4573 8192000000 10335016368 10749345792 1388578000 14.493 100 67 129 1 1437044176 32642672 15823504
17:52:44 2280 8192000000 10312272560 10728161280 1370730608 14.334 99 72 140 0 1419238160 31962288 16545264
17:53:52 22915 8192000000 10319199760 10740973568 1381340416 14.429 99 48 96 0 1426584912 33445072 11799424
17:55:19 5598 8192000000 10314644256 10738388992 1377702224 14.396 100 39 78 0 1422450192 34242160 10505808
17:56:33 4298 8192000000 10290472640 10704453632 1350402400 14.151 100 47 94 0 1397319920 35179824 11737696
17:57:53 10390 8192000000 10305719232 10722140160 1339762272 14.055 97 45 89 1 1393651456 42347680 11541504
17:59:06 9969 8192000000 10324581392 10741010432 1373259616 14.356 100 42 84 0 1425777664 42107040 10411008
18:00:19 3689 8192000000 10326943616 10745561088 1381847184 14.433 100 51 103 1 1433880064 38757120 13275760
18:01:25 16002 8192000000 10326946976 10757251072 1378945920 14.407 100 35 70 0 1436231664 47555152 9730592
18:02:50 13612 8192000000 10325370976 10758598656 1380822080 14.424 100 34 68 1 1434799936 44393376 9584480
18:04:08 15539 8192000000 10321490736 10751680512 1346887456 14.119 98 30 60 0 1406906064 50732800 9285808
18:05:25 11362 8192000000 10297892192 10731855872 1341804624 14.074 99 29 55 0 1402893952 52888448 8200880
18:06:38 44518 8192000000 10301629008 10739871744 1351795136 14.164 100 14 28 0 1391544528 35714064 4035328
18:09:48 13314 8192000000 10254562896 10680119296 1330094016 13.968 98 33 65 0 1344864688 7823264 6947408
18:11:02 5646 8192000000 10284093328 10710667264 1361450448 14.250 100 54 102 2 1385338480 11947584 11940448
18:12:23 74983 8192000000 10276058672 10730823680 1371968304 14.345 100 8 16 0 1383911056 10371664 1571088
18:14:38 35278 8192000000 10288496496 10753200128 1385946208 14.470 100 9 18 0 1404582544 16741088 1895248
18:16:14 43568 8192000000 10293993504 10746748928 1379717872 14.414 99 15 30 0 1396429904 13213184 3498848
18:18:00 68941 8192000000 10300617728 10751332352 1389466416 14.501 100 25 48 0 1406819120 11672720 5679984
18:20:10 140740 8192000000 10325459728 10775482368 1406053360 14.649 100 33 67 1 1424662464 10731568 7877536
18:23:33 46510 8192000000 10304335376 10766180352 1402048320 14.613 99 19 38 0 1419497584 12787936 4661328
18:25:26 57121 8192000000 10317364512 10782969856 1409030688 14.675 100 24 48 1 1431866080 17100176 5735216
18:27:23 82723 8192000000 10306474208 10773356544 1411046176 14.693 100 16 32 1 1423136624 8254432 3836016
18:29:53 75912 8192000000 10314118896 10780033024 1411029040 14.693 100 20 40 2 1426392640 10799824 4563776
18:32:10 52611 8192000000 10306987744 10783543296 1413764736 14.717 100 12 24 1 1426981072 10321216 2895120
18:34:03 62101 8192000000 10296543312 10766598144 1390181984 14.507 98 20 38 0 1403336192 8659952 4494256
18:36:06 33412 8192000000 10309245120 10761842688 1378236384 14.401 98 46 90 0 1398661024 10527728 9896912
18:37:46 45409 8192000000 10288157360 10746150912 1363097472 14.265 98 14 28 1 1381324224 15213216 3013536
18:39:32 23630 8192000000 10291558816 10743619584 1373892336 14.362 99 26 48 1 1392898720 13855904 5150480
18:41:16 70047 8192000000 10280079248 10742001664 1341345792 14.070 97 24 48 1 1365033328 17983008 5704528
18:43:36 29101 8192000000 10282588960 10727235584 1341863120 14.074 98 29 58 0 1358183600 10114560 6205920
18:45:09 25210 8192000000 10283206208 10728804352 1341324288 14.069 97 41 82 0 1360688352 10660144 8703920
18:46:35 116857 8192000000 10301923728 10756366336 1327663520 13.946 96 27 54 1 1346497248 12801680 6032048
18:49:33 66748 8192000000 10264353824 10714902528 1303254720 13.725 94 8 17 0 1317455264 12312256 1888288
18:51:40 51595 8192000000 10255561056 10703482880 1303295056 13.725 95 26 52 1 1315813392 6675472 5842864
18:53:34 30154 8192000000 10236196240 10671734784 1329530464 13.963 99 31 62 0 1349050704 12840416 6679824
18:55:05 38347 8192000000 10218330992 10673897472 1304868192 13.739 98 13 26 0 1317494880 9652160 2974528
18:56:44 56170 8192000000 10248306608 10694422528 1338777472 14.046 99 20 40 0 1351365232 7476592 5111168
18:59:12 73926 8192000000 10247872928 10712899584 1348054912 14.130 99 13 26 0 1360183232 8928240 3200080
19:01:26 48861 8192000000 10283495024 10731544576 1372095536 14.346 100 29 58 0 1391264112 12552336 6616240
19:03:27 40469 8192000000 10279385824 10725056512 1362428496 14.259 99 26 52 0 1380885648 12196128 6261024
19:05:36 64356 8192000000 10297905040 10756214784 1388739824 14.495 100 19 38 0 1407651984 15068944 3843216
19:07:43 31693 8192000000 10315269264 10756603904 1397925872 14.577 100 34 68 0 1415024352 9313664 7784816
19:09:15 97758 8192000000 10320545728 10764726272 1363813824 14.272 97 15 30 0 1384328752 17470640 3044288
19:11:53 138338 8192000000 10307836560 10767835136 1355597968 14.198 96 6 12 0 1371404960 14844112 962880
19:15:12 88790 8192000000 10317783440 10767179776 1393316000 14.535 99 26 51 0 1409702176 10595312 5790864
19:17:41 98237 8192000000 10296372480 10754252800 1387772672 14.486 98 23 44 1 1405248112 12158800 5316640
19:20:20 40664 8192000000 10291376720 10760880128 1388502256 14.493 99 4 8 0 1398994528 9555856 936416
19:22:08 49862 8192000000 10302014304 10763264000 1395489344 14.555 100 18 35 0 1412452096 12869328 4093424
19:24:02 100136 8192000000 10310559504 10776223744 1366781072 14.298 97 11 22 0 1383302208 14004944 2516192
19:26:55 22442 8192000000 10306239952 10751045632 1393182960 14.534 100 25 50 0 1405829200 7570080 5076160
19:28:21 44367 8192000000 10256944048 10708209664 1317991968 13.859 96 15 30 0 1331136192 9510192 3634032
19:30:07 93194 8192000000 10265033360 10715926528 1278866624 13.503 93 20 41 0 1294282800 10838480 4577696
19:32:51 60888 8192000000 10284269360 10728677376 1351376560 14.160 99 36 70 0 1371134816 11955904 7802352
19:35:21 109588 8192000000 10272582416 10721456128 1306293840 13.752 95 16 32 0 1332107536 22034160 3779536
19:38:18 26324 8192000000 9161869168 9415540736 325475840 3.821 24 12 24 0 330667872 2532544 2659488

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 19-02-2023 19:40:03 to 19-02-2023 19:40:18.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 19-02-2023 19:40:19 to 19-02-2023 19:40:21.

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          244312
MON$OLDEST_ACTIVE               244313
MON$OLDEST_SNAPSHOT             244313
MON$NEXT_TRANSACTION            244313
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                       2023232
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 ---
:251: 
:252: mscs-test-161.budzdorov.ru    Sun Feb 19 19:38:47 2023
:253:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:254: 
:255: 
:256: mscs-test-161.budzdorov.ru    Sun Feb 19 19:38:48 2023
:257:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:258: 
--- end of diff output ---

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

19.02.23 19:40:22. 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-19 19:40:25 No severe PSQL-related problems occured
19.02.23 19:40:26. End of report.