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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15431916 kB
MemAvailable 30383276 kB
Buffers 0 kB
Cached 16268680 kB
SwapCached 5780 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-01-02 19:38:36
DTS_END                         2023-01-02 19:38:36.9010
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/59372
TRN_ID                          247425
ATT_ID                          666
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: 9177.76 22710 1101331
customer order: creation 178.93 352 21471
customer order: refuse 15.74 325 1889
order to supplier: creation 47.16 1303 5659
order to supplier: removal 3.55 86935 426
invoice (draft): creation 47.04 3374 5645
invoice (draft): removal 51.80 748 6216
invoice accept: apply 42.70 17820 5124
invoice accept: cancel 45.92 694 5510
customer reserve: creation 3929.19 177 471503
customer reserve: removal 4519.74 53 542369
realization accept: apply 88.18 166 10581
realization accept: cancel 73.44 104 8813
payment from customer: creation 61.66 114 7399
payment from customer: removal 5.86 59 703
payment to supplier: creation 50.21 119 6025
payment to supplier: removal 3.66 74 439
service: total inventory turnovers 5.56 935 667
service: total monetary turnovers 6.63 24 795
service: aggregate perf. data 0.78 20721 93
service: refresh index statistics 0.03 320109 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 02-01-2023 19:39:27 to 02-01-2023 19:39:29.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 375
TEST_TIME 2 1267
TEST_TIME 3 2349
TEST_TIME 4 3433
TEST_TIME 5 3815
TEST_TIME 6 3954
TEST_TIME 7 3927
TEST_TIME 8 4725
TEST_TIME 9 5174
TEST_TIME 10 5704
TEST_TIME 11 5922
TEST_TIME 12 6179
TEST_TIME 13 6820
TEST_TIME 14 7146
TEST_TIME 15 7078
TEST_TIME 16 7324
TEST_TIME 17 7498
TEST_TIME 18 7711
TEST_TIME 19 7869
TEST_TIME 20 8090
TEST_TIME 21 8366
TEST_TIME 22 9150
TEST_TIME 23 9387
TEST_TIME 24 9712
TEST_TIME 25 9825
TEST_TIME 26 10455
TEST_TIME 27 10295
TEST_TIME 28 10215
TEST_TIME 29 10032
TEST_TIME 30 9863
TEST_TIME 31 9943
TEST_TIME 32 9922
TEST_TIME 33 9745
TEST_TIME 34 9746
TEST_TIME 35 9636
TEST_TIME 36 9668
TEST_TIME 37 9603
TEST_TIME 38 9441
TEST_TIME 39 9318
TEST_TIME 40 9259
TEST_TIME 41 9302
TEST_TIME 42 9212
TEST_TIME 43 9353
TEST_TIME 44 9188
TEST_TIME 45 9068
TEST_TIME 46 9007
TEST_TIME 47 8875
TEST_TIME 48 8973
TEST_TIME 49 8947
TEST_TIME 50 8971
TEST_TIME 51 8942
TEST_TIME 52 9042
TEST_TIME 53 8977
TEST_TIME 54 8937
TEST_TIME 55 8895
TEST_TIME 56 9100
TEST_TIME 57 9281
TEST_TIME 58 9195
TEST_TIME 59 9326
TEST_TIME 60 9348
TEST_TIME 61 9488
TEST_TIME 62 9389
TEST_TIME 63 9290
TEST_TIME 64 9200
TEST_TIME 65 9149
TEST_TIME 66 9144
TEST_TIME 67 9158
TEST_TIME 68 9155
TEST_TIME 69 9085
TEST_TIME 70 9067
TEST_TIME 71 9147
TEST_TIME 72 9094
TEST_TIME 73 9188
TEST_TIME 74 9173
TEST_TIME 75 9160
TEST_TIME 76 9196
TEST_TIME 77 9184
TEST_TIME 78 9297
TEST_TIME 79 9299
TEST_TIME 80 9323
TEST_TIME 81 9236
TEST_TIME 82 9191
TEST_TIME 83 9133
TEST_TIME 84 9120
TEST_TIME 85 9083
TEST_TIME 86 9020
TEST_TIME 87 9013
TEST_TIME 88 9094
TEST_TIME 89 9106
TEST_TIME 90 9107
TEST_TIME 91 9117
TEST_TIME 92 9134
TEST_TIME 93 9160
TEST_TIME 94 9193
TEST_TIME 95 9193
TEST_TIME 96 9204
TEST_TIME 97 9249
TEST_TIME 98 9285
TEST_TIME 99 9246
TEST_TIME 100 9339
TEST_TIME 101 9332
TEST_TIME 102 9343
TEST_TIME 103 9338
TEST_TIME 104 9257
TEST_TIME 105 9271
TEST_TIME 106 9281
TEST_TIME 107 9231
TEST_TIME 108 9192
TEST_TIME 109 9163
TEST_TIME 110 9175
TEST_TIME 111 9122
TEST_TIME 112 9176
TEST_TIME 113 9150
TEST_TIME 114 9161
TEST_TIME 115 9177
TEST_TIME 116 9177
TEST_TIME 117 9176
TEST_TIME 118 9221
TEST_TIME 119 9228
TEST_TIME 120 9178

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 02-01-2023 19:39:30 to 02-01-2023 19:39:34.

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 1538711 1538711 0 0.00 0 16650 6 0 0
doc_list_biud 1538710 1538710 0 0.00 0 22678 24 0 0
sp_add_invoice_to_stock 5249 5124 125 2.38 23 180726 17820 0 125
sp_cancel_adding_invoice 5510 5510 0 0.00 5 45566 695 0 0
sp_cancel_client_order 1889 1889 0 0.00 4 13059 326 0 0
sp_cancel_customer_reserve 542369 542369 0 0.00 0 22688 54 0 0
sp_cancel_pay_from_customer 703 703 0 0.00 3 3130 59 0 0
sp_cancel_pay_to_supplier 439 439 0 0.00 2 3755 75 0 0
sp_cancel_supplier_invoice 6228 6216 12 0.19 2 14374 748 0 12
sp_cancel_supplier_order 426 426 0 0.00 8 888473 86935 0 0
sp_cancel_write_off 8813 8813 0 0.00 1 8722 105 0 0
sp_client_order 21471 21471 0 0.00 3 47159 353 0 0
sp_customer_reserve 471503 471503 0 0.00 0 159660 178 0 0
sp_fill_shopping_cart 32767 32767 0 0.00 1 11634 41 0 0
sp_fill_shopping_cart_clo_res 471503 471503 0 0.00 0 17586 15 0 0
sp_get_clo_for_invoice 5124 5124 0 0.00 0 29962 335 0 0
sp_kill_cost_storno 15465 15465 0 0.00 0 4233 45 0 0
sp_kill_qstorno_ret_qs2qd 1100193 1100193 0 0.00 0 22676 29 0 0
sp_kill_qty_storno 580927 580927 0 0.00 0 22678 64 0 0
sp_lock_selected_doc 21789 21789 0 0.00 0 7884 5 0 0
sp_make_cost_storno 29129 29129 0 0.00 0 12427 49 0 0
sp_multiply_rows_for_pdistr 29129 29129 0 0.00 0 6764 24 0 0
sp_multiply_rows_for_qdistr 21471 21471 0 0.00 0 15376 163 0 0
sp_pay_from_customer 7399 7399 0 0.00 4 13048 114 0 0
sp_pay_to_supplier 6025 6025 0 0.00 5 15651 120 0 0
sp_payment_common 13424 13424 0 0.00 1 12352 27 0 0
sp_qd_handle_on_cancel_clo 1889 1889 0 0.00 0 13037 297 0 0
sp_qd_handle_on_invoice_upd_sts 10633 10633 0 0.00 0 17696 286 0 0
sp_qd_handle_on_reserve_upd_sts 10581 10581 0 0.00 0 20469 53 0 0
sp_reserve_write_off 10581 10581 0 0.00 6 20679 166 0 0
sp_supplier_invoice 5645 5645 0 0.00 5 107335 3374 0 0
sp_supplier_order 5659 5659 0 0.00 6 29053 1303 0 0
srv_aggregate_perf_data 93 93 0 0.00 2310 261152 20722 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1651 110687 45221 0 0
srv_find_qd_qs_mism 1052540 1052540 0 0.00 0 12263 5 0 0
srv_make_invnt_saldo 667 667 0 0.00 43 56575 936 0 0
srv_make_money_saldo 795 795 0 0.00 1 715 24 0 0
srv_recalc_idx_stat 4 4 0 0.00 246658 490295 320110 0 0
t$perf-abend:sp_add_invoice_to_stock 125 125 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5124 5124 0 0.00 0 40231 176 0 0
t$perf-norm:sp_cancel_adding_invoice 404 404 0 0.00 0 2071 54 0 0
t$perf-norm:sp_cancel_client_order 1889 1889 0 0.00 0 14649 20 0 0
t$perf-norm:sp_cancel_customer_reserve 742 742 0 0.00 0 7338 18 0 0
t$perf-norm:sp_cancel_pay_from_customer 703 703 0 0.00 0 1238 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 439 439 0 0.00 0 447 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 434 434 0 0.00 0 5155 29 0 0
t$perf-norm:sp_cancel_supplier_order 426 426 0 0.00 0 24897 1295 0 0
t$perf-norm:sp_cancel_write_off 1049 1049 0 0.00 0 5307 9 0 0
t$perf-norm:sp_client_order 21471 21471 0 0.00 0 21955 18 0 0
t$perf-norm:sp_customer_reserve 1046 1046 0 0.00 0 539 4 0 0
t$perf-norm:sp_pay_from_customer 7399 7399 0 0.00 0 12341 9 0 0
t$perf-norm:sp_pay_to_supplier 6025 6025 0 0.00 0 9120 11 0 0
t$perf-norm:sp_reserve_write_off 10581 10581 0 0.00 0 17508 16 0 0
t$perf-norm:sp_supplier_invoice 5645 5645 0 0.00 0 12297 19 0 0
t$perf-norm:sp_supplier_order 5659 5659 0 0.00 0 6261 14 0 0
t$perf-norm:srv_aggregate_perf_data 93 93 0 0.00 0 413 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 954 17 0 0
t$perf-norm:srv_make_invnt_saldo 667 667 0 0.00 0 3217 19 0 0
t$perf-norm:srv_make_money_saldo 795 795 0 0.00 0 1863 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 3 0 0
v_add_invoice_to_stock 10496 10246 250 2.38 0 7222 22 0 250
v_all_customers 38806 38806 0 0.00 0 18 1 0 0
v_all_suppliers 31248 31248 0 0.00 0 60 1 0 0
v_all_wares 42942 42942 0 0.00 0 27 1 0 0
v_cancel_adding_invoice 808 808 0 0.00 0 434 6 0 0
v_cancel_client_order 3778 3778 0 0.00 0 2184 5 0 0
v_cancel_customer_prepayment 1406 1406 0 0.00 0 1853 5 0 0
v_cancel_customer_reserve 1484 1484 0 0.00 0 1507 6 0 0
v_cancel_payment_to_supplier 878 878 0 0.00 0 238 3 0 0
v_cancel_supplier_invoice 892 868 24 2.69 0 3784 22 0 24
v_cancel_supplier_order 852 852 0 0.00 0 1318 11 0 0
v_cancel_write_off 2098 2098 0 0.00 0 1039 12 0 0
v_max_id_clo_ord 5657 5657 0 0.00 0 1091 2 0 0
v_max_id_clo_res 1046 1046 0 0.00 0 2115 18 0 0
v_max_id_ord_sup 5638 5638 0 0.00 0 6356 8 0 0
v_max_non_paid_invoice 1696 1696 0 0.00 0 5780 8 0 0
v_max_non_paid_realizn 4211 4211 0 0.00 0 5782 5 0 0
v_min_id_clo_ord 5657 5657 0 0.00 0 8835 8 0 0
v_min_id_clo_res 1046 1046 0 0.00 0 1997 9 0 0
v_min_id_ord_sup 5638 5638 0 0.00 0 9375 13 0 0
v_min_non_paid_invoice 6025 6025 0 0.00 0 10495 17 0 0
v_min_non_paid_realizn 7399 7399 0 0.00 0 9496 18 0 0
v_our_firm 10512 10512 0 0.00 0 28 1 0 0
v_reserve_write_off 21160 21160 0 0.00 0 6690 8 0 0
x_lock_depdocs_on_canc_invoice 11292 11292 0 0.00 0 19594 122 0 0
x_lock_depdocs_on_canc_sup_ord 426 426 0 0.00 0 9281 332 0 0
x_make_qstorno_1000_1200 5659 5659 0 0.00 1 29032 1228 0 0
x_make_qstorno_1200_2000 5645 5645 0 0.00 2 107294 3214 0 0
x_make_qstorno_2100_3300 471503 471503 0 0.00 0 159635 160 0 0

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

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
17:08:40 4 8192000000 8802620528 8815153152 8205424 0.100 3 1 2 0 8342832 62880 74528
17:09:42 13152 8192000000 9892968160 10202288128 982559312 10.709 100 85 170 0 1010675552 6059440 22056800
17:10:59 19741 8192000000 9943078208 10262425600 1021108672 11.083 100 89 179 0 1052656768 8634176 22913920
17:12:23 14841 8192000000 9972257024 10300239872 1051397568 11.374 100 91 182 0 1084634080 10418256 22818256
17:13:47 9199 8192000000 10009847936 10356678656 1098355392 11.822 100 77 151 0 1127850608 10895616 18599600
17:15:01 6828 8192000000 10064814256 10419240960 1149878928 12.308 100 82 165 1 1182132208 13496464 18756816
17:16:10 3150 8192000000 10107584432 10470694912 1193152032 12.713 100 81 162 2 1225326144 13197872 18976240
17:17:15 2698 8192000000 10146166560 10517549056 1234787952 13.098 100 90 181 0 1269578336 13814672 20975712
17:18:20 1696 8192000000 10180868336 10555842560 1263040048 13.358 100 86 169 1 1294377328 12372512 18964768
17:19:23 818 8192000000 10207322176 10591465472 1290818976 13.612 100 89 179 0 1323518240 13208896 19490368
17:20:25 5594 8192000000 10216863744 10607276032 1302406864 13.717 100 67 132 2 1326430576 8443696 15580016
17:21:31 7696 8192000000 10199879168 10613850112 1315527328 13.836 100 34 69 0 1329087120 6028944 7530848
17:22:41 14095 8192000000 10197342672 10616852480 1315506768 13.836 100 13 25 0 1323854880 5677520 2670592
17:23:55 17977 8192000000 10214516192 10631852032 1323841136 13.911 100 40 77 2 1338737392 6276336 8619920
17:25:14 5990 8192000000 10229921824 10638848000 1326715472 13.937 100 61 125 0 1347801872 6425648 14660752
17:26:21 7713 8192000000 10216600464 10639355904 1329834896 13.966 100 18 34 1 1339123712 5124288 4164528
17:27:29 10955 8192000000 10221026896 10643017728 1331718480 13.983 100 14 28 0 1340277696 5682768 2876448
17:28:42 9008 8192000000 10205142224 10626326528 1308729840 13.775 99 22 44 1 1319417952 5661808 5026304
17:29:54 14810 8192000000 10155327008 10569699328 1215859792 12.923 93 20 40 0 1225730672 5669920 4200960
17:31:11 7436 8192000000 10128202864 10519855104 1140139440 12.217 91 30 60 0 1152535120 5635088 6760592
17:32:22 7449 8192000000 10114233664 10498781184 1167380032 12.472 94 36 66 0 1181527056 5796288 8350736
17:33:30 47501 8192000000 10180375536 10579578880 1277003664 13.486 100 43 82 3 1291107808 4432640 9671504
17:35:28 5835 8192000000 10183778848 10595606528 1293262464 13.634 100 28 57 0 1306261392 6632048 6366880
17:36:34 9563 8192000000 10193032320 10606592000 1304984256 13.741 100 24 48 1 1316058064 5838432 5235376
17:37:47 12322 8192000000 10205897728 10622218240 1315274960 13.834 100 10 18 0 1322614000 5398464 1940576
17:39:01 87545 8192000000 10253781152 10672222208 1349984880 14.147 100 38 73 0 1366971264 8646608 8339776
17:41:34 10450 8192000000 10286128016 10703429632 1374987616 14.372 100 51 100 0 1399029184 12982832 11058736
17:43:03 6439 8192000000 10281383760 10703769600 1366701856 14.297 99 35 63 2 1385764352 11070896 7991600
17:44:16 9272 8192000000 10293997904 10721017856 1389839216 14.504 100 39 78 0 1413635392 14208864 9587312
17:45:32 16590 8192000000 10319562768 10739146752 1397020080 14.568 100 55 107 1 1427192688 17607488 12565120
17:46:51 12004 8192000000 10323928544 10750402560 1404029968 14.631 100 48 96 0 1437794400 21482080 12282352
17:48:10 6876 8192000000 10335543888 10763481088 1406480400 14.653 100 51 102 1 1444564080 25786208 12297472
17:49:18 1650 8192000000 10348111248 10772398080 1414844720 14.727 100 67 128 0 1458121968 27707680 15569568
17:50:23 9693 8192000000 10355311280 10781253632 1411776000 14.700 100 50 99 1 1458331872 34707904 11847968
17:51:36 5371 8192000000 10340625408 10758774784 1393348448 14.536 100 69 135 0 1445926496 36116640 16461408
17:52:47 2636 8192000000 10326271120 10745450496 1391678960 14.521 100 59 115 0 1435793328 30499568 13614800
17:53:53 17231 8192000000 10298800736 10712977408 1329338864 13.961 97 58 116 0 1379709888 35764688 14606336
17:55:14 8878 8192000000 10318135648 10738274304 1378134640 14.400 100 56 112 0 1428272336 36134064 14003632
17:56:29 3180 8192000000 10341506208 10752278528 1387311984 14.482 100 70 137 1 1446018320 40462992 18243344
17:57:39 15736 8192000000 10322991792 10741841920 1372937392 14.353 99 34 66 0 1422673632 40213920 9522320
17:59:05 16718 8192000000 10305577360 10728914944 1339713840 14.055 97 39 77 0 1389952176 39472912 10765424
18:00:30 12047 8192000000 10276810256 10700218368 1341190928 14.068 100 34 68 1 1387371024 37348928 8831168
18:01:52 34738 8192000000 10281234400 10709397504 1341886656 14.074 99 28 56 0 1385526144 36289104 7350384
18:03:29 22173 8192000000 10262814672 10691792896 1335194304 14.014 100 24 47 0 1371015152 29852432 5968416
18:04:54 85819 8192000000 10225470080 10656829440 1297736688 13.675 97 34 66 0 1312485760 7299712 7449360
18:07:21 46110 8192000000 10249331488 10681376768 1325816160 13.929 99 30 59 0 1340236592 7866192 6554240
18:09:09 29557 8192000000 10269138256 10708582400 1359602096 14.234 100 45 87 0 1385333648 16112432 9619120
18:11:26 92349 8192000000 10279511104 10734223360 1366578432 14.296 99 22 42 0 1387677872 16168912 4930528
18:13:59 24503 8192000000 10279371968 10729066496 1377923232 14.398 100 16 33 1 1393167808 11656432 3588144
18:15:24 71111 8192000000 10279328752 10734821376 1374396480 14.366 99 18 36 0 1390311392 11320656 4594256
18:17:35 56351 8192000000 10309748544 10762985472 1399623824 14.592 100 31 61 0 1422006832 15355136 7027872
18:19:45 13753 8192000000 10308720080 10757103616 1402898176 14.621 100 35 67 0 1423898592 13254528 7745888
18:21:33 58425 8192000000 10293588304 10756378624 1396150784 14.561 100 3 6 0 1409620016 12910064 559168
18:23:32 25307 8192000000 10315283168 10759430144 1395119568 14.552 99 40 79 1 1413297936 9872560 8305808
18:24:57 57029 8192000000 10307258464 10769903616 1407192016 14.659 100 14 28 0 1424971568 14481920 3297632
18:26:55 64795 8192000000 10306872368 10762690560 1400226832 14.597 99 13 25 0 1409441856 6873600 2341424
18:29:01 26265 8192000000 10332837200 10780958720 1418514848 14.760 100 29 58 0 1435401328 10088704 6797776
18:30:29 37901 8192000000 10305524256 10765434880 1371532480 14.341 97 16 30 0 1385512112 10491040 3488592
18:32:07 49102 8192000000 10321542096 10777845760 1383347248 14.446 97 26 52 0 1406605456 17076080 6182128
18:34:19 101984 8192000000 10301120080 10758164480 1356839552 14.209 96 16 32 0 1380148160 19245904 4062704
18:37:09 15288 8192000000 10301371024 10750390272 1381498832 14.430 100 45 91 0 1405614384 14441728 9673824
18:38:25 55992 8192000000 10299474256 10752077824 1364657104 14.279 98 28 56 0 1385047072 14448320 5941648
18:40:26 107611 8192000000 10238949344 10677682176 1281682672 13.528 92 30 60 1 1295609248 7421552 6505024
18:43:16 39723 8192000000 10268890240 10712854528 1359387168 14.232 100 42 85 2 1381540848 12710112 9443568
18:46:07 49889 8192000000 10252800816 10702213120 1339986560 14.057 98 21 38 0 1354119360 9846816 4285984
18:48:06 26605 8192000000 10291533216 10731802624 1376206032 14.383 100 39 76 0 1395243872 10352336 8685504
18:49:40 80763 8192000000 10281920080 10726154240 1343228224 14.087 98 8 14 0 1360913824 16180960 1504640
18:52:04 56826 8192000000 10276963744 10722443264 1319138192 13.869 96 18 36 0 1341629392 18596080 3895120
18:54:03 85291 8192000000 10268084928 10706014208 1306725152 13.756 96 11 23 1 1320219600 11382848 2111600
18:56:32 110686 8192000000 10272772784 10726715392 1337515856 14.035 98 8 16 0 1354294944 14862048 1917040
18:59:24 101374 8192000000 10258514672 10711883776 1363847296 14.272 100 15 30 0 1374131040 7080128 3203616
19:02:06 46216 8192000000 10284168256 10737254400 1382417072 14.438 100 29 57 0 1402666160 13595056 6654032
19:04:01 68090 8192000000 10293475264 10745364480 1389350720 14.500 100 14 26 0 1406329376 13895872 3082784
19:06:14 40151 8192000000 10278420448 10735710208 1355009840 14.193 98 18 35 0 1372628464 12964720 4653904
19:07:55 64291 8192000000 10281860304 10732392448 1353917600 14.183 98 13 27 0 1372559520 15930816 2711104
19:10:00 74434 8192000000 10303105600 10756247552 1389425792 14.501 99 14 28 0 1404108128 11635776 3046560
19:12:19 42191 8192000000 10307225696 10755153920 1378272800 14.401 98 24 46 2 1390974240 7833504 4867936
19:14:12 31794 8192000000 10319066320 10762047488 1398834720 14.585 100 48 95 1 1421274000 11968432 10470848
19:15:51 89315 8192000000 10295243312 10741452800 1310418656 13.790 93 12 25 0 1333123104 19901040 2803408
19:18:23 76341 8192000000 10301175216 10747559936 1357076608 14.211 97 15 30 0 1372389152 12188800 3123744
19:20:43 31388 8192000000 10314575744 10761662464 1392479568 14.528 100 26 52 0 1413747360 15384560 5883232
19:22:14 104019 8192000000 10280311200 10731433984 1344558784 14.098 96 27 54 0 1362515712 12179296 5777632
19:27:19 60848 8192000000 10260884160 10708574208 1308204112 13.770 95 17 34 0 1330009584 17914976 3890496
19:29:24 51291 8192000000 10258142704 10723057664 1333948704 14.003 98 11 22 0 1353773728 17343056 2481968
19:31:17 42669 8192000000 10258784784 10704551936 1330690608 13.973 98 17 34 0 1344967600 10155296 4121696
19:33:09 33576 8192000000 10262315056 10698969088 1330667360 13.973 98 24 48 0 1346432720 10675648 5089712
19:34:50 67690 8192000000 10267482192 10707419136 1331475488 13.980 98 21 42 0 1347048256 10525456 5047312
19:37:06 54895 8192000000 10259689568 10700140544 1323593568 13.909 98 10 20 0 1337564640 12335376 1635696

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 02-01-2023 19:39:41 to 02-01-2023 19:39:56.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 125 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 02-01-2023 19:39:57 to 02-01-2023 19:39:59.

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          246753
MON$OLDEST_ACTIVE               248753
MON$OLDEST_SNAPSHOT             248753
MON$NEXT_TRANSACTION            248753
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                       2016064
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.33650 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33650 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33650 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33650 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 ---
:1573: 
:1574: mscs-test-161.budzdorov.ru    Mon Jan  2 19:38:44 2023
:1575:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1576: 
:1577: 
:1578: mscs-test-161.budzdorov.ru    Mon Jan  2 19:38:44 2023
:1579:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1580: 
--- end of diff output ---

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

02.01.23 19:40:00. 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-01-02 19:40:03 No severe PSQL-related problems occured
02.01.23 19:40:04. End of report.