Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 07.01.2023 04:37
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 14742332 kB
MemAvailable 30301444 kB
Buffers 20 kB
Cached 16860468 kB
SwapCached 4396 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-07 04:37:25
DTS_END                         2023-01-07 04:37:25.1480
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_AGGREGATE_PERF_DATA(83:5) ==> TMP_AGGREGATE_PERF_DATA_AUTOGEN(451:13) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/57264
TRN_ID                          220603
ATT_ID                          570
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: 8959.34 31615 1075121
customer order: creation 166.10 518 19932
customer order: refuse 14.62 464 1754
order to supplier: creation 45.56 1504 5467
order to supplier: removal 3.30 182945 396
invoice (draft): creation 44.73 3576 5368
invoice (draft): removal 49.88 1082 5986
invoice accept: apply 41.38 28110 4966
invoice accept: cancel 45.56 1410 5467
customer reserve: creation 3821.28 268 458553
customer reserve: removal 4428.93 107 531472
realization accept: apply 82.74 319 9929
realization accept: cancel 87.62 188 10514
payment from customer: creation 57.98 212 6958
payment from customer: removal 5.63 144 675
payment to supplier: creation 47.91 215 5749
payment to supplier: removal 3.28 116 394
service: total inventory turnovers 3.92 5042 470
service: total monetary turnovers 7.95 488 954
service: aggregate perf. data 0.93 16027 112
service: refresh index statistics 0.04 389559 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 07-01-2023 04:38:09 to 07-01-2023 04:38:11.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7852
TEST_TIME 2 6736
TEST_TIME 3 5499
TEST_TIME 4 6002
TEST_TIME 5 5536
TEST_TIME 6 5564
TEST_TIME 7 5893
TEST_TIME 8 5753
TEST_TIME 9 5877
TEST_TIME 10 6344
TEST_TIME 11 6585
TEST_TIME 12 6788
TEST_TIME 13 6822
TEST_TIME 14 6799
TEST_TIME 15 6996
TEST_TIME 16 6989
TEST_TIME 17 7045
TEST_TIME 18 7147
TEST_TIME 19 7188
TEST_TIME 20 7186
TEST_TIME 21 7272
TEST_TIME 22 7315
TEST_TIME 23 7447
TEST_TIME 24 7434
TEST_TIME 25 7550
TEST_TIME 26 7655
TEST_TIME 27 7910
TEST_TIME 28 8165
TEST_TIME 29 8432
TEST_TIME 30 8569
TEST_TIME 31 8914
TEST_TIME 32 8870
TEST_TIME 33 9149
TEST_TIME 34 9452
TEST_TIME 35 9537
TEST_TIME 36 9800
TEST_TIME 37 9749
TEST_TIME 38 9662
TEST_TIME 39 10009
TEST_TIME 40 10163
TEST_TIME 41 10387
TEST_TIME 42 10324
TEST_TIME 43 10159
TEST_TIME 44 10124
TEST_TIME 45 10056
TEST_TIME 46 10085
TEST_TIME 47 10029
TEST_TIME 48 9930
TEST_TIME 49 9844
TEST_TIME 50 9664
TEST_TIME 51 9613
TEST_TIME 52 9534
TEST_TIME 53 9547
TEST_TIME 54 9483
TEST_TIME 55 9424
TEST_TIME 56 9379
TEST_TIME 57 9297
TEST_TIME 58 9320
TEST_TIME 59 9297
TEST_TIME 60 9260
TEST_TIME 61 9227
TEST_TIME 62 9246
TEST_TIME 63 9216
TEST_TIME 64 9194
TEST_TIME 65 9114
TEST_TIME 66 9114
TEST_TIME 67 9103
TEST_TIME 68 9045
TEST_TIME 69 9083
TEST_TIME 70 9061
TEST_TIME 71 9077
TEST_TIME 72 9105
TEST_TIME 73 9210
TEST_TIME 74 9162
TEST_TIME 75 9206
TEST_TIME 76 9259
TEST_TIME 77 9245
TEST_TIME 78 9227
TEST_TIME 79 9201
TEST_TIME 80 9311
TEST_TIME 81 9261
TEST_TIME 82 9198
TEST_TIME 83 9117
TEST_TIME 84 9096
TEST_TIME 85 9058
TEST_TIME 86 9003
TEST_TIME 87 8954
TEST_TIME 88 8989
TEST_TIME 89 9070
TEST_TIME 90 9035
TEST_TIME 91 9003
TEST_TIME 92 9003
TEST_TIME 93 8983
TEST_TIME 94 9058
TEST_TIME 95 9071
TEST_TIME 96 9076
TEST_TIME 97 9098
TEST_TIME 98 9128
TEST_TIME 99 9125
TEST_TIME 100 9085
TEST_TIME 101 9036
TEST_TIME 102 8977
TEST_TIME 103 8950
TEST_TIME 104 8929
TEST_TIME 105 8912
TEST_TIME 106 8880
TEST_TIME 107 8868
TEST_TIME 108 8827
TEST_TIME 109 8788
TEST_TIME 110 8805
TEST_TIME 111 8787
TEST_TIME 112 8803
TEST_TIME 113 8861
TEST_TIME 114 8832
TEST_TIME 115 8881
TEST_TIME 116 8842
TEST_TIME 117 8850
TEST_TIME 118 8922
TEST_TIME 119 8918
TEST_TIME 120 8974

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 07-01-2023 04:38:13 to 07-01-2023 04:38:16.

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 1496350 1496350 0 0.00 0 29618 14 0 0
doc_list_biud 1496335 1496335 0 0.00 0 28927 45 0 0
sp_add_invoice_to_stock 5086 4966 120 2.35 21 245068 28111 0 120
sp_cancel_adding_invoice 5467 5467 0 0.00 7 101828 1411 0 0
sp_cancel_client_order 1754 1754 0 0.00 6 7692 465 0 0
sp_cancel_customer_reserve 531472 531472 0 0.00 1 21263 108 0 0
sp_cancel_pay_from_customer 675 675 0 0.00 4 4292 144 0 0
sp_cancel_pay_to_supplier 394 394 0 0.00 4 817 116 0 0
sp_cancel_supplier_invoice 5995 5986 9 0.15 3 28950 1082 0 9
sp_cancel_supplier_order 396 396 0 0.00 12 1264559 182946 0 0
sp_cancel_write_off 10514 10514 0 0.00 2 27775 189 0 0
sp_client_order 19932 19932 0 0.00 6 15875 518 0 0
sp_customer_reserve 458553 458553 0 0.00 1 88035 269 0 0
sp_fill_shopping_cart 30748 30748 0 0.00 1 11554 88 0 0
sp_fill_shopping_cart_clo_res 458532 458532 0 0.00 0 13301 21 0 0
sp_get_clo_for_invoice 4936 4936 0 0.00 0 15601 274 0 0
sp_kill_cost_storno 17050 17050 0 0.00 0 21244 86 0 0
sp_kill_qstorno_ret_qs2qd 1079840 1079840 0 0.00 0 28923 47 0 0
sp_kill_qty_storno 570446 570446 0 0.00 0 28925 109 0 0
sp_lock_selected_doc 20601 20601 0 0.00 0 7542 8 0 0
sp_make_cost_storno 27570 27570 0 0.00 0 16455 86 0 0
sp_multiply_rows_for_pdistr 27567 27567 0 0.00 0 18123 48 0 0
sp_multiply_rows_for_qdistr 19931 19931 0 0.00 0 11295 197 0 0
sp_pay_from_customer 6958 6958 0 0.00 5 20458 213 0 0
sp_pay_to_supplier 5749 5749 0 0.00 6 18055 216 0 0
sp_payment_common 12706 12706 0 0.00 2 20450 55 0 0
sp_qd_handle_on_cancel_clo 1754 1754 0 0.00 0 7626 386 0 0
sp_qd_handle_on_invoice_upd_sts 10401 10401 0 0.00 0 16486 575 0 0
sp_qd_handle_on_reserve_upd_sts 9923 9923 0 0.00 0 25736 83 0 0
sp_reserve_write_off 9929 9929 0 0.00 8 25775 320 0 0
sp_supplier_invoice 5368 5368 0 0.00 8 99781 3576 0 0
sp_supplier_order 5467 5467 0 0.00 7 30927 1505 0 0
srv_aggregate_perf_data 113 112 1 0.88 2253 167686 16028 0 1
srv_fill_mon_cache_memory 74 74 0 0.00 741 90181 30118 0 0
srv_find_qd_qs_mism 1026519 1026519 0 0.00 0 29618 11 0 0
srv_make_invnt_saldo 470 470 0 0.00 65 106374 5042 0 0
srv_make_money_saldo 954 954 0 0.00 1 14429 488 0 0
srv_recalc_idx_stat 5 5 0 0.00 218462 526522 389559 0 0
t$perf-abend:sp_add_invoice_to_stock 120 120 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 4969 4969 0 0.00 1 17345 996 0 0
t$perf-norm:sp_cancel_adding_invoice 426 426 0 0.00 1 8484 525 0 0
t$perf-norm:sp_cancel_client_order 1754 1754 0 0.00 0 2257 31 0 0
t$perf-norm:sp_cancel_customer_reserve 715 715 0 0.00 0 7356 38 0 0
t$perf-norm:sp_cancel_pay_from_customer 675 675 0 0.00 0 1040 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 394 394 0 0.00 0 3355 23 0 0
t$perf-norm:sp_cancel_supplier_invoice 395 395 0 0.00 0 816 32 0 0
t$perf-norm:sp_cancel_supplier_order 396 396 0 0.00 0 115743 7623 0 0
t$perf-norm:sp_cancel_write_off 992 992 0 0.00 0 1118 21 0 0
t$perf-norm:sp_client_order 19932 19932 0 0.00 0 7281 39 0 0
t$perf-norm:sp_customer_reserve 1001 1001 0 0.00 0 1018 18 0 0
t$perf-norm:sp_pay_from_customer 6958 6958 0 0.00 0 12049 21 0 0
t$perf-norm:sp_pay_to_supplier 5749 5749 0 0.00 0 2058 20 0 0
t$perf-norm:sp_reserve_write_off 9929 9929 0 0.00 0 4905 24 0 0
t$perf-norm:sp_supplier_invoice 5368 5368 0 0.00 0 8319 36 0 0
t$perf-norm:sp_supplier_order 5467 5467 0 0.00 0 7276 37 0 0
t$perf-norm:srv_aggregate_perf_data 112 112 0 0.00 0 583 24 0 0
t$perf-norm:srv_fill_mon_cache_memory 74 74 0 0.00 0 802 15 0 0
t$perf-norm:srv_make_invnt_saldo 470 470 0 0.00 0 508 17 0 0
t$perf-norm:srv_make_money_saldo 954 954 0 0.00 0 9138 14 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 2 196 79 0 0
v_add_invoice_to_stock 10102 9862 240 2.37 0 5024 28 0 240
v_all_customers 36594 36594 0 0.00 0 171 4 0 0
v_all_suppliers 30024 30024 0 0.00 0 101 5 0 0
v_all_wares 39845 39845 0 0.00 0 110 4 0 0
v_cancel_adding_invoice 852 852 0 0.00 0 1940 21 0 0
v_cancel_client_order 3508 3508 0 0.00 0 4524 14 0 0
v_cancel_customer_prepayment 1350 1350 0 0.00 0 4245 15 0 0
v_cancel_customer_reserve 1430 1430 0 0.00 0 3775 15 0 0
v_cancel_payment_to_supplier 788 788 0 0.00 0 530 13 0 0
v_cancel_supplier_invoice 808 790 18 2.22 0 3916 32 0 18
v_cancel_supplier_order 792 792 0 0.00 0 1197 13 0 0
v_cancel_write_off 1984 1984 0 0.00 0 4136 34 0 0
v_max_id_clo_ord 5465 5465 0 0.00 0 430 6 0 0
v_max_id_clo_res 1001 1001 0 0.00 0 3332 14 0 0
v_max_id_ord_sup 5358 5358 0 0.00 0 3326 8 0 0
v_max_non_paid_invoice 1559 1559 0 0.00 0 17396 21 0 0
v_max_non_paid_realizn 3685 3685 0 0.00 0 1579 8 0 0
v_min_id_clo_ord 5465 5465 0 0.00 0 4670 11 0 0
v_min_id_clo_res 1001 1001 0 0.00 0 1684 11 0 0
v_min_id_ord_sup 5358 5358 0 0.00 0 5174 11 0 0
v_min_non_paid_invoice 5748 5748 0 0.00 0 5723 21 0 0
v_min_non_paid_realizn 6958 6958 0 0.00 0 20443 25 0 0
v_our_firm 9792 9792 0 0.00 0 77 5 0 0
v_reserve_write_off 19842 19842 0 0.00 0 4163 12 0 0
x_lock_depdocs_on_canc_invoice 11058 11058 0 0.00 0 17575 152 0 0
x_lock_depdocs_on_canc_sup_ord 396 396 0 0.00 0 5783 427 0 0
x_make_qstorno_1000_1200 5467 5467 0 0.00 2 30912 1318 0 0
x_make_qstorno_1200_2000 5368 5368 0 0.00 4 99697 3333 0 0
x_make_qstorno_2100_3300 458550 458550 0 0.00 0 88033 234 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 07-01-2023 04:38:17 to 07-01-2023 04:38:21.

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:29 2 8192000000 8797776992 8808681472 3987920 0.048 2 1 2 0 4087312 63616 35776
02:08:30 27875 8192000000 9714499472 10011795456 794768048 8.843 100 83 166 0 822910064 7773008 20369008
02:10:01 13557 8192000000 9757121808 10064793600 832643280 9.226 100 84 168 0 864461760 11876800 19941680
02:11:18 16943 8192000000 9790810160 10110181376 859767792 9.498 100 77 154 0 896555936 18841200 17946944
02:12:44 9711 8192000000 9868796736 10198319104 910437392 10.002 100 82 164 0 976967584 48346768 18183424
02:13:57 3755 8192000000 9915102128 10255847424 953808016 10.428 100 86 170 2 1028675328 56939488 17927824
02:15:03 3099 8192000000 9967033808 10316963840 982838640 10.712 100 89 175 0 1080692480 79626160 18227680
02:16:08 1703 8192000000 9995753872 10350587904 1009126320 10.967 100 92 177 2 1112669504 85235120 18308064
02:17:13 2011 8192000000 10006567248 10369064960 1034140480 11.208 100 88 162 0 1122872480 72520672 16211328
02:18:17 978 8192000000 10017477056 10389938176 1050166800 11.362 100 96 190 1 1141682368 72600544 18915024
02:19:18 883 8192000000 10000602208 10374094848 1059589280 11.453 100 93 184 2 1122073312 43882512 18601520
02:20:20 12563 8192000000 10033686880 10409443328 1063133248 11.486 100 95 185 0 1152415472 71648272 17633952
02:21:34 1402 8192000000 10044187424 10427789312 1069468528 11.547 100 96 189 1 1166559728 78097264 18993936
02:22:36 1242 8192000000 10056502048 10442899456 1074849200 11.598 100 94 181 0 1176823696 83931856 18042640
02:23:38 1170 8192000000 10041415456 10431193088 1081604640 11.663 100 95 191 0 1162837520 61393344 19839536
02:24:41 1120 8192000000 10047043728 10429616128 1090179680 11.744 100 96 189 2 1171196544 61033440 19983424
02:25:42 6536 8192000000 10048841600 10442887168 1091572656 11.758 100 79 159 0 1188580224 80969600 16037968
02:26:50 1215 8192000000 10085713296 10470133760 1092360048 11.765 100 97 188 0 1200868368 90058000 18450320
02:27:53 1697 8192000000 10040871328 10434068480 1090962320 11.752 100 75 139 2 1160309488 54918560 14428608
02:28:56 1274 8192000000 10069000976 10460184576 1087227584 11.716 100 92 174 2 1189562144 83277232 19057328
02:29:58 1167 8192000000 10029220048 10416087040 1083676416 11.682 100 85 159 3 1154698880 52848864 18173600
02:31:01 1100 8192000000 10022596080 10409226240 1072555632 11.576 100 79 157 3 1144391792 55993264 15842896
02:32:02 6686 8192000000 10017055632 10399604736 1063068960 11.486 100 69 134 0 1149590144 73850800 12670384
02:33:10 993 8192000000 10018660896 10406584320 1070325024 11.555 100 80 143 4 1142523904 55445488 16753392
02:34:12 1143 8192000000 9977765264 10349608960 1035830048 11.225 99 92 174 2 1098263568 44122576 18310944
02:35:14 1718 8192000000 10008813488 10391142400 1046278464 11.325 100 76 137 5 1132084928 72385424 13421040
02:36:17 1562 8192000000 9964316688 10333306880 1028821888 11.157 100 85 155 1 1084669808 40928400 14919520
02:37:19 2101 8192000000 9972185888 10350637056 1049035536 11.351 100 76 121 3 1095435824 34229728 12170560
02:38:25 3563 8192000000 9996741920 10375438336 1052261232 11.382 100 68 125 1 1118823728 53985200 12577296
02:39:36 1001 8192000000 10027183536 10406899712 1067233232 11.526 100 83 161 0 1149802272 66634352 15934688
02:40:43 10180 8192000000 10055686400 10447572992 1080964256 11.657 100 64 125 0 1176966928 83094784 12907888
02:41:57 16374 8192000000 10087986480 10488049664 1094925856 11.789 100 59 114 1 1205326192 98298416 12101920
02:43:20 5025 8192000000 10125191440 10519064576 1106262464 11.897 100 88 166 0 1242478016 118991312 17224240
02:44:30 1648 8192000000 10152368736 10548776960 1117965392 12.008 100 93 174 2 1269392400 132399872 19027136
02:45:37 3352 8192000000 10172425568 10587910144 1129745696 12.119 100 64 121 1 1288406512 145947984 12712832
02:46:45 5019 8192000000 10189173904 10601816064 1139154640 12.208 100 78 156 0 1317685808 161160624 17370544
02:47:53 1440 8192000000 10201858512 10609033216 1147554656 12.287 100 91 175 1 1318703792 152040576 19108560
02:49:00 1072 8192000000 10262893184 10661318656 1166060944 12.460 100 96 179 0 1372482016 188012544 18408528
02:50:04 1639 8192000000 10265941072 10661044224 1161104672 12.414 100 97 188 1 1373811808 192467232 20239904
02:51:11 892 8192000000 10297876640 10698788864 1173625488 12.531 100 96 181 1 1409497456 214583760 21288208
02:52:15 1045 8192000000 10319742944 10738495488 1181438704 12.604 100 96 187 1 1430624448 227098336 22087408
02:53:20 2007 8192000000 10327814624 10746875904 1177512048 12.567 100 93 174 0 1435641648 236512912 21616688
02:54:25 728 8192000000 10359278480 10758774784 1185766672 12.644 100 96 189 0 1462963936 256264976 20932288
02:55:28 1175 8192000000 10382487984 10781372416 1175695440 12.550 99 96 185 0 1488364672 291375232 21294000
02:56:36 1113 8192000000 10405181968 10813042688 1187435968 12.659 100 96 179 1 1511874544 302888976 21549600
02:57:43 1681 8192000000 10392276944 10807066624 1176649152 12.559 99 91 172 1 1494957328 297970528 20337648
02:58:48 1640 8192000000 10455475872 10873470976 1194388272 12.724 100 97 189 0 1557212032 340700720 22123040
02:59:54 1254 8192000000 10466453040 10888032256 1192244944 12.704 100 95 184 1 1573483680 359377696 21861040
03:00:57 957 8192000000 10506957104 10921943040 1198761696 12.765 100 95 190 0 1605776880 384371776 22643408
03:02:03 732 8192000000 10517800672 10961125376 1190827168 12.691 100 100 195 2 1620584416 407020976 22736272
03:03:06 1710 8192000000 10516919632 10983321600 1189105488 12.675 100 92 183 2 1620127424 408802912 22219024
03:04:12 2168 8192000000 10500061920 10949795840 1189939696 12.683 100 93 188 1 1602257568 389783920 22533952
03:05:20 1260 8192000000 10537511344 10993344512 1198072976 12.758 100 95 186 1 1636435088 416008496 22353616
03:06:24 2032 8192000000 10489566320 10968076288 1187542752 12.660 100 73 144 0 1594525824 387057440 19925632
03:07:30 14930 8192000000 10474457280 10932277248 1178775584 12.579 100 49 98 1 1584408208 392815440 12817184
03:09:30 13798 8192000000 10445271680 10892746752 1166946336 12.468 100 35 70 0 1546543312 370231120 9365856
03:10:48 21272 8192000000 10374977536 10851794944 1164155104 12.442 100 34 68 0 1498752384 326362304 8234976
03:12:12 20019 8192000000 10304038672 10768175104 1160514016 12.408 100 32 60 0 1410533296 243038144 6981136
03:13:34 13328 8192000000 10217165152 10675138560 1144002304 12.253 100 29 54 0 1318820400 168424896 6393200
03:14:55 16461 8192000000 10227645072 10677063680 1138811392 12.204 99 27 54 0 1324153056 179381696 5959968
03:16:13 45653 8192000000 10227534496 10783256576 1132382480 12.144 99 10 20 0 1323281616 188191600 2707536
03:18:03 89223 8192000000 10078831728 10568110080 1118897424 12.017 98 7 14 0 1210487968 90282256 1308288
03:20:37 35797 8192000000 10117399008 10551889920 1140002944 12.216 100 46 91 1 1198604384 48706976 9894464
03:22:18 40733 8192000000 10078662640 10540220416 1126787920 12.091 99 13 26 0 1177409360 48117392 2504048
03:24:03 83960 8192000000 10057964368 10485358592 1089695328 11.740 95 16 32 1 1119965232 27020944 3248960
03:26:28 90181 8192000000 10091313264 10523226112 1112489824 11.956 98 37 74 1 1154983552 34826080 7667648
03:29:13 82520 8192000000 10101769456 10587480064 1137524144 12.192 99 41 81 0 1210441232 65469696 7447392
03:31:38 30999 8192000000 10092352992 10550591488 1150538432 12.315 100 33 66 0 1183949552 26846672 6564448
03:33:14 12652 8192000000 10109919600 10554765312 1153283312 12.340 100 46 84 2 1206549456 44436352 8829792
03:34:29 9449 8192000000 10131516864 10556108800 1154492064 12.352 100 76 144 1 1217473104 47725968 15255072
03:35:39 20062 8192000000 10124111248 10595946496 1122108848 12.047 97 29 55 0 1225374544 96968144 6297552
03:37:14 78335 8192000000 10106206864 10581323776 1098474736 11.823 95 23 47 1 1166581616 62654832 5452048
03:39:35 41557 8192000000 10119693424 10608857088 1131585024 12.136 99 11 22 0 1217706560 83725840 2395696
03:41:17 63419 8192000000 10083831840 10527543296 1137120048 12.188 99 30 52 1 1199940240 57241520 5578672
03:43:22 39770 8192000000 10088435712 10568073216 1135874976 12.177 100 26 45 1 1188513072 46362992 6275104
03:45:17 38118 8192000000 10097629088 10539761664 1093292656 11.774 96 36 67 2 1146998256 46187168 7518432
03:47:01 31717 8192000000 10104939968 10568491008 1105790384 11.893 97 26 52 0 1156312848 45393760 5128704
03:48:35 44789 8192000000 10133655488 10595352576 1131704000 12.137 99 42 82 1 1211945696 71113088 9128608
03:50:21 47501 8192000000 10101344592 10592485376 1104695952 11.882 97 16 32 0 1186864176 78679568 3488656
03:53:03 64010 8192000000 10078350176 10533232640 1119631760 12.024 99 20 39 0 1190752960 66983456 4137744
03:55:09 28200 8192000000 10142723344 10595278848 1135568176 12.174 100 37 74 0 1216975088 73976992 7429920
03:56:38 42084 8192000000 10151599760 10608414720 1105954368 11.894 97 31 60 0 1223794528 111748960 6091200
03:59:46 53121 8192000000 10045891456 10475196416 1107725200 11.911 98 34 65 0 1129688432 15596544 6366688
04:01:41 30253 8192000000 10101894832 10569330688 1113932096 11.970 98 36 70 0 1191443200 70553648 6957456
04:03:13 71245 8192000000 10128962896 10578063360 1110867200 11.941 98 19 38 1 1191728864 76666608 4195056
04:05:30 70557 8192000000 10100682688 10598948864 1122290016 12.049 100 19 38 0 1218990576 92328352 4372208
04:07:42 41434 8192000000 10116992064 10556960768 1132115232 12.141 99 45 85 1 1200619760 59652928 8851600
04:09:27 72879 8192000000 10130336096 10594418688 1137192256 12.189 99 24 48 0 1218906048 76415072 5298720
04:11:40 58102 8192000000 10097315488 10543783936 1107313120 11.907 97 24 48 0 1158021424 45590912 5117392
04:13:39 35920 8192000000 10110184016 10564345856 1139852992 12.214 100 40 76 0 1208579472 61310128 7416352
04:15:21 39392 8192000000 10103496464 10558652416 1133184624 12.151 99 34 67 1 1202018080 62355888 6477568
04:17:26 3997 8192000000 10115640400 10531876864 1143826400 12.252 100 77 148 1 1200449232 42549168 14073664
04:18:31 63861 8192000000 10068014224 10515210240 1145971136 12.272 100 22 44 0 1175936832 25776416 4189280
04:20:36 54997 8192000000 10070533056 10524811264 1118048144 12.009 98 39 78 1 1208512464 82655744 7808576
04:22:42 70686 8192000000 10099952016 10569306112 1142377856 12.238 100 16 30 0 1208800192 63077984 3344352
04:24:56 61172 8192000000 10104540768 10572820480 1135562096 12.174 99 19 37 1 1198794480 59459008 3773376
04:27:00 58425 8192000000 10142490368 10590089216 1161175888 12.414 100 24 48 0 1251858976 85141504 5541584
04:29:01 43709 8192000000 10141497408 10599583744 1129585520 12.117 99 28 55 0 1258945312 123440208 5919584
04:30:45 73979 8192000000 10156378576 10600382464 1126934528 12.092 98 33 65 1 1216213984 82691968 6587488
04:33:59 22275 8192000000 10215243648 10649264128 1160313376 12.406 100 46 90 0 1303609712 134013664 9282672
04:35:24 50840 8192000000 10168488416 10612121600 1114391200 11.974 96 21 42 0 1271744368 152863584 4489584
04:37:15 20288 8192000000 9009980432 9259053056 153267520 1.836 14 1 2 0 171643920 18340992 35408

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 07-01-2023 04:38:23 to 07-01-2023 04:38:40.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 120 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 07-01-2023 04:38:41 to 07-01-2023 04:38:43.

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          220980
MON$OLDEST_ACTIVE               220981
MON$OLDEST_SNAPSHOT             220981
MON$NEXT_TRANSACTION            220981
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                       2088160
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             773
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2886 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2886 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2886 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2886 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 ---
:53: 
:54: mscs-test-161.budzdorov.ru    Sat Jan  7 04:37:39 2023
:55:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:56: 
:57: 
:58: mscs-test-161.budzdorov.ru    Sat Jan  7 04:37:39 2023
:59:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:60: 
:61: 
:62: mscs-test-161.budzdorov.ru    Sat Jan  7 04:37:39 2023
:63:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:64: 
--- end of diff output ---

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

07.01.23 04:38:44. 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-07 04:38:46 No severe PSQL-related problems occured
07.01.23 04:38:48. End of report.