Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 21.01.2023 04:38
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15072640 kB
MemAvailable 30416300 kB
Buffers 20 kB
Cached 16594304 kB
SwapCached 1644 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 4.0.3
DB_NAME                         /home/bases/oltp40-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/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/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=3400
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/oltp40-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-oltp40
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-01-21 04:37:26
DTS_END                         2023-01-21 04:37:26.3290
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(113:22) ==> SP_CANCEL_WRITE_OFF(41:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/46280
TRN_ID                          220999
ATT_ID                          618
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: 9118.83 29046 1094260
customer order: creation 165.17 471 19820
customer order: refuse 14.58 428 1749
order to supplier: creation 44.23 1444 5307
order to supplier: removal 3.41 161843 409
invoice (draft): creation 45.33 2987 5440
invoice (draft): removal 49.67 1039 5960
invoice accept: apply 42.52 27095 5102
invoice accept: cancel 44.76 1268 5371
customer reserve: creation 3945.98 258 473518
customer reserve: removal 4470.01 96 536401
realization accept: apply 82.00 279 9840
realization accept: cancel 81.02 178 9722
payment from customer: creation 58.61 208 7033
payment from customer: removal 5.36 122 643
payment to supplier: creation 49.34 187 5921
payment to supplier: removal 3.72 122 446
service: total inventory turnovers 4.18 3865 501
service: total monetary turnovers 8.07 354 969
service: aggregate perf. data 0.86 19316 103
service: refresh index statistics 0.04 359364 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 21-01-2023 04:38:56 to 21-01-2023 04:38:58.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 10074
TEST_TIME 2 6908
TEST_TIME 3 6292
TEST_TIME 4 6776
TEST_TIME 5 6593
TEST_TIME 6 6753
TEST_TIME 7 6862
TEST_TIME 8 6952
TEST_TIME 9 7058
TEST_TIME 10 7223
TEST_TIME 11 7427
TEST_TIME 12 7658
TEST_TIME 13 7762
TEST_TIME 14 7759
TEST_TIME 15 7799
TEST_TIME 16 7674
TEST_TIME 17 7711
TEST_TIME 18 7966
TEST_TIME 19 7849
TEST_TIME 20 7835
TEST_TIME 21 7841
TEST_TIME 22 7863
TEST_TIME 23 8031
TEST_TIME 24 8123
TEST_TIME 25 8174
TEST_TIME 26 8331
TEST_TIME 27 8489
TEST_TIME 28 8694
TEST_TIME 29 9447
TEST_TIME 30 9676
TEST_TIME 31 10759
TEST_TIME 32 11003
TEST_TIME 33 11353
TEST_TIME 34 11370
TEST_TIME 35 11450
TEST_TIME 36 11198
TEST_TIME 37 10953
TEST_TIME 38 10757
TEST_TIME 39 10520
TEST_TIME 40 10455
TEST_TIME 41 10445
TEST_TIME 42 10319
TEST_TIME 43 10220
TEST_TIME 44 10225
TEST_TIME 45 10163
TEST_TIME 46 10070
TEST_TIME 47 10023
TEST_TIME 48 10070
TEST_TIME 49 9954
TEST_TIME 50 9929
TEST_TIME 51 9995
TEST_TIME 52 9841
TEST_TIME 53 9896
TEST_TIME 54 9843
TEST_TIME 55 9838
TEST_TIME 56 9747
TEST_TIME 57 9771
TEST_TIME 58 9905
TEST_TIME 59 9963
TEST_TIME 60 9854
TEST_TIME 61 9829
TEST_TIME 62 9839
TEST_TIME 63 10037
TEST_TIME 64 9988
TEST_TIME 65 10010
TEST_TIME 66 9969
TEST_TIME 67 9860
TEST_TIME 68 9790
TEST_TIME 69 9781
TEST_TIME 70 9744
TEST_TIME 71 9674
TEST_TIME 72 9642
TEST_TIME 73 9589
TEST_TIME 74 9491
TEST_TIME 75 9484
TEST_TIME 76 9448
TEST_TIME 77 9410
TEST_TIME 78 9404
TEST_TIME 79 9392
TEST_TIME 80 9448
TEST_TIME 81 9403
TEST_TIME 82 9475
TEST_TIME 83 9452
TEST_TIME 84 9452
TEST_TIME 85 9463
TEST_TIME 86 9540
TEST_TIME 87 9548
TEST_TIME 88 9583
TEST_TIME 89 9559
TEST_TIME 90 9604
TEST_TIME 91 9526
TEST_TIME 92 9453
TEST_TIME 93 9473
TEST_TIME 94 9410
TEST_TIME 95 9353
TEST_TIME 96 9287
TEST_TIME 97 9277
TEST_TIME 98 9239
TEST_TIME 99 9330
TEST_TIME 100 9279
TEST_TIME 101 9265
TEST_TIME 102 9312
TEST_TIME 103 9268
TEST_TIME 104 9261
TEST_TIME 105 9272
TEST_TIME 106 9295
TEST_TIME 107 9421
TEST_TIME 108 9436
TEST_TIME 109 9417
TEST_TIME 110 9356
TEST_TIME 111 9351
TEST_TIME 112 9308
TEST_TIME 113 9302
TEST_TIME 114 9267
TEST_TIME 115 9280
TEST_TIME 116 9255
TEST_TIME 117 9228
TEST_TIME 118 9248
TEST_TIME 119 9212
TEST_TIME 120 9145

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 21-01-2023 04:39:00 to 21-01-2023 04:39:03.

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 1530022 1530022 0 0.00 0 15727 14 0 0
doc_list_biud 1530008 1530008 0 0.00 0 30219 39 0 0
sp_add_invoice_to_stock 5239 5102 137 2.61 41 176099 27095 0 137
sp_cancel_adding_invoice 5371 5371 0 0.00 6 89874 1269 0 0
sp_cancel_client_order 1749 1749 0 0.00 7 20783 429 0 0
sp_cancel_customer_reserve 536401 536401 0 0.00 1 25304 96 0 0
sp_cancel_pay_from_customer 643 643 0 0.00 4 7746 123 0 0
sp_cancel_pay_to_supplier 446 446 0 0.00 4 3174 123 0 0
sp_cancel_supplier_invoice 5969 5960 9 0.15 2 18509 1040 0 9
sp_cancel_supplier_order 409 409 0 0.00 9 1578816 161843 0 0
sp_cancel_write_off 9722 9722 0 0.00 2 16123 178 0 0
sp_client_order 19820 19820 0 0.00 6 24126 472 0 0
sp_customer_reserve 473518 473518 0 0.00 1 38817 259 0 0
sp_fill_shopping_cart 30555 30555 0 0.00 1 14037 79 0 0
sp_fill_shopping_cart_clo_res 473485 473485 0 0.00 0 15274 19 0 0
sp_get_clo_for_invoice 5056 5056 0 0.00 0 28104 279 0 0
sp_kill_cost_storno 16182 16182 0 0.00 0 8464 69 0 0
sp_kill_qstorno_ret_qs2qd 1088893 1088893 0 0.00 0 25240 41 0 0
sp_kill_qty_storno 574503 574503 0 0.00 0 30218 96 0 0
sp_lock_selected_doc 20543 20543 0 0.00 0 12591 9 0 0
sp_make_cost_storno 27848 27848 0 0.00 0 15681 76 0 0
sp_multiply_rows_for_pdistr 27846 27846 0 0.00 0 8551 38 0 0
sp_multiply_rows_for_qdistr 19820 19820 0 0.00 0 19095 180 0 0
sp_pay_from_customer 7033 7033 0 0.00 5 21536 208 0 0
sp_pay_to_supplier 5921 5921 0 0.00 6 17814 187 0 0
sp_payment_common 12954 12954 0 0.00 2 9913 51 0 0
sp_qd_handle_on_cancel_clo 1749 1749 0 0.00 1 20773 350 0 0
sp_qd_handle_on_invoice_upd_sts 10423 10423 0 0.00 0 30217 522 0 0
sp_qd_handle_on_reserve_upd_sts 9839 9839 0 0.00 0 11999 63 0 0
sp_reserve_write_off 9840 9840 0 0.00 8 21623 279 0 0
sp_supplier_invoice 5440 5440 0 0.00 11 83977 2988 0 0
sp_supplier_order 5307 5307 0 0.00 8 40221 1445 0 0
srv_aggregate_perf_data 104 103 1 0.96 2384 158677 19316 0 1
srv_fill_mon_cache_memory 72 72 0 0.00 690 162136 34719 0 0
srv_find_qd_qs_mism 1045254 1045254 0 0.00 0 8097 11 0 0
srv_make_invnt_saldo 501 501 0 0.00 50 92085 3865 0 0
srv_make_money_saldo 969 969 0 0.00 1 10133 355 0 0
srv_recalc_idx_stat 5 5 0 0.00 155586 526772 359365 0 0
t$perf-abend:sp_add_invoice_to_stock 137 137 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 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 5108 5108 0 0.00 1 14761 915 0 0
t$perf-norm:sp_cancel_adding_invoice 409 409 0 0.00 2 14537 487 0 0
t$perf-norm:sp_cancel_client_order 1749 1749 0 0.00 0 3363 27 0 0
t$perf-norm:sp_cancel_customer_reserve 697 697 0 0.00 0 687 27 0 0
t$perf-norm:sp_cancel_pay_from_customer 643 643 0 0.00 0 602 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 446 446 0 0.00 0 1599 17 0 0
t$perf-norm:sp_cancel_supplier_invoice 394 394 0 0.00 0 1251 39 0 0
t$perf-norm:sp_cancel_supplier_order 409 409 0 0.00 0 79628 6068 0 0
t$perf-norm:sp_cancel_write_off 906 906 0 0.00 0 1068 16 0 0
t$perf-norm:sp_client_order 19821 19821 0 0.00 0 11040 35 0 0
t$perf-norm:sp_customer_reserve 982 982 0 0.00 0 3203 18 0 0
t$perf-norm:sp_pay_from_customer 7034 7034 0 0.00 0 6025 21 0 0
t$perf-norm:sp_pay_to_supplier 5921 5921 0 0.00 0 4974 19 0 0
t$perf-norm:sp_reserve_write_off 9840 9840 0 0.00 0 8490 21 0 0
t$perf-norm:sp_supplier_invoice 5440 5440 0 0.00 0 3203 28 0 0
t$perf-norm:sp_supplier_order 5307 5307 0 0.00 0 3342 31 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 3060 60 0 0
t$perf-norm:srv_fill_mon_cache_memory 72 72 0 0.00 0 511 10 0 0
t$perf-norm:srv_make_invnt_saldo 501 501 0 0.00 0 2819 19 0 0
t$perf-norm:srv_make_money_saldo 969 969 0 0.00 0 3058 9 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 6 194 69 0 0
v_add_invoice_to_stock 10374 10100 274 2.64 0 5902 26 0 274
v_all_customers 36684 36684 0 0.00 0 126 4 0 0
v_all_suppliers 30232 30232 0 0.00 0 143 4 0 0
v_all_wares 39630 39630 0 0.00 0 97 4 0 0
v_cancel_adding_invoice 818 818 0 0.00 0 268 15 0 0
v_cancel_client_order 3498 3498 0 0.00 0 16946 14 0 0
v_cancel_customer_prepayment 1286 1286 0 0.00 0 7722 17 0 0
v_cancel_customer_reserve 1394 1394 0 0.00 0 528 11 0 0
v_cancel_payment_to_supplier 892 892 0 0.00 0 849 14 0 0
v_cancel_supplier_invoice 806 788 18 2.23 0 2964 32 0 18
v_cancel_supplier_order 818 818 0 0.00 0 1966 18 0 0
v_cancel_write_off 1812 1812 0 0.00 0 9492 36 0 0
v_max_id_clo_ord 5303 5303 0 0.00 0 407 5 0 0
v_max_id_clo_res 982 982 0 0.00 0 769 13 0 0
v_max_id_ord_sup 5432 5432 0 0.00 0 6139 7 0 0
v_max_non_paid_invoice 1539 1539 0 0.00 0 58 8 0 0
v_max_non_paid_realizn 3575 3575 0 0.00 0 791 8 0 0
v_min_id_clo_ord 5303 5303 0 0.00 0 5484 10 0 0
v_min_id_clo_res 982 982 0 0.00 0 2847 11 0 0
v_min_id_ord_sup 5432 5432 0 0.00 0 4563 10 0 0
v_min_non_paid_invoice 5921 5921 0 0.00 0 8241 16 0 0
v_min_non_paid_realizn 7033 7033 0 0.00 0 9908 24 0 0
v_our_firm 9862 9862 0 0.00 0 115 5 0 0
v_reserve_write_off 19678 19678 0 0.00 0 21428 12 0 0
x_lock_depdocs_on_canc_invoice 10937 10937 0 0.00 0 42380 142 0 0
x_lock_depdocs_on_canc_sup_ord 409 409 0 0.00 0 12995 387 0 0
x_make_qstorno_1000_1200 5307 5307 0 0.00 3 40207 1276 0 0
x_make_qstorno_1200_2000 5440 5440 0 0.00 5 74192 2785 0 0
x_make_qstorno_2100_3300 473518 473518 0 0.00 0 31387 227 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 21-01-2023 04:39:04 to 21-01-2023 04:39:08.

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
02:07:30 932 8192000000 8912635472 8965791744 14330032 0.174 4 3 5 0 14540880 112832 98016
02:08:32 24488 8192000000 9718357536 10016837632 796559520 8.861 100 86 172 0 825420128 7583616 21276992
02:10:05 19365 8192000000 9757589616 10065809408 833509616 9.235 100 87 172 0 865857792 11426208 20921968
02:11:32 10665 8192000000 9793930240 10114502656 871492688 9.615 100 89 178 0 910133248 17825792 20814768
02:12:55 5169 8192000000 9861891312 10196320256 921261632 10.109 100 77 155 0 976267472 38526448 16479392
02:14:02 4649 8192000000 9932133232 10275934208 959132816 10.481 100 88 173 0 1044660960 67665200 17862944
02:15:12 2071 8192000000 9947609888 10301157376 986985280 10.752 100 89 171 1 1064732704 60018928 17728496
02:16:16 1722 8192000000 9984308592 10340831232 1013014160 11.005 100 90 172 3 1107737184 77736768 16986256
02:17:21 2372 8192000000 10006934688 10373672960 1030894224 11.177 100 87 165 0 1122452016 75588928 15968864
02:18:24 889 8192000000 10015059712 10391318528 1058931312 11.446 100 95 184 1 1141722096 63768656 19022128
02:19:26 1202 8192000000 10032082416 10409361408 1065305600 11.507 100 90 175 1 1153268240 69891472 18071168
02:20:28 1151 8192000000 10037337424 10420015104 1079133296 11.639 100 78 154 1 1166136880 71993968 15009616
02:21:31 994 8192000000 10055926032 10444840960 1087175152 11.716 100 82 160 2 1179940560 75794256 16971152
02:22:33 1326 8192000000 10045416112 10430902272 1088447216 11.728 100 91 167 1 1169223328 62788784 17987328
02:23:35 1219 8192000000 10049450480 10441068544 1091853344 11.760 100 88 156 2 1173183696 65287296 16043056
02:24:37 1106 8192000000 10056900352 10449887232 1094123264 11.782 100 83 156 0 1180260512 68899760 17237488
02:25:38 2057 8192000000 10049748672 10438967296 1093017344 11.771 100 83 160 1 1170827584 61506144 16304096
02:26:43 1079 8192000000 10062310112 10457149440 1095928528 11.799 100 82 154 2 1183644208 70434960 17280720
02:27:45 811 8192000000 10049759792 10435473408 1070940800 11.561 100 81 158 3 1171736112 84676384 16118928
02:28:47 3467 8192000000 10062773936 10461896704 1091667312 11.759 100 76 148 0 1188523792 81974688 14881792
02:29:51 1099 8192000000 10016312368 10413686784 1075534416 11.605 100 72 137 2 1138212560 47113008 15565136
02:30:53 1255 8192000000 10052415008 10439475200 1078781712 11.636 100 95 177 2 1176657632 80296624 17579296
02:31:55 1143 8192000000 10048916272 10435821568 1086335088 11.708 100 96 188 0 1170137920 64952800 18850032
02:32:58 1262 8192000000 10022227440 10402648064 1068786400 11.540 100 93 179 1 1144234128 55929520 19518208
02:34:00 964 8192000000 10019349904 10394882048 1041004864 11.274 100 85 166 2 1144319776 86757680 16557232
02:35:02 2106 8192000000 9989928864 10369970176 1042367152 11.287 100 91 170 3 1126079392 63868944 19843296
02:36:05 1549 8192000000 9974486352 10346418176 1022979472 11.101 99 79 153 6 1096498304 59004464 14514368
02:37:08 828 8192000000 10004095120 10379923456 1048373424 11.345 100 92 184 0 1131746464 62329680 21043360
02:38:14 16537 8192000000 9990570224 10368708608 1053883488 11.398 100 79 148 1 1116293184 48143520 14266176
02:39:36 8443 8192000000 10018142736 10390196224 1047565200 11.337 99 90 168 1 1145326992 79845008 17916784
02:40:53 4613 8192000000 10045744496 10437156864 1064787152 11.502 100 70 137 0 1165841504 87031680 14022672
02:42:03 2061 8192000000 10039655424 10421813248 1080855312 11.656 100 82 152 3 1153528640 56076880 16596448
02:43:11 16080 8192000000 10093520656 10490540032 1090523552 11.748 100 69 137 0 1206075712 101434496 14117664
02:44:32 1237 8192000000 10098592800 10494005248 1105589808 11.891 100 98 183 0 1216358736 90476240 20292688
02:45:37 13985 8192000000 10119530208 10520866816 1122273248 12.048 100 80 142 3 1225736896 89405808 14057840
02:46:54 4332 8192000000 10168878528 10577960960 1129505904 12.117 100 76 150 0 1288665504 143021040 16138560
02:48:03 1316 8192000000 10214460672 10613575680 1157721536 12.382 100 84 165 1 1318476896 143793024 16962336
02:49:08 1082 8192000000 10250988848 10650882048 1160660240 12.409 100 94 184 1 1362961296 182906704 19394352
02:50:11 1111 8192000000 10250265184 10659868672 1168642064 12.484 100 83 162 1 1367432480 180618496 18171920
02:51:19 977 8192000000 10288013392 10692706304 1198160704 12.759 100 82 155 0 1399330752 184206544 16963504
02:52:22 1867 8192000000 10339195472 10745659392 1202630048 12.801 100 91 170 0 1446949072 224948256 19370768
02:53:26 1169 8192000000 10386711200 10792103936 1206892816 12.840 100 96 181 0 1496271888 268753984 20625088
02:54:32 2459 8192000000 10403514272 10823671808 1212489008 12.892 100 88 166 1 1512917648 281134512 19294128
02:55:38 1489 8192000000 10442405600 10871889920 1218028336 12.943 100 92 178 0 1546907056 308258416 20620304
02:56:41 918 8192000000 10498621792 10908372992 1226680896 13.023 100 98 193 1 1606847424 357343296 22823232
02:57:45 734 8192000000 10535185488 10955505664 1227634624 13.032 100 99 194 1 1637513600 387460928 22418048
02:58:50 683 8192000000 10586327792 10993291264 1214214192 12.908 100 98 196 1 1692101808 454757856 23129760
02:59:53 1174 8192000000 10650328848 11076653056 1220610496 12.967 100 96 190 0 1756756896 511063792 25082608
03:00:57 2443 8192000000 10619726656 11059376128 1218895760 12.951 100 82 162 2 1724971472 485321760 20753952
03:02:01 2723 8192000000 10643963536 11073818624 1213757296 12.904 100 80 157 1 1747093472 513194800 20141376
03:03:06 2889 8192000000 10636868000 11085434880 1183979632 12.627 100 65 128 0 1747575584 546920048 16675904
03:04:11 5367 8192000000 10643950208 11096121344 1184161296 12.629 100 61 117 0 1756766128 557787264 14817568
03:05:18 14996 8192000000 10601943904 11109597184 1175577456 12.549 100 46 92 0 1730445504 541658352 13209696
03:06:34 16280 8192000000 10465954160 10974318592 1172099440 12.516 100 40 79 0 1606566832 424182720 10284672
03:07:57 48017 8192000000 10243780080 10860867584 1147200144 12.283 99 26 48 0 1438040512 284521920 6318448
03:10:30 46907 8192000000 10104962096 10555383808 1130395120 12.125 98 12 24 0 1204673520 71479152 2799248
03:12:31 93007 8192000000 10069594304 10503798784 1126853584 12.092 98 39 65 0 1157323536 24422400 6047552
03:15:12 162133 8192000000 10078131920 10525638656 1087627504 11.720 95 22 40 0 1146453952 54825328 4001120
03:18:57 50582 8192000000 10057526640 10494218240 1088025104 11.724 96 34 64 1 1134366944 39251744 7090096
03:20:49 6817 8192000000 10070770096 10504073216 1122962784 12.055 100 40 76 0 1166647392 36242096 7442512
03:21:57 12171 8192000000 10086171552 10528915456 1122527344 12.051 100 31 60 1 1179487536 50219840 6740352
03:23:10 22764 8192000000 10106154576 10536607744 1123961216 12.064 100 34 66 0 1201326736 69960560 7404960
03:24:35 31716 8192000000 10152221248 10598834176 1125883072 12.083 99 32 64 1 1263278416 130411584 6983760
03:26:08 39893 8192000000 10131487904 10581876736 1131217456 12.133 99 13 24 0 1223854624 90133952 2503216
03:27:49 24943 8192000000 10089710160 10584727552 1119582208 12.023 99 19 38 0 1203402416 79940720 3879488
03:29:15 64421 8192000000 10067587616 10556125184 1110635312 11.938 98 22 44 0 1181396112 66582832 4177968
03:31:21 25557 8192000000 10102661856 10540068864 1131302320 12.134 100 34 68 0 1181393920 42985600 7106000
03:32:52 22376 8192000000 10111569728 10560200704 1146113280 12.273 100 26 51 0 1206131840 54731280 5287280
03:34:17 45223 8192000000 10155472992 10607906816 1154706944 12.354 100 35 66 0 1262027488 100295376 7025168
03:36:07 38501 8192000000 10101960384 10575540224 1151068416 12.320 100 17 34 0 1206326960 51718256 3540288
03:37:51 53938 8192000000 10162337424 10627346432 1140233328 12.218 98 24 48 0 1236145728 90770208 5142192
03:39:45 86426 8192000000 10073589696 10509266944 1094121888 11.782 96 19 39 0 1201046240 102538096 4386256
03:42:13 46587 8192000000 10120977952 10594541568 1105950288 11.894 97 42 80 0 1189873840 75586944 8336608
03:44:00 78637 8192000000 10132165392 10602864640 1131971632 12.140 99 41 80 0 1211993152 71556992 8464528
03:46:20 34749 8192000000 10085799376 10581336064 1135172352 12.170 100 30 58 1 1222080944 80543616 6364976
03:47:56 19982 8192000000 10035212272 10487828480 1108321168 11.917 99 14 29 0 1144897696 33905536 2670992
03:49:22 62615 8192000000 10069215456 10522619904 1115451824 11.984 99 16 32 0 1175229424 56299808 3477792
03:51:27 36540 8192000000 10065357856 10532564992 1107050992 11.904 98 22 41 0 1157552416 45928864 4572560
03:53:03 39722 8192000000 10050227248 10505027584 1117341936 12.002 99 28 54 1 1142001264 19985888 4673440
03:55:21 32916 8192000000 10068388192 10525835264 1108116944 11.915 98 30 60 0 1173730096 59573616 6039536
03:56:55 41208 8192000000 10081368016 10535337984 1129348960 12.115 100 23 44 0 1200839696 66587344 4903392
03:58:37 41737 8192000000 10083306992 10537226240 1106815472 11.902 98 34 68 0 1163159904 49260800 7083632
04:00:19 31058 8192000000 10061403952 10496671744 1098874688 11.827 98 38 76 1 1142647984 36387488 7385808
04:01:52 33950 8192000000 10128815856 10585890816 1112957808 11.960 99 48 96 0 1242573344 120048768 9566768
04:03:26 37132 8192000000 10119992016 10577293312 1120972336 12.036 99 35 68 0 1203158352 75115040 7070976
04:05:04 31228 8192000000 10124995184 10588696576 1137650128 12.193 100 32 60 2 1220441648 76457600 6333920
04:06:38 122186 8192000000 10085275696 10559148032 1135084208 12.169 100 23 46 0 1205228288 65176928 4967152
04:10:33 53120 8192000000 10055522576 10514321408 1096923392 11.808 98 12 24 0 1163311424 63911264 2476768
04:12:27 74817 8192000000 10115160496 10568650752 1123277568 12.058 100 15 30 0 1190434256 64048576 3108112
04:14:49 53595 8192000000 10148390416 10639065088 1129643232 12.118 100 8 16 0 1238827008 107103136 2080640
04:16:52 61475 8192000000 10115024736 10556559360 1113255376 11.963 98 20 40 0 1203363536 85761392 4346768
04:18:58 33698 8192000000 10129383568 10581471232 1105222048 11.887 97 27 54 0 1203168512 90935840 7010624
04:20:34 35580 8192000000 10137444560 10585935872 1111829968 11.950 98 31 62 0 1211867584 93210160 6827456
04:22:18 43501 8192000000 10162485840 10602823680 1110692080 11.939 98 47 92 0 1233674128 113673600 9308448
04:24:05 112767 8192000000 10106422912 10644512768 1063818368 11.493 94 20 40 0 1133923920 65772480 4333072
04:27:02 101378 8192000000 10082867648 10568466432 1132318544 12.143 100 30 59 0 1200764768 62624016 5822208
04:30:02 54383 8192000000 10073874960 10550849536 1138420816 12.201 100 7 14 0 1172002112 32567104 1014192
04:31:57 76062 8192000000 10115136208 10584862720 1153912432 12.346 100 20 38 0 1226935504 69255696 3767376
04:34:13 63741 8192000000 10130700992 10579841024 1147697376 12.288 99 52 93 1 1208840464 52020960 9122128
04:36:17 42824 8192000000 10160536432 10594111488 1150305712 12.312 99 33 64 0 1238032640 80842880 6884048

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 21-01-2023 04:39:10 to 21-01-2023 04:39:27.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 137 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 21-01-2023 04:39:28 to 21-01-2023 04:39:30.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          217735
MON$OLDEST_ACTIVE               221176
MON$OLDEST_SNAPSHOT             221176
MON$NEXT_TRANSACTION            221176
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-30 07:30:08.6030 GMT
MON$PAGES                       2060512
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             776
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2890 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2890 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2890 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2890 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.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 ---
:911: 
:912: mscs-test-161.budzdorov.ru    Sat Jan 21 04:37:40 2023
:913:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:914: 
--- end of diff output ---

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

21.01.23 04:39:31. There are 3 logs in /var/tmp/logs-oltp40 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-01-21 04:39:34 No severe PSQL-related problems occured
21.01.23 04:39:35. End of report.