Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 15.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 15440420 kB
MemAvailable 30392140 kB
Buffers 0 kB
Cached 16255988 kB
SwapCached 2216 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-15 19:38:36
DTS_END                         2023-01-15 19:38:36.9680
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/33190
TRN_ID                          232471
ATT_ID                          679
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: 9101.14 19625 1092137
customer order: creation 164.50 367 19740
customer order: refuse 13.98 334 1678
order to supplier: creation 44.27 1267 5312
order to supplier: removal 3.61 103758 433
invoice (draft): creation 43.19 3577 5183
invoice (draft): removal 54.16 806 6499
invoice accept: apply 39.48 18613 4738
invoice accept: cancel 48.57 840 5828
customer reserve: creation 3643.74 185 437249
customer reserve: removal 4752.04 60 570245
realization accept: apply 80.82 185 9698
realization accept: cancel 90.82 121 10898
payment from customer: creation 55.19 107 6623
payment from customer: removal 5.54 84 665
payment to supplier: creation 46.56 123 5587
payment to supplier: removal 3.34 82 401
service: total inventory turnovers 4.43 1222 532
service: total monetary turnovers 6.05 28 726
service: aggregate perf. data 0.82 22559 98
service: refresh index statistics 0.03 238185 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 15-01-2023 19:39:46 to 15-01-2023 19:39:48.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1924
TEST_TIME 2 3135
TEST_TIME 3 3340
TEST_TIME 4 3760
TEST_TIME 5 4594
TEST_TIME 6 4517
TEST_TIME 7 4429
TEST_TIME 8 4411
TEST_TIME 9 4314
TEST_TIME 10 5294
TEST_TIME 11 5630
TEST_TIME 12 6061
TEST_TIME 13 6147
TEST_TIME 14 6515
TEST_TIME 15 6562
TEST_TIME 16 6875
TEST_TIME 17 7270
TEST_TIME 18 7867
TEST_TIME 19 7853
TEST_TIME 20 7916
TEST_TIME 21 8386
TEST_TIME 22 8496
TEST_TIME 23 8722
TEST_TIME 24 9377
TEST_TIME 25 9253
TEST_TIME 26 9320
TEST_TIME 27 10041
TEST_TIME 28 9915
TEST_TIME 29 10268
TEST_TIME 30 10264
TEST_TIME 31 10267
TEST_TIME 32 10207
TEST_TIME 33 10055
TEST_TIME 34 9968
TEST_TIME 35 9894
TEST_TIME 36 9886
TEST_TIME 37 9836
TEST_TIME 38 9717
TEST_TIME 39 9732
TEST_TIME 40 9608
TEST_TIME 41 9488
TEST_TIME 42 9365
TEST_TIME 43 9401
TEST_TIME 44 9426
TEST_TIME 45 9305
TEST_TIME 46 9278
TEST_TIME 47 9221
TEST_TIME 48 9111
TEST_TIME 49 9079
TEST_TIME 50 9098
TEST_TIME 51 9020
TEST_TIME 52 8924
TEST_TIME 53 8791
TEST_TIME 54 8874
TEST_TIME 55 8891
TEST_TIME 56 8899
TEST_TIME 57 8874
TEST_TIME 58 8934
TEST_TIME 59 9013
TEST_TIME 60 9021
TEST_TIME 61 9068
TEST_TIME 62 9128
TEST_TIME 63 9158
TEST_TIME 64 9203
TEST_TIME 65 9205
TEST_TIME 66 9232
TEST_TIME 67 9314
TEST_TIME 68 9343
TEST_TIME 69 9304
TEST_TIME 70 9232
TEST_TIME 71 9139
TEST_TIME 72 9122
TEST_TIME 73 9248
TEST_TIME 74 9194
TEST_TIME 75 9184
TEST_TIME 76 9133
TEST_TIME 77 9105
TEST_TIME 78 9109
TEST_TIME 79 9086
TEST_TIME 80 9045
TEST_TIME 81 8994
TEST_TIME 82 9000
TEST_TIME 83 8925
TEST_TIME 84 9007
TEST_TIME 85 9000
TEST_TIME 86 9027
TEST_TIME 87 9031
TEST_TIME 88 9021
TEST_TIME 89 9032
TEST_TIME 90 9097
TEST_TIME 91 9099
TEST_TIME 92 9121
TEST_TIME 93 9173
TEST_TIME 94 9137
TEST_TIME 95 9197
TEST_TIME 96 9175
TEST_TIME 97 9244
TEST_TIME 98 9248
TEST_TIME 99 9179
TEST_TIME 100 9148
TEST_TIME 101 9120
TEST_TIME 102 9106
TEST_TIME 103 9052
TEST_TIME 104 8996
TEST_TIME 105 8983
TEST_TIME 106 8934
TEST_TIME 107 8959
TEST_TIME 108 8931
TEST_TIME 109 8950
TEST_TIME 110 8988
TEST_TIME 111 8994
TEST_TIME 112 9021
TEST_TIME 113 9014
TEST_TIME 114 9048
TEST_TIME 115 9073
TEST_TIME 116 9054
TEST_TIME 117 9099
TEST_TIME 118 9113
TEST_TIME 119 9110
TEST_TIME 120 9102

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 15-01-2023 19:39:49 to 15-01-2023 19:39:53.

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 1499253 1499253 0 0.00 0 24190 7 0 0
doc_list_biud 1499253 1499253 0 0.00 0 59769 30 0 0
sp_add_invoice_to_stock 4840 4738 102 2.10 40 219597 18614 0 102
sp_cancel_adding_invoice 5828 5828 0 0.00 4 95769 841 0 0
sp_cancel_client_order 1678 1678 0 0.00 4 9336 334 0 0
sp_cancel_customer_reserve 570245 570245 0 0.00 1 59770 61 0 0
sp_cancel_pay_from_customer 665 665 0 0.00 3 3444 84 0 0
sp_cancel_pay_to_supplier 401 401 0 0.00 3 6920 82 0 0
sp_cancel_supplier_invoice 6511 6499 12 0.18 1 18871 807 0 12
sp_cancel_supplier_order 433 433 0 0.00 5 1004787 103758 0 0
sp_cancel_write_off 10898 10898 0 0.00 1 21489 122 0 0
sp_client_order 19740 19740 0 0.00 3 20825 368 0 0
sp_customer_reserve 437249 437249 0 0.00 0 85623 185 0 0
sp_fill_shopping_cart 30224 30224 0 0.00 1 28339 50 0 0
sp_fill_shopping_cart_clo_res 437247 437247 0 0.00 0 15746 15 0 0
sp_get_clo_for_invoice 4736 4736 0 0.00 0 16078 312 0 0
sp_kill_cost_storno 17792 17792 0 0.00 0 24184 59 0 0
sp_kill_qstorno_ret_qs2qd 1158320 1158320 0 0.00 0 59765 33 0 0
sp_kill_qty_storno 610015 610015 0 0.00 0 59768 72 0 0
sp_lock_selected_doc 19961 19961 0 0.00 0 9468 5 0 0
sp_make_cost_storno 26644 26644 0 0.00 0 12062 51 0 0
sp_multiply_rows_for_pdistr 26644 26644 0 0.00 0 10655 26 0 0
sp_multiply_rows_for_qdistr 19740 19740 0 0.00 0 13021 176 0 0
sp_pay_from_customer 6623 6623 0 0.00 4 14986 107 0 0
sp_pay_to_supplier 5587 5587 0 0.00 5 10672 123 0 0
sp_payment_common 12210 12210 0 0.00 2 5999 25 0 0
sp_qd_handle_on_cancel_clo 1678 1678 0 0.00 0 9324 306 0 0
sp_qd_handle_on_invoice_upd_sts 10564 10564 0 0.00 0 21907 368 0 0
sp_qd_handle_on_reserve_upd_sts 9698 9698 0 0.00 0 13484 76 0 0
sp_reserve_write_off 9698 9698 0 0.00 5 13495 185 0 0
sp_supplier_invoice 5183 5183 0 0.00 6 96448 3578 0 0
sp_supplier_order 5312 5312 0 0.00 5 43290 1268 0 0
srv_aggregate_perf_data 98 98 0 0.00 2475 176781 22560 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 2055 145048 43957 0 0
srv_find_qd_qs_mism 1048001 1048001 0 0.00 0 16397 6 0 0
srv_make_invnt_saldo 532 532 0 0.00 47 55203 1222 0 0
srv_make_money_saldo 726 726 0 0.00 1 671 28 0 0
srv_recalc_idx_stat 4 4 0 0.00 153234 410262 238185 0 0
t$perf-abend:sp_add_invoice_to_stock 102 102 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 4738 4738 0 0.00 0 19010 199 0 0
t$perf-norm:sp_cancel_adding_invoice 371 371 0 0.00 0 5931 137 0 0
t$perf-norm:sp_cancel_client_order 1678 1678 0 0.00 0 15252 26 0 0
t$perf-norm:sp_cancel_customer_reserve 654 654 0 0.00 0 4498 28 0 0
t$perf-norm:sp_cancel_pay_from_customer 665 665 0 0.00 0 4429 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 401 401 0 0.00 0 2014 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 391 391 0 0.00 0 4547 44 0 0
t$perf-norm:sp_cancel_supplier_order 433 433 0 0.00 0 20806 1660 0 0
t$perf-norm:sp_cancel_write_off 934 934 0 0.00 0 2678 12 0 0
t$perf-norm:sp_client_order 19740 19740 0 0.00 0 10638 17 0 0
t$perf-norm:sp_customer_reserve 930 930 0 0.00 0 280 2 0 0
t$perf-norm:sp_pay_from_customer 6623 6623 0 0.00 0 4630 10 0 0
t$perf-norm:sp_pay_to_supplier 5587 5587 0 0.00 0 7893 11 0 0
t$perf-norm:sp_reserve_write_off 9698 9698 0 0.00 0 5105 13 0 0
t$perf-norm:sp_supplier_invoice 5183 5183 0 0.00 0 2901 15 0 0
t$perf-norm:sp_supplier_order 5312 5312 0 0.00 0 4588 19 0 0
t$perf-norm:srv_aggregate_perf_data 98 98 0 0.00 0 1961 28 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 918 42 0 0
t$perf-norm:srv_make_invnt_saldo 532 532 0 0.00 0 2606 27 0 0
t$perf-norm:srv_make_money_saldo 726 726 0 0.00 0 5451 30 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 7 4 0 0
v_add_invoice_to_stock 9676 9472 204 2.10 0 7758 25 0 204
v_all_customers 35956 35956 0 0.00 0 21 1 0 0
v_all_suppliers 28834 28834 0 0.00 0 198 1 0 0
v_all_wares 39480 39480 0 0.00 0 49 1 0 0
v_cancel_adding_invoice 742 742 0 0.00 0 5498 13 0 0
v_cancel_client_order 3356 3356 0 0.00 0 3077 8 0 0
v_cancel_customer_prepayment 1330 1330 0 0.00 0 3436 13 0 0
v_cancel_customer_reserve 1308 1308 0 0.00 0 2530 7 0 0
v_cancel_payment_to_supplier 802 802 0 0.00 0 6772 19 0 0
v_cancel_supplier_invoice 806 782 24 2.97 0 11370 30 0 24
v_cancel_supplier_order 866 866 0 0.00 0 1316 6 0 0
v_cancel_write_off 1868 1868 0 0.00 0 2122 15 0 0
v_max_id_clo_ord 5308 5308 0 0.00 0 3902 2 0 0
v_max_id_clo_res 930 930 0 0.00 0 2465 20 0 0
v_max_id_ord_sup 5175 5175 0 0.00 0 6470 9 0 0
v_max_non_paid_invoice 1652 1652 0 0.00 0 4036 5 0 0
v_max_non_paid_realizn 3589 3589 0 0.00 0 1920 4 0 0
v_min_id_clo_ord 5308 5308 0 0.00 0 6511 16 0 0
v_min_id_clo_res 930 930 0 0.00 0 6897 20 0 0
v_min_id_ord_sup 5175 5175 0 0.00 0 7163 13 0 0
v_min_non_paid_invoice 5587 5587 0 0.00 0 4054 16 0 0
v_min_non_paid_realizn 6623 6623 0 0.00 0 5995 20 0 0
v_our_firm 9592 9592 0 0.00 0 19 1 0 0
v_reserve_write_off 19396 19396 0 0.00 0 11427 6 0 0
x_lock_depdocs_on_canc_invoice 11936 11936 0 0.00 0 32923 122 0 0
x_lock_depdocs_on_canc_sup_ord 433 433 0 0.00 0 15763 377 0 0
x_make_qstorno_1000_1200 5312 5312 0 0.00 1 43279 1176 0 0
x_make_qstorno_1200_2000 5183 5183 0 0.00 3 89261 3384 0 0
x_make_qstorno_2100_3300 437249 437249 0 0.00 0 85622 168 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 15-01-2023 19:39:54 to 15-01-2023 19:39:58.

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 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:41 21131 8192000000 9891600624 10195652608 972070160 10.607 100 93 186 0 1002555168 5561136 24923872
17:11:10 22454 8192000000 9938519600 10256822272 1017941568 11.052 100 90 180 0 1049406640 7935088 23529984
17:12:47 9152 8192000000 9973791728 10305134592 1059231200 11.449 100 83 166 0 1089478336 9674736 20572400
17:14:07 9754 8192000000 10043118304 10392158208 1129758800 12.119 100 76 152 0 1158395200 10449856 18186544
17:15:22 3795 8192000000 10096807872 10460999680 1182978656 12.618 100 71 143 1 1211769872 12913248 15877968
17:16:27 3504 8192000000 10138829200 10508115968 1228780064 13.043 100 85 164 1 1259056352 12102832 18173456
17:17:33 1866 8192000000 10162097488 10540478464 1251756160 13.254 100 82 161 0 1284320832 14994736 17569936
17:18:36 1873 8192000000 10181997696 10562043904 1264117008 13.368 100 89 179 0 1298536624 14682032 19737584
17:19:41 898 8192000000 10204733856 10590031872 1295049360 13.650 100 83 167 1 1325613600 11782912 18781328
17:20:46 1076 8192000000 10222159088 10607112192 1300899696 13.703 100 88 174 3 1332612240 12554800 19157744
17:21:48 6926 8192000000 10192497456 10600464384 1308455424 13.772 100 32 64 0 1321053152 5498464 7099264
17:22:56 7044 8192000000 10195680928 10614046720 1317114672 13.851 100 27 56 0 1327752976 4845424 5792880
17:24:08 8375 8192000000 10211633840 10628812800 1326934544 13.939 100 19 33 0 1336303280 5443440 3925296
17:25:16 7629 8192000000 10216460768 10637987840 1334354448 14.006 100 12 23 0 1341698672 5128880 2215344
17:26:26 23878 8192000000 10221721568 10645835776 1334616384 14.009 100 33 67 1 1348392048 6346768 7428896
17:27:51 5075 8192000000 10228513296 10650705920 1336220816 14.023 100 23 45 0 1346861440 5602208 5038416
17:29:01 8195 8192000000 10201550928 10623758336 1277982352 13.495 96 12 22 0 1285263424 5252240 2028832
17:30:13 65293 8192000000 10146006272 10554142720 1163733040 12.438 90 23 43 0 1173279488 4286944 5259504
17:32:19 4675 8192000000 10130315216 10524569600 1208275200 12.853 96 30 58 0 1221423696 6543712 6604784
17:33:27 6142 8192000000 10156033280 10553405440 1235695280 13.107 98 16 31 2 1248531200 9219120 3616800
17:34:35 13104 8192000000 10166557744 10574450688 1272042736 13.440 100 22 44 0 1294900016 18094000 4763280
17:35:49 37760 8192000000 10176314016 10586030080 1284029808 13.550 100 34 67 1 1297554192 5491952 8032432
17:37:27 7036 8192000000 10185989152 10600730624 1296728240 13.665 100 20 40 0 1306531568 4870352 4932976
17:38:36 40387 8192000000 10214755232 10624344064 1311696464 13.801 99 29 57 1 1322678832 4540096 6442272
17:40:22 13487 8192000000 10252470624 10663116800 1341386032 14.070 100 44 87 0 1361725168 10268192 10070944
17:41:38 64260 8192000000 10273125664 10693451776 1357660080 14.216 100 49 96 2 1389194976 20381616 11153280
17:43:46 16643 8192000000 10276018912 10700460032 1371097712 14.337 100 40 78 0 1393071984 12297040 9677232
17:45:08 8830 8192000000 10295828272 10719129600 1384097360 14.453 100 47 93 0 1408851792 13949584 10804848
17:46:23 8511 8192000000 10323702704 10743541760 1406489632 14.653 100 54 108 2 1440794976 21570576 12734768
17:47:44 9792 8192000000 10342167024 10762694656 1406794208 14.655 100 56 110 0 1449771632 29275456 13701968
17:48:57 2053 8192000000 10355630912 10781020160 1420337600 14.776 100 59 115 2 1463665696 28135968 15192128
17:50:02 5642 8192000000 10345668128 10768990208 1398303200 14.580 98 68 136 1 1442588384 27781664 16503520
17:51:14 3715 8192000000 10361128128 10782011392 1408409424 14.670 99 62 123 0 1458648368 34938960 15299984
17:52:23 4601 8192000000 10357887904 10773471232 1413598192 14.716 100 70 139 0 1467359152 37038880 16722080
17:53:33 5573 8192000000 10343593168 10759626752 1379441152 14.412 98 61 122 0 1435395248 40633968 15320128
17:54:50 2299 8192000000 10348602128 10761916416 1391402016 14.518 100 70 139 0 1452828144 43228656 18197472
17:56:00 4533 8192000000 10332506544 10748907520 1381815664 14.433 100 60 120 0 1437683472 40530064 15337744
17:57:09 15684 8192000000 10321787216 10752647168 1386797408 14.477 100 34 68 0 1434711200 38545792 9368000
17:58:28 5415 8192000000 10337129072 10759565312 1382876208 14.442 100 45 86 1 1440943968 46338800 11728960
17:59:44 8089 8192000000 10339970016 10769031168 1397864832 14.576 100 45 90 0 1447420800 37812208 11743760
18:01:08 9081 8192000000 10339747296 10762371072 1384802896 14.459 100 44 85 1 1444569120 48323312 11442912
18:02:21 10119 8192000000 10312603696 10751229952 1380794144 14.424 100 34 68 0 1424934560 34902832 9237584
18:03:38 17704 8192000000 10316546000 10749349888 1364539024 14.278 99 28 56 1 1412701936 40042528 8120384
18:05:10 5165 8192000000 10313737680 10753900544 1371083792 14.337 100 30 60 1 1424522480 45108944 8329744
18:06:23 47315 8192000000 10283077952 10715951104 1335139600 14.014 99 26 51 0 1375366240 33875376 6351264
18:08:13 8312 8192000000 10266999584 10707243008 1358096224 14.220 100 25 50 0 1379105744 14962768 6046752
18:09:30 57518 8192000000 10268213440 10716049408 1350573152 14.153 99 22 43 1 1371774912 16121808 5079952
18:11:30 55535 8192000000 10273257648 10716626944 1362415168 14.259 100 14 29 0 1381961152 16258912 3287072
18:13:35 13898 8192000000 10294867072 10730725376 1378210128 14.401 100 36 71 0 1397847584 11638016 7999440
18:14:56 58701 8192000000 10275764352 10730180608 1359901792 14.236 99 9 19 0 1376974496 14908528 2164176
18:17:32 57812 8192000000 10289308432 10729213952 1364940592 14.282 99 19 38 0 1377972112 8858992 4172528
18:19:32 55102 8192000000 10292935744 10739277824 1385822816 14.469 100 24 49 1 1401505696 10199392 5483488
18:21:31 69105 8192000000 10297954176 10753036288 1388823824 14.495 100 18 36 0 1409528992 15769024 4936144
18:23:44 106939 8192000000 10271665936 10724864000 1365848336 14.290 98 25 48 1 1385444352 14102080 5493936
18:26:32 16274 8192000000 10280861344 10732331008 1376556432 14.386 99 26 48 0 1390521584 8768864 5196288
18:27:49 57622 8192000000 10279061728 10736799744 1352219648 14.167 97 16 32 0 1366764704 10967424 3577632
18:29:47 76662 8192000000 10283458416 10733445120 1360462112 14.242 98 11 22 0 1371643552 8868240 2313200
18:32:04 69729 8192000000 10290506672 10767024128 1395841440 14.558 100 14 28 0 1417577488 18466448 3269600
18:34:15 57532 8192000000 10298794992 10761170944 1394310464 14.544 100 7 14 0 1406947968 11085120 1552384
18:36:21 95318 8192000000 10293764448 10762256384 1361989120 14.255 97 11 22 0 1377940784 13498880 2452784
18:38:56 87085 8192000000 10288042704 10743496704 1351938384 14.165 96 10 18 0 1363222816 9111072 2173360
18:41:24 94532 8192000000 10292797648 10752675840 1344291376 14.096 96 20 40 0 1363969376 15017760 4660240
18:44:07 53365 8192000000 10316054896 10772185088 1392053488 14.524 100 30 60 0 1424328816 25908144 6367184
18:46:04 88960 8192000000 10290864656 10725203968 1307471696 13.763 94 16 33 0 1327340304 16120576 3748032
18:48:34 145044 8192000000 10294814992 10735947776 1366216240 14.293 99 40 79 1 1391937248 16788016 8932992
18:52:05 33459 8192000000 10270621488 10738999296 1341275840 14.069 98 23 46 1 1370676128 24240176 5160112
18:53:47 43954 8192000000 10261943312 10711080960 1349961872 14.147 99 11 21 0 1362032432 9970144 2100416
18:55:31 35914 8192000000 10254752432 10704736256 1330923616 13.975 98 17 34 0 1345331776 10161936 4246224
18:58:28 54229 8192000000 10250535152 10695729152 1348981408 14.138 99 28 52 0 1360777088 6419504 5376176
19:00:23 69105 8192000000 10267186080 10722299904 1346232720 14.114 98 22 42 0 1367735856 16006016 5497120
19:02:32 62009 8192000000 10263789232 10730385408 1311826880 13.803 96 12 24 0 1337972224 23056448 3088896
19:04:52 15038 8192000000 10271870336 10703360000 1343317024 14.087 99 34 69 0 1359181536 7668016 8196496
19:06:16 32908 8192000000 10260473216 10695311360 1336910912 14.030 99 23 46 0 1353183424 11143632 5128880
19:08:21 62968 8192000000 10269887856 10709778432 1353449648 14.179 99 23 46 0 1367653792 9547984 4656160
19:10:25 45732 8192000000 10283321504 10725937152 1352354032 14.169 99 39 78 0 1378031632 17077696 8599904
19:12:27 13881 8192000000 10309179344 10739699712 1379417984 14.411 100 45 90 0 1402379408 13036496 9924928
19:13:43 68984 8192000000 10319556320 10753617920 1356506400 14.206 98 38 74 1 1386025152 21708528 7810224
19:15:56 108029 8192000000 10293907056 10744881152 1375011824 14.372 99 20 40 0 1394560384 15182880 4365680
19:19:02 48502 8192000000 10276586560 10731913216 1349750784 14.145 97 16 32 0 1362567232 10008560 2807888
19:20:51 48248 8192000000 10264055152 10724405248 1340768176 14.064 97 5 10 0 1353027056 11602624 656256
19:22:41 36042 8192000000 10281978496 10739634176 1390319296 14.509 100 19 39 0 1402083360 7334272 4429792
19:24:20 92521 8192000000 10286035424 10753830912 1375924224 14.380 99 16 30 0 1392977680 12893872 4159584
19:26:58 53426 8192000000 10310942512 10762600448 1394286960 14.544 99 21 42 0 1411274176 11827408 5159808
19:29:06 73135 8192000000 10294881200 10752303104 1366535376 14.296 98 18 37 0 1380008576 9211424 4261776
19:31:20 38019 8192000000 10272703872 10722299904 1334796560 14.010 97 19 39 1 1350335408 10623872 4914976
19:33:02 123640 8192000000 10303780240 10758758400 1351043968 14.157 98 22 44 0 1373674864 17891264 4739632
19:36:43 45344 8192000000 10305144640 10744238080 1344747280 14.100 97 37 75 0 1362861056 9265904 8847872
19:38:34 18120 8192000000 9068911984 9284820992 231481520 2.748 17 2 4 0 235033984 3284512 267952

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 102 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 15-01-2023 19:40:16 to 15-01-2023 19:40:18.

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          232822
MON$OLDEST_ACTIVE               232823
MON$OLDEST_SNAPSHOT             232823
MON$NEXT_TRANSACTION            232823
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                       2015744
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.33653 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33653 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33653 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33653 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 ---
--- end of diff output ---

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

15.01.23 19:40:19. 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-15 19:40:21 No severe PSQL-related problems occured
15.01.23 19:40:23. End of report.