Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 25.02.2023 22: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 15434064 kB
MemAvailable 30422332 kB
Buffers 0 kB
Cached 16294328 kB
SwapCached 3144 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-25 22:38:49
DTS_END                         2023-02-25 22:38:49.9630
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/43220
TRN_ID                          241882
ATT_ID                          665
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: 8946.35 24450 1073562
customer order: creation 173.11 328 20773
customer order: refuse 14.79 306 1775
order to supplier: creation 45.32 1264 5438
order to supplier: removal 3.14 96673 377
invoice (draft): creation 46.71 3438 5605
invoice (draft): removal 50.57 718 6068
invoice accept: apply 41.36 17146 4963
invoice accept: cancel 45.74 764 5489
customer reserve: creation 3810.60 170 457272
customer reserve: removal 4414.58 53 529749
realization accept: apply 83.23 169 9988
realization accept: cancel 87.58 106 10509
payment from customer: creation 59.27 108 7112
payment from customer: removal 5.74 75 689
payment to supplier: creation 49.00 126 5880
payment to supplier: removal 3.39 84 407
service: total inventory turnovers 4.86 1118 583
service: total monetary turnovers 6.53 61 784
service: aggregate perf. data 0.81 21729 97
service: refresh index statistics 0.03 344562 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 25-02-2023 22:40:16 to 25-02-2023 22:40:19.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2246
TEST_TIME 2 2052
TEST_TIME 3 3314
TEST_TIME 4 3383
TEST_TIME 5 3746
TEST_TIME 6 3877
TEST_TIME 7 3993
TEST_TIME 8 4315
TEST_TIME 9 4748
TEST_TIME 10 4846
TEST_TIME 11 5235
TEST_TIME 12 5567
TEST_TIME 13 5690
TEST_TIME 14 6004
TEST_TIME 15 6422
TEST_TIME 16 6602
TEST_TIME 17 7007
TEST_TIME 18 7360
TEST_TIME 19 8027
TEST_TIME 20 8310
TEST_TIME 21 8808
TEST_TIME 22 9318
TEST_TIME 23 9316
TEST_TIME 24 9477
TEST_TIME 25 9549
TEST_TIME 26 10238
TEST_TIME 27 10297
TEST_TIME 28 10214
TEST_TIME 29 10294
TEST_TIME 30 10210
TEST_TIME 31 9989
TEST_TIME 32 10009
TEST_TIME 33 9915
TEST_TIME 34 9851
TEST_TIME 35 9736
TEST_TIME 36 9630
TEST_TIME 37 9561
TEST_TIME 38 9401
TEST_TIME 39 9385
TEST_TIME 40 9349
TEST_TIME 41 9218
TEST_TIME 42 9113
TEST_TIME 43 9124
TEST_TIME 44 9031
TEST_TIME 45 9028
TEST_TIME 46 9156
TEST_TIME 47 9060
TEST_TIME 48 9002
TEST_TIME 49 8955
TEST_TIME 50 8926
TEST_TIME 51 8993
TEST_TIME 52 9033
TEST_TIME 53 9143
TEST_TIME 54 9134
TEST_TIME 55 9207
TEST_TIME 56 9228
TEST_TIME 57 9261
TEST_TIME 58 9193
TEST_TIME 59 9193
TEST_TIME 60 9346
TEST_TIME 61 9316
TEST_TIME 62 9374
TEST_TIME 63 9434
TEST_TIME 64 9295
TEST_TIME 65 9254
TEST_TIME 66 9215
TEST_TIME 67 9137
TEST_TIME 68 9136
TEST_TIME 69 9069
TEST_TIME 70 9142
TEST_TIME 71 9148
TEST_TIME 72 9124
TEST_TIME 73 9086
TEST_TIME 74 9097
TEST_TIME 75 9262
TEST_TIME 76 9257
TEST_TIME 77 9224
TEST_TIME 78 9305
TEST_TIME 79 9318
TEST_TIME 80 9336
TEST_TIME 81 9302
TEST_TIME 82 9298
TEST_TIME 83 9231
TEST_TIME 84 9182
TEST_TIME 85 9173
TEST_TIME 86 9166
TEST_TIME 87 9170
TEST_TIME 88 9117
TEST_TIME 89 9072
TEST_TIME 90 9066
TEST_TIME 91 9019
TEST_TIME 92 8968
TEST_TIME 93 8937
TEST_TIME 94 8964
TEST_TIME 95 8964
TEST_TIME 96 9036
TEST_TIME 97 9020
TEST_TIME 98 9039
TEST_TIME 99 9040
TEST_TIME 100 9085
TEST_TIME 101 9075
TEST_TIME 102 9141
TEST_TIME 103 9111
TEST_TIME 104 9185
TEST_TIME 105 9178
TEST_TIME 106 9183
TEST_TIME 107 9217
TEST_TIME 108 9147
TEST_TIME 109 9084
TEST_TIME 110 9094
TEST_TIME 111 9066
TEST_TIME 112 9033
TEST_TIME 113 8992
TEST_TIME 114 8975
TEST_TIME 115 8955
TEST_TIME 116 8970
TEST_TIME 117 8969
TEST_TIME 118 9004
TEST_TIME 119 8968
TEST_TIME 120 8951

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 25-02-2023 22:40:20 to 25-02-2023 22:40:24.

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 1496409 1496409 0 0.00 0 17737 6 0 0
doc_list_biud 1496408 1496408 0 0.00 0 29278 25 0 0
sp_add_invoice_to_stock 5071 4963 108 2.12 33 185719 17147 0 108
sp_cancel_adding_invoice 5489 5489 0 0.00 5 89274 765 0 0
sp_cancel_client_order 1775 1775 0 0.00 4 7051 306 0 0
sp_cancel_customer_reserve 529749 529749 0 0.00 0 19602 54 0 0
sp_cancel_pay_from_customer 689 689 0 0.00 2 4880 75 0 0
sp_cancel_pay_to_supplier 407 407 0 0.00 2 4532 84 0 0
sp_cancel_supplier_invoice 6076 6068 8 0.13 1 22380 719 0 8
sp_cancel_supplier_order 377 377 0 0.00 7 1121290 96673 0 0
sp_cancel_write_off 10509 10509 0 0.00 1 7660 107 0 0
sp_client_order 20773 20773 0 0.00 3 12777 328 0 0
sp_customer_reserve 457272 457272 0 0.00 0 42914 170 0 0
sp_fill_shopping_cart 31791 31791 0 0.00 1 12861 41 0 0
sp_fill_shopping_cart_clo_res 457264 457264 0 0.00 0 18328 14 0 0
sp_get_clo_for_invoice 4953 4953 0 0.00 0 31458 314 0 0
sp_kill_cost_storno 17094 17094 0 0.00 0 5706 53 0 0
sp_kill_qstorno_ret_qs2qd 1076452 1076452 0 0.00 0 29276 29 0 0
sp_kill_qty_storno 568908 568908 0 0.00 0 29278 64 0 0
sp_lock_selected_doc 20742 20742 0 0.00 0 9379 5 0 0
sp_make_cost_storno 27933 27933 0 0.00 0 14817 52 0 0
sp_multiply_rows_for_pdistr 27933 27933 0 0.00 0 8755 25 0 0
sp_multiply_rows_for_qdistr 20773 20773 0 0.00 0 12384 157 0 0
sp_pay_from_customer 7112 7112 0 0.00 3 12476 109 0 0
sp_pay_to_supplier 5880 5880 0 0.00 4 13532 127 0 0
sp_payment_common 12992 12992 0 0.00 1 12472 27 0 0
sp_qd_handle_on_cancel_clo 1775 1775 0 0.00 0 7035 285 0 0
sp_qd_handle_on_invoice_upd_sts 10442 10442 0 0.00 0 12770 323 0 0
sp_qd_handle_on_reserve_upd_sts 9988 9988 0 0.00 0 9121 57 0 0
sp_reserve_write_off 9988 9988 0 0.00 6 14829 169 0 0
sp_supplier_invoice 5605 5605 0 0.00 6 87361 3438 0 0
sp_supplier_order 5438 5438 0 0.00 4 18464 1265 0 0
srv_aggregate_perf_data 97 97 0 0.00 1960 276339 21729 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 755 123485 44841 0 0
srv_find_qd_qs_mism 1025628 1025628 0 0.00 0 17736 5 0 0
srv_make_invnt_saldo 583 583 0 0.00 24 120151 1119 0 0
srv_make_money_saldo 784 784 0 0.00 1 28080 61 0 0
srv_recalc_idx_stat 4 4 0 0.00 170142 520271 344562 0 0
t$perf-abend:sp_add_invoice_to_stock 108 108 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4963 4963 0 0.00 0 21627 183 0 0
t$perf-norm:sp_cancel_adding_invoice 411 411 0 0.00 0 8515 139 0 0
t$perf-norm:sp_cancel_client_order 1775 1775 0 0.00 0 5841 14 0 0
t$perf-norm:sp_cancel_customer_reserve 712 712 0 0.00 0 2324 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 689 689 0 0.00 0 746 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 407 407 0 0.00 0 623 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 400 400 0 0.00 0 700 16 0 0
t$perf-norm:sp_cancel_supplier_order 377 377 0 0.00 0 27756 1661 0 0
t$perf-norm:sp_cancel_write_off 1030 1030 0 0.00 0 3499 13 0 0
t$perf-norm:sp_client_order 20773 20773 0 0.00 0 9348 18 0 0
t$perf-norm:sp_customer_reserve 993 993 0 0.00 0 2989 9 0 0
t$perf-norm:sp_pay_from_customer 7112 7112 0 0.00 0 8094 10 0 0
t$perf-norm:sp_pay_to_supplier 5880 5880 0 0.00 0 7008 15 0 0
t$perf-norm:sp_reserve_write_off 9988 9988 0 0.00 0 5939 13 0 0
t$perf-norm:sp_supplier_invoice 5605 5605 0 0.00 0 9251 17 0 0
t$perf-norm:sp_supplier_order 5438 5438 0 0.00 0 14115 16 0 0
t$perf-norm:srv_aggregate_perf_data 97 97 0 0.00 0 429 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 1104 18 0 0
t$perf-norm:srv_make_invnt_saldo 583 583 0 0.00 0 2075 21 0 0
t$perf-norm:srv_make_money_saldo 784 784 0 0.00 0 3931 26 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 2 0 0
v_add_invoice_to_stock 10122 9906 216 2.13 0 6952 16 0 216
v_all_customers 37894 37894 0 0.00 0 26 1 0 0
v_all_suppliers 30324 30324 0 0.00 0 28 1 0 0
v_all_wares 41544 41544 0 0.00 0 18 1 0 0
v_cancel_adding_invoice 822 822 0 0.00 0 829 7 0 0
v_cancel_client_order 3550 3550 0 0.00 0 2146 4 0 0
v_cancel_customer_prepayment 1378 1378 0 0.00 0 4324 8 0 0
v_cancel_customer_reserve 1424 1424 0 0.00 0 832 4 0 0
v_cancel_payment_to_supplier 814 814 0 0.00 0 1491 7 0 0
v_cancel_supplier_invoice 816 800 16 1.96 0 1366 15 0 16
v_cancel_supplier_order 754 754 0 0.00 0 1326 7 0 0
v_cancel_write_off 2060 2060 0 0.00 0 7076 16 0 0
v_max_id_clo_ord 5438 5438 0 0.00 0 1208 2 0 0
v_max_id_clo_res 993 993 0 0.00 0 6840 16 0 0
v_max_id_ord_sup 5581 5581 0 0.00 0 5728 7 0 0
v_max_non_paid_invoice 1737 1737 0 0.00 0 4427 5 0 0
v_max_non_paid_realizn 3808 3808 0 0.00 0 4640 5 0 0
v_min_id_clo_ord 5438 5438 0 0.00 0 3687 6 0 0
v_min_id_clo_res 993 993 0 0.00 0 9455 29 0 0
v_min_id_ord_sup 5581 5581 0 0.00 0 8335 7 0 0
v_min_non_paid_invoice 5880 5880 0 0.00 0 6471 20 0 0
v_min_non_paid_realizn 7112 7112 0 0.00 0 7581 16 0 0
v_our_firm 10258 10258 0 0.00 0 30 1 0 0
v_reserve_write_off 19976 19976 0 0.00 0 3719 6 0 0
x_lock_depdocs_on_canc_invoice 11157 11157 0 0.00 0 17908 118 0 0
x_lock_depdocs_on_canc_sup_ord 377 377 0 0.00 0 7068 344 0 0
x_make_qstorno_1000_1200 5438 5438 0 0.00 1 18441 1193 0 0
x_make_qstorno_1200_2000 5605 5605 0 0.00 3 79014 3277 0 0
x_make_qstorno_2100_3300 457272 457272 0 0.00 0 42865 153 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 25-02-2023 22:40:25 to 25-02-2023 22:40:29.

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
20:08:53 3 8192000000 8801574464 8813707264 7731408 0.094 2 2 3 0 7872512 66144 74960
20:09:55 28433 8192000000 9888584944 10194284544 969704080 10.584 100 90 180 0 1002723664 9829312 23190272
20:11:32 16902 8192000000 9934715440 10251444224 1016079232 11.034 100 85 170 0 1046059760 8001616 21978912
20:12:56 16582 8192000000 9978652944 10308743168 1062095632 11.477 100 82 164 0 1091460096 9018832 20345632
20:14:18 5454 8192000000 10023406416 10366951424 1113221504 11.963 100 77 152 1 1141847456 10310368 18315584
20:15:27 5424 8192000000 10074125488 10431729664 1170163520 12.498 100 75 150 0 1198315072 11121344 17030208
20:16:35 3049 8192000000 10117370064 10479079424 1205687536 12.829 100 84 168 0 1238343344 14081648 18574160
20:17:40 3367 8192000000 10158114288 10535792640 1247616768 13.216 100 82 162 1 1278767552 12915520 18235264
20:18:46 2659 8192000000 10179896656 10556088320 1263706464 13.364 100 84 166 0 1296539824 15206784 17626576
20:19:50 926 8192000000 10204964992 10583232512 1287705216 13.583 100 92 183 1 1321023456 12790976 20527264
20:20:51 2475 8192000000 10201194288 10589188096 1293641280 13.637 100 70 138 1 1319611056 10711280 15258496
20:21:54 10328 8192000000 10188741984 10599223296 1301299792 13.707 100 17 34 0 1311485504 6486016 3699696
20:23:08 12140 8192000000 10193979280 10608754688 1310154496 13.787 100 15 29 0 1319806400 5588176 4063728
20:24:23 13897 8192000000 10205471168 10618486784 1320773344 13.884 100 40 80 1 1335672736 5540224 9359168
20:25:39 13853 8192000000 10208819040 10630549504 1321250976 13.888 100 17 34 1 1331457440 6382688 3823776
20:26:53 6236 8192000000 10223836560 10641350656 1318697424 13.865 99 22 44 0 1330299776 6793664 4808688
20:28:00 7896 8192000000 10210601360 10626527232 1306720544 13.756 99 20 37 1 1315929136 5109744 4098848
20:29:09 20472 8192000000 10195777568 10617176064 1279787968 13.511 97 30 59 1 1296175616 10045952 6341696
20:30:51 10285 8192000000 10157991872 10556706816 1185261776 12.639 92 26 50 0 1197020640 6294320 5464544
20:32:06 9306 8192000000 10120657392 10514587648 1160500400 12.408 93 17 35 0 1173400240 9301536 3598304
20:33:15 15506 8192000000 10151390384 10553122816 1238642048 13.134 98 14 26 1 1246931552 5081584 3207920
20:34:32 25813 8192000000 10163787552 10567286784 1249639424 13.235 98 33 66 0 1263080736 6004016 7437296
20:35:58 4376 8192000000 10176574944 10593030144 1291659888 13.619 100 15 30 0 1300379328 4814864 3904576
20:37:05 6810 8192000000 10204012112 10619928576 1310606816 13.792 100 31 62 1 1324676944 7022272 7047856
20:38:14 10815 8192000000 10216250720 10635919360 1326839552 13.939 100 20 40 0 1337230336 5623392 4767392
20:39:28 24765 8192000000 10244162288 10654494720 1334204496 14.005 100 39 76 0 1348305872 5455344 8646032
20:41:01 26341 8192000000 10266660272 10682519552 1357197456 14.212 100 46 93 1 1379147728 11588272 10362000
20:42:29 8484 8192000000 10283985040 10706071552 1376850608 14.388 100 43 84 0 1401262832 14586880 9825344
20:43:41 19598 8192000000 10299373008 10727395328 1390914912 14.514 100 36 72 1 1418393344 18540400 8938032
20:45:09 21745 8192000000 10311588336 10736500736 1385928672 14.470 99 42 84 0 1415373008 19627984 9816352
20:46:36 4912 8192000000 10336995520 10757382144 1397845728 14.576 99 56 106 0 1434207824 24175120 12186976
20:47:43 7778 8192000000 10341029824 10769309696 1415105152 14.729 100 50 96 0 1454998112 27693872 12199088
20:48:58 6922 8192000000 10357882320 10783719424 1420929216 14.781 100 53 105 0 1463984992 29654944 13400832
20:50:10 17059 8192000000 10358200720 10788614144 1417354192 14.749 99 54 107 0 1463052704 32266720 13431792
20:51:30 7116 8192000000 10354112688 10780942336 1414803504 14.727 100 55 110 0 1461051392 32261920 13985968
20:52:42 4740 8192000000 10358359984 10781278208 1415378336 14.732 100 71 139 2 1469162192 36468640 17315216
20:53:52 2437 8192000000 10348998944 10763546624 1385120432 14.462 99 67 131 2 1440783296 39682848 15980016
20:55:03 2003 8192000000 10347556352 10758176768 1383846352 14.451 100 75 150 0 1450394448 47777664 18770432
20:56:10 753 8192000000 10339517648 10739245056 1372135680 14.346 100 94 190 0 1439049952 42954560 23959712
20:57:16 1294 8192000000 10341911728 10744475648 1372198224 14.347 100 85 171 0 1442463856 48157200 22108432
20:58:20 14790 8192000000 10303062512 10724614144 1339897328 14.056 98 43 85 1 1395794672 44138672 11758672
20:59:42 3729 8192000000 10311286224 10724745216 1357159072 14.212 99 55 102 1 1412056736 41672720 13224944
21:00:49 27883 8192000000 10285840832 10726785024 1350835952 14.155 100 23 45 0 1387721952 31204160 5681840
21:02:21 28843 8192000000 10288913424 10720657408 1358813264 14.227 100 34 68 2 1401488400 33786080 8889056
21:03:51 29265 8192000000 10282837392 10717405184 1315491664 13.836 97 25 50 1 1358051104 35472528 7086912
21:05:26 27328 8192000000 10255626784 10700038144 1347719744 14.127 100 10 20 1 1367049632 17294240 2035648
21:06:57 16234 8192000000 10272056608 10711175168 1357394032 14.214 100 31 62 0 1379713584 15186384 7133168
21:08:32 31434 8192000000 10262321456 10711830528 1349297520 14.141 99 8 14 0 1362135920 11378544 1459856
21:10:04 79738 8192000000 10280054864 10714742784 1344526368 14.098 98 20 40 0 1360233360 11199056 4507936
21:12:27 68635 8192000000 10281292960 10729054208 1379614208 14.413 100 20 40 0 1391568192 7868048 4085936
21:14:41 61303 8192000000 10280494256 10728828928 1354705920 14.190 98 17 34 0 1365603360 6798480 4098960
21:17:59 68914 8192000000 10280447040 10732613632 1363365488 14.268 98 31 62 0 1381931920 10576944 7989488
21:20:12 20947 8192000000 10282441552 10740191232 1373917744 14.362 99 18 36 0 1387332048 9515680 3898624
21:21:35 6482 8192000000 10287865680 10741153792 1372619712 14.351 98 48 94 2 1393518320 9073616 11824992
21:22:43 43172 8192000000 10299424608 10750341120 1395044720 14.551 100 45 90 1 1420721280 14992928 10683632
21:24:28 36009 8192000000 10292349808 10760478720 1378386512 14.402 99 12 24 0 1392871696 11746432 2738752
21:26:14 65621 8192000000 10281286768 10747670528 1352270624 14.168 97 7 14 0 1361143376 7387520 1485232
21:28:20 30228 8192000000 10302314096 10764132352 1400311504 14.598 100 21 39 1 1414233648 8911680 5010464
21:29:52 71541 8192000000 10304455552 10770145280 1389395184 14.500 99 12 24 0 1404895312 12770112 2730016
21:32:15 78041 8192000000 10312974704 10779910144 1358907280 14.228 97 15 30 0 1385574320 22673376 3993664
21:35:02 45791 8192000000 10295498928 10746257408 1361997936 14.255 98 19 38 0 1374902928 8577680 4327312
21:36:52 32333 8192000000 10300177168 10733981696 1362432336 14.259 99 41 81 1 1383157904 11817696 8907872
21:38:28 70651 8192000000 10277584560 10724712448 1343986144 14.093 98 15 30 0 1362937504 15662176 3289184
21:40:39 103433 8192000000 10264969008 10725965824 1329637328 13.964 97 11 22 0 1353757328 21899632 2220368
21:44:12 63333 8192000000 10252409680 10710351872 1344302336 14.096 99 8 16 0 1359835776 13711888 1821552
21:46:15 92648 8192000000 10263728016 10722242560 1316960976 13.849 96 14 28 0 1330257712 10252720 3044016
21:48:49 60762 8192000000 10249104048 10701127680 1304745744 13.738 95 10 20 0 1316483808 9822320 1915744
21:50:51 63778 8192000000 10272632512 10714775552 1311170624 13.797 96 20 40 0 1329509168 13710112 4628432
21:52:55 96359 8192000000 10275892704 10738315264 1361344160 14.249 100 7 14 0 1381353408 18255968 1753280
21:55:32 41943 8192000000 10280817296 10726608896 1343180896 14.086 98 22 44 0 1364796288 16666992 4948400
21:57:15 50929 8192000000 10276806032 10724622336 1353762576 14.181 99 12 24 0 1367517984 11567904 2187504
21:59:13 26895 8192000000 10279964352 10712449024 1343105328 14.085 98 28 56 0 1357225520 7267264 6852928
22:00:49 108417 8192000000 10260256272 10711150592 1340265216 14.060 98 23 46 0 1358900192 13591232 5043744
22:03:42 44061 8192000000 10282404256 10723131392 1369978272 14.327 100 36 72 0 1387157712 9520048 7659392
22:05:27 66412 8192000000 10270974800 10731675648 1372781072 14.352 100 14 28 0 1386980032 10923184 3275776
22:07:33 50436 8192000000 10269616704 10728304640 1365536080 14.287 99 11 22 0 1375760368 7834544 2389744
22:09:40 73521 8192000000 10261626944 10718609408 1350379216 14.151 98 17 34 0 1362003840 8420288 3204336
22:12:01 78464 8192000000 10287449472 10748932096 1364105440 14.274 98 8 16 0 1373795728 8065216 1625072
22:14:20 62378 8192000000 10283835088 10738008064 1350821216 14.155 97 14 28 0 1363725504 9763888 3140400
22:16:23 70113 8192000000 10307325184 10764500992 1398824928 14.585 100 29 58 0 1419574160 14393200 6356032
22:19:29 52718 8192000000 10320078800 10761531392 1388746208 14.495 99 27 55 0 1404874368 10256736 5871424
22:21:24 123485 8192000000 10283831312 10742935552 1338897024 14.047 96 11 22 0 1356516432 15259360 2360048
22:24:28 28262 8192000000 10298881936 10746097664 1370735136 14.334 99 34 66 0 1392712512 14663584 7313792
22:25:57 94427 8192000000 10270229472 10716254208 1330630128 13.973 97 10 20 0 1347881872 15106608 2145136
22:28:49 79551 8192000000 10256100368 10722435072 1323790992 13.911 96 6 12 0 1333878512 8875648 1211872
22:31:29 36852 8192000000 10282967584 10734465024 1370666624 14.333 99 19 38 0 1383754512 8684736 4403152
22:33:08 50121 8192000000 10265044016 10717724672 1346917600 14.120 98 15 28 0 1360294128 9884528 3492000
22:34:58 82249 8192000000 10258455280 10720485376 1321972336 13.895 96 14 29 0 1336492656 11308560 3211760
22:37:23 53389 8192000000 10256341312 10701418496 1313831216 13.821 97 34 68 0 1332393248 11162912 7399120

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 25-02-2023 22:40:31 to 25-02-2023 22:40:46.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 108 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 25-02-2023 22:40:47 to 25-02-2023 22:40:49.

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          240364
MON$OLDEST_ACTIVE               242521
MON$OLDEST_SNAPSHOT             242521
MON$NEXT_TRANSACTION            242521
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                       2015424
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 ---
:608: 
:609: mscs-test-161.budzdorov.ru    Sat Feb 25 22:39:01 2023
:610:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:611: 
:612: 
:613: mscs-test-161.budzdorov.ru    Sat Feb 25 22:39:03 2023
:614:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:615: 
:616: 
:617: mscs-test-161.budzdorov.ru    Sat Feb 25 22:39:05 2023
:618:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:619: 
:620: 
:621: mscs-test-161.budzdorov.ru    Sat Feb 25 22:39:05 2023
:622:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:623: 
:624: 
:625: mscs-test-161.budzdorov.ru    Sat Feb 25 22:39:05 2023
:626:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:627: 
--- end of diff output ---

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

25.02.23 22:40:50. 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-25 22:40:53 No severe PSQL-related problems occured
25.02.23 22:40:54. End of report.