Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 21.12.2022 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 14829144 kB
MemAvailable 30118388 kB
Buffers 0 kB
Cached 16894756 kB
SwapCached 3420 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 2022-12-21 19:38:34
DTS_END                         2022-12-21 19:38:34.3280
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/45280
TRN_ID                          244727
ATT_ID                          729
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: 9836.81 18489 1180417
customer order: creation 178.73 331 21448
customer order: refuse 14.88 354 1786
order to supplier: creation 47.30 1163 5676
order to supplier: removal 3.56 109865 427
invoice (draft): creation 46.69 3352 5603
invoice (draft): removal 57.69 740 6923
invoice accept: apply 41.68 19246 5002
invoice accept: cancel 52.13 768 6256
customer reserve: creation 3920.60 188 470472
customer reserve: removal 5161.08 59 619329
realization accept: apply 85.97 179 10316
realization accept: cancel 93.02 109 11162
payment from customer: creation 60.87 99 7304
payment from customer: removal 6.03 104 724
payment to supplier: creation 50.41 108 6049
payment to supplier: removal 3.72 111 446
service: total inventory turnovers 4.50 1194 540
service: total monetary turnovers 7.01 25 841
service: aggregate perf. data 0.91 16347 109
service: refresh index statistics 0.03 215437 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 21-12-2022 19:40:25 to 21-12-2022 19:40:27.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1881
TEST_TIME 2 3098
TEST_TIME 3 4093
TEST_TIME 4 4587
TEST_TIME 5 5144
TEST_TIME 6 5575
TEST_TIME 7 5504
TEST_TIME 8 5479
TEST_TIME 9 5647
TEST_TIME 10 5610
TEST_TIME 11 5537
TEST_TIME 12 5563
TEST_TIME 13 5813
TEST_TIME 14 5856
TEST_TIME 15 6294
TEST_TIME 16 6532
TEST_TIME 17 6730
TEST_TIME 18 7149
TEST_TIME 19 7120
TEST_TIME 20 7668
TEST_TIME 21 7968
TEST_TIME 22 8238
TEST_TIME 23 8271
TEST_TIME 24 8421
TEST_TIME 25 8976
TEST_TIME 26 9447
TEST_TIME 27 9731
TEST_TIME 28 9905
TEST_TIME 29 10479
TEST_TIME 30 11055
TEST_TIME 31 11180
TEST_TIME 32 11141
TEST_TIME 33 11121
TEST_TIME 34 11229
TEST_TIME 35 11068
TEST_TIME 36 10909
TEST_TIME 37 10772
TEST_TIME 38 10718
TEST_TIME 39 10644
TEST_TIME 40 10724
TEST_TIME 41 10632
TEST_TIME 42 10512
TEST_TIME 43 10437
TEST_TIME 44 10364
TEST_TIME 45 10390
TEST_TIME 46 10427
TEST_TIME 47 10456
TEST_TIME 48 10370
TEST_TIME 49 10293
TEST_TIME 50 10224
TEST_TIME 51 10136
TEST_TIME 52 10082
TEST_TIME 53 10109
TEST_TIME 54 10067
TEST_TIME 55 10041
TEST_TIME 56 10082
TEST_TIME 57 10074
TEST_TIME 58 10123
TEST_TIME 59 10096
TEST_TIME 60 10103
TEST_TIME 61 10225
TEST_TIME 62 10241
TEST_TIME 63 10165
TEST_TIME 64 10306
TEST_TIME 65 10383
TEST_TIME 66 10420
TEST_TIME 67 10431
TEST_TIME 68 10351
TEST_TIME 69 10214
TEST_TIME 70 10264
TEST_TIME 71 10161
TEST_TIME 72 10214
TEST_TIME 73 10161
TEST_TIME 74 10143
TEST_TIME 75 10082
TEST_TIME 76 10081
TEST_TIME 77 10036
TEST_TIME 78 9983
TEST_TIME 79 10003
TEST_TIME 80 9941
TEST_TIME 81 9884
TEST_TIME 82 9897
TEST_TIME 83 9940
TEST_TIME 84 9920
TEST_TIME 85 9882
TEST_TIME 86 9914
TEST_TIME 87 9923
TEST_TIME 88 9903
TEST_TIME 89 9944
TEST_TIME 90 9970
TEST_TIME 91 10029
TEST_TIME 92 10056
TEST_TIME 93 10054
TEST_TIME 94 10098
TEST_TIME 95 10138
TEST_TIME 96 10113
TEST_TIME 97 10025
TEST_TIME 98 10002
TEST_TIME 99 9968
TEST_TIME 100 9931
TEST_TIME 101 9948
TEST_TIME 102 9881
TEST_TIME 103 9825
TEST_TIME 104 9781
TEST_TIME 105 9767
TEST_TIME 106 9885
TEST_TIME 107 9869
TEST_TIME 108 9860
TEST_TIME 109 9832
TEST_TIME 110 9838
TEST_TIME 111 9875
TEST_TIME 112 9944
TEST_TIME 113 9900
TEST_TIME 114 9904
TEST_TIME 115 9910
TEST_TIME 116 9925
TEST_TIME 117 9914
TEST_TIME 118 9849
TEST_TIME 119 9844
TEST_TIME 120 9837

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 21-12-2022 19:40:29 to 21-12-2022 19:40:32.

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 1616725 1616725 0 0.00 0 27117 6 0 0
doc_list_biud 1616725 1616725 0 0.00 0 72368 29 0 0
sp_add_invoice_to_stock 5104 5002 102 1.99 23 203032 19246 0 102
sp_cancel_adding_invoice 6256 6256 0 0.00 3 59721 769 0 0
sp_cancel_client_order 1786 1786 0 0.00 4 16630 355 0 0
sp_cancel_customer_reserve 619329 619329 0 0.00 0 62425 60 0 0
sp_cancel_pay_from_customer 724 724 0 0.00 3 9267 104 0 0
sp_cancel_pay_to_supplier 446 446 0 0.00 3 6461 112 0 0
sp_cancel_supplier_invoice 6927 6923 4 0.05 1 72372 741 0 4
sp_cancel_supplier_order 427 427 0 0.00 7 1160942 109865 0 0
sp_cancel_write_off 11162 11162 0 0.00 1 19850 110 0 0
sp_client_order 21448 21448 0 0.00 3 36392 332 0 0
sp_customer_reserve 470472 470472 0 0.00 0 120143 188 0 0
sp_fill_shopping_cart 32708 32708 0 0.00 1 13172 32 0 0
sp_fill_shopping_cart_clo_res 470471 470471 0 0.00 0 23131 15 0 0
sp_get_clo_for_invoice 5001 5001 0 0.00 0 37700 306 0 0
sp_kill_cost_storno 18588 18588 0 0.00 0 16925 53 0 0
sp_kill_qstorno_ret_qs2qd 1257170 1257170 0 0.00 0 72367 32 0 0
sp_kill_qty_storno 661200 661200 0 0.00 0 72368 69 0 0
sp_lock_selected_doc 21227 21227 0 0.00 0 9800 9 0 0
sp_make_cost_storno 28670 28670 0 0.00 0 26982 47 0 0
sp_multiply_rows_for_pdistr 28670 28670 0 0.00 0 11214 26 0 0
sp_multiply_rows_for_qdistr 21448 21448 0 0.00 0 18205 133 0 0
sp_pay_from_customer 7304 7304 0 0.00 4 11220 99 0 0
sp_pay_to_supplier 6049 6049 0 0.00 5 27127 109 0 0
sp_payment_common 13353 13353 0 0.00 1 7870 21 0 0
sp_qd_handle_on_cancel_clo 1786 1786 0 0.00 0 16621 323 0 0
sp_qd_handle_on_invoice_upd_sts 11257 11257 0 0.00 0 23871 333 0 0
sp_qd_handle_on_reserve_upd_sts 10316 10316 0 0.00 0 25039 71 0 0
sp_reserve_write_off 10316 10316 0 0.00 6 25369 179 0 0
sp_supplier_invoice 5603 5603 0 0.00 7 110680 3352 0 0
sp_supplier_order 5676 5676 0 0.00 4 51643 1163 0 0
srv_aggregate_perf_data 109 109 0 0.00 2189 127257 16347 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1990 131437 45612 0 0
srv_find_qd_qs_mism 1131710 1131710 0 0.00 0 17544 6 0 0
srv_make_invnt_saldo 540 540 0 0.00 39 75750 1195 0 0
srv_make_money_saldo 841 841 0 0.00 1 2537 26 0 0
srv_recalc_idx_stat 4 4 0 0.00 105013 318875 215438 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 4 4 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5002 5002 0 0.00 0 16051 194 0 0
t$perf-norm:sp_cancel_adding_invoice 413 413 0 0.00 0 3072 86 0 0
t$perf-norm:sp_cancel_client_order 1786 1786 0 0.00 0 3158 14 0 0
t$perf-norm:sp_cancel_customer_reserve 699 699 0 0.00 0 3117 17 0 0
t$perf-norm:sp_cancel_pay_from_customer 724 724 0 0.00 0 5594 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 446 446 0 0.00 0 777 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 400 400 0 0.00 0 572 11 0 0
t$perf-norm:sp_cancel_supplier_order 427 427 0 0.00 0 21048 1653 0 0
t$perf-norm:sp_cancel_write_off 1015 1015 0 0.00 0 3947 11 0 0
t$perf-norm:sp_client_order 21448 21448 0 0.00 0 9878 15 0 0
t$perf-norm:sp_customer_reserve 1035 1035 0 0.00 0 1774 6 0 0
t$perf-norm:sp_pay_from_customer 7304 7304 0 0.00 0 15306 12 0 0
t$perf-norm:sp_pay_to_supplier 6049 6049 0 0.00 0 9017 13 0 0
t$perf-norm:sp_reserve_write_off 10316 10316 0 0.00 0 10233 12 0 0
t$perf-norm:sp_supplier_invoice 5603 5603 0 0.00 0 13558 20 0 0
t$perf-norm:sp_supplier_order 5676 5676 0 0.00 0 8775 20 0 0
t$perf-norm:srv_aggregate_perf_data 109 109 0 0.00 0 1565 21 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 428 8 0 0
t$perf-norm:srv_make_invnt_saldo 540 540 0 0.00 0 3064 17 0 0
t$perf-norm:srv_make_money_saldo 841 841 0 0.00 0 2188 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 10206 10002 204 1.99 0 12897 21 0 204
v_all_customers 38994 38994 0 0.00 0 27 1 0 0
v_all_suppliers 31472 31472 0 0.00 0 25 1 0 0
v_all_wares 42896 42896 0 0.00 0 19 1 0 0
v_cancel_adding_invoice 826 826 0 0.00 0 1122 7 0 0
v_cancel_client_order 3572 3572 0 0.00 0 1907 5 0 0
v_cancel_customer_prepayment 1448 1448 0 0.00 0 5204 15 0 0
v_cancel_customer_reserve 1398 1398 0 0.00 0 2691 6 0 0
v_cancel_payment_to_supplier 892 892 0 0.00 0 6267 26 0 0
v_cancel_supplier_invoice 808 800 8 0.99 0 1582 15 0 8
v_cancel_supplier_order 854 854 0 0.00 0 273 3 0 0
v_cancel_write_off 2030 2030 0 0.00 0 6426 18 0 0
v_max_id_clo_ord 5676 5676 0 0.00 0 1792 2 0 0
v_max_id_clo_res 1035 1035 0 0.00 0 3464 17 0 0
v_max_id_ord_sup 5584 5584 0 0.00 0 7217 5 0 0
v_max_non_paid_invoice 1573 1573 0 0.00 0 485 2 0 0
v_max_non_paid_realizn 3893 3893 0 0.00 0 1407 4 0 0
v_min_id_clo_ord 5676 5676 0 0.00 0 1819 6 0 0
v_min_id_clo_res 1035 1035 0 0.00 0 5474 15 0 0
v_min_id_ord_sup 5584 5584 0 0.00 0 5040 8 0 0
v_min_non_paid_invoice 6049 6049 0 0.00 0 5572 14 0 0
v_min_non_paid_realizn 7304 7304 0 0.00 0 7173 15 0 0
v_our_firm 10724 10724 0 0.00 0 18 1 0 0
v_reserve_write_off 20632 20632 0 0.00 0 5827 5 0 0
x_lock_depdocs_on_canc_invoice 12779 12779 0 0.00 0 31587 133 0 0
x_lock_depdocs_on_canc_sup_ord 427 427 0 0.00 0 17353 415 0 0
x_make_qstorno_1000_1200 5676 5676 0 0.00 2 51629 1096 0 0
x_make_qstorno_1200_2000 5603 5603 0 0.00 3 108842 3234 0 0
x_make_qstorno_2100_3300 470472 470472 0 0.00 0 120141 170 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 21-12-2022 19:40:33 to 21-12-2022 19:40:37.

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:38 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:39 43052 8192000000 9917002368 10229481472 992612848 10.807 100 84 169 0 1020372704 5855440 21904416
17:11:27 25061 8192000000 9960724336 10285215744 1037099104 11.237 100 87 174 0 1067767424 8519856 22148464
17:13:04 12744 8192000000 10008065600 10345795584 1091579328 11.758 100 79 158 0 1120798640 10067904 19151408
17:14:18 8545 8192000000 10050998672 10400903168 1136605744 12.184 100 81 162 0 1167049184 11226048 19217392
17:15:30 7285 8192000000 10088082592 10452819968 1178895856 12.580 100 70 140 0 1206314016 11173248 16244912
17:16:40 2714 8192000000 10128156512 10498347008 1220352608 12.965 100 86 169 1 1252469968 13582416 18534944
17:17:44 1986 8192000000 10162010576 10538426368 1250925760 13.247 100 85 167 2 1280269104 11025664 18317680
17:18:49 1247 8192000000 10189038240 10567168000 1278143104 13.496 100 83 164 0 1309793808 14211744 17438960
17:19:52 34037 8192000000 10197269152 10599849984 1302355488 13.717 100 60 121 0 1326916832 11013120 13548224
17:21:26 3784 8192000000 10194350848 10608283648 1311046640 13.796 100 22 44 0 1323777488 7229680 5501168
17:22:31 12516 8192000000 10205789360 10621911040 1321149392 13.887 100 11 21 0 1328747120 5229024 2368704
17:23:44 8723 8192000000 10219837584 10633793536 1330723120 13.974 100 17 34 0 1340160000 5911008 3525872
17:24:53 64380 8192000000 10214126768 10640416768 1331045168 13.977 100 27 55 2 1342814496 5025472 6743856
17:26:57 8754 8192000000 10227365680 10643492864 1326045648 13.931 99 26 52 0 1337528448 5824976 5657824
17:28:07 5507 8192000000 10215308304 10631507968 1317287376 13.852 99 23 45 2 1328536784 6557184 4692224
17:29:14 49755 8192000000 10161474112 10554380288 1250259008 13.241 97 46 87 1 1266955216 5825312 10870896
17:31:11 6497 8192000000 10154961616 10554560512 1258591744 13.317 100 36 72 3 1274415024 7796768 8026512
17:32:18 7898 8192000000 10116968144 10513145856 1214238800 12.908 98 34 68 0 1227940112 5511504 8189808
17:33:26 60096 8192000000 10177565184 10579394560 1281879792 13.530 100 32 62 0 1294547088 5467120 7200176
17:35:31 7152 8192000000 10196468624 10598907904 1294086368 13.641 100 27 53 0 1307051488 6830320 6134800
17:36:39 10446 8192000000 10197232896 10610290688 1304802384 13.739 100 24 48 1 1315961408 5588864 5570160
17:37:50 55128 8192000000 10222062880 10639781888 1317607728 13.855 100 17 32 0 1326891216 5892912 3390576
17:39:57 12817 8192000000 10256634112 10669842432 1350676144 14.154 100 54 107 0 1370690240 8529536 11484560
17:41:14 8483 8192000000 10276839328 10692759552 1363230384 14.266 100 51 101 1 1384487952 9449968 11807600
17:42:26 13312 8192000000 10281676688 10706661376 1376906880 14.389 100 38 76 0 1396321984 10723456 8691648
17:43:45 18325 8192000000 10300634032 10727780352 1386463200 14.474 100 34 65 0 1409337872 15271568 7603104
17:45:06 14705 8192000000 10302003584 10729500672 1390907408 14.514 100 55 110 0 1417931872 13354416 13670048
17:46:33 17407 8192000000 10322355824 10753699840 1406146112 14.650 100 47 91 1 1434272448 17013136 11113200
17:47:56 28454 8192000000 10324570096 10754482176 1386462720 14.474 98 44 86 0 1417117184 19858336 10796128
17:49:31 4674 8192000000 10342786608 10766168064 1408617984 14.672 99 61 118 1 1445584992 22891424 14075584
17:50:38 5280 8192000000 10344094112 10763993088 1411651104 14.699 100 71 142 3 1457109024 27761120 17696800
17:51:51 6358 8192000000 10339282160 10767089664 1409008720 14.675 100 50 96 0 1449315280 28260176 12046384
17:53:07 3096 8192000000 10350122720 10763591680 1404520880 14.635 100 73 144 2 1458953264 35727152 18705232
17:54:17 6424 8192000000 10350945616 10763091968 1409421568 14.679 100 72 145 1 1458817312 31525600 17870144
17:55:34 2584 8192000000 10361148576 10766123008 1403119184 14.623 100 85 168 2 1459771472 35987776 20664512
17:56:40 2257 8192000000 10354422288 10764324864 1401631440 14.610 100 82 159 2 1461040096 38607392 20801264
17:57:46 1989 8192000000 10356863616 10763821056 1396025680 14.560 100 69 134 1 1458012256 44452912 17533664
17:58:55 3485 8192000000 10361340624 10771697664 1401296256 14.607 100 69 137 1 1462320096 43550704 17473136
18:00:05 18406 8192000000 10346756032 10763177984 1395065968 14.551 100 40 79 1 1449380304 43173888 11140448
18:01:28 8954 8192000000 10332154880 10750349312 1381056144 14.426 100 42 84 0 1436745952 43913920 11775888
18:02:43 12136 8192000000 10326053696 10744274944 1364876800 14.281 99 42 82 1 1428286832 51817632 11592400
18:04:01 20854 8192000000 10339760688 10762702848 1376560992 14.386 99 32 64 0 1442102048 56160544 9380512
18:05:24 14447 8192000000 10339750560 10779025408 1386827344 14.478 100 30 60 0 1436872336 41488496 8556496
18:06:49 26746 8192000000 10310810320 10758172672 1366965728 14.300 100 21 42 0 1418263856 44740064 6558064
18:08:25 50539 8192000000 10267702640 10716422144 1311183296 13.797 96 41 84 1 1335623376 14686608 9753472
18:10:23 33946 8192000000 10274802528 10711711744 1345987904 14.111 99 15 30 0 1366973936 17848208 3137824
18:12:01 64382 8192000000 10287174448 10729148416 1356503760 14.206 99 16 32 0 1381043344 21127984 3411600
18:14:17 117811 8192000000 10302763904 10749198336 1384352944 14.455 100 31 62 1 1399498832 8002496 7143392
18:17:37 92257 8192000000 10303442624 10762940416 1379234128 14.410 99 27 54 0 1405052384 19226176 6592080
18:20:26 72712 8192000000 10301808784 10756464640 1371765600 14.343 98 17 34 0 1384052160 8258752 4027808
18:22:41 54393 8192000000 10332502080 10783637504 1413014816 14.711 100 37 74 0 1437538720 15397856 9126048
18:24:59 33059 8192000000 10300364880 10772627456 1389380112 14.500 99 18 36 1 1404955872 11556656 4019104
18:26:39 60645 8192000000 10307278112 10775400448 1408570016 14.671 100 12 25 0 1424329616 12975664 2783936
18:28:42 122474 8192000000 10293587264 10762342400 1386791008 14.477 98 17 32 0 1402231584 11528688 3911888
18:31:48 36343 8192000000 10294114048 10771578880 1401310032 14.607 100 11 20 1 1412293504 8700560 2282912
18:33:29 79422 8192000000 10300330368 10765795328 1345973120 14.111 96 18 34 0 1362172224 12434720 3764384
18:35:54 73210 8192000000 10307252848 10765803520 1373232672 14.356 98 11 22 0 1387007760 11486080 2289008
18:38:36 48979 8192000000 10265113808 10727170048 1317672064 13.856 95 24 48 0 1333313312 10436048 5205200
18:40:26 81520 8192000000 10272464992 10716090368 1277084896 13.486 93 9 18 0 1293239104 13989088 2165120
18:42:50 85037 8192000000 10294115952 10755293184 1365703552 14.289 99 13 27 0 1391607504 23130032 2773920
18:45:16 39871 8192000000 10272947984 10712535040 1314304304 13.825 96 25 47 1 1332166144 12013648 5848192
18:46:56 117567 8192000000 10264631424 10723332096 1328881328 13.957 97 26 50 0 1348237632 13375344 5980960
18:49:55 11714 8192000000 10278968464 10724081664 1348789120 14.137 99 28 56 0 1372464736 17255024 6420592
18:51:18 78619 8192000000 10242797216 10695561216 1286944976 13.576 95 15 30 0 1307034976 16452608 3637392
18:53:39 52483 8192000000 10231040560 10691010560 1304669600 13.738 97 17 34 0 1323146752 14467824 4009328
18:55:32 68985 8192000000 10230544704 10685403136 1313316864 13.816 98 10 20 0 1329383136 13709024 2357248
18:57:42 28276 8192000000 10250095968 10704302080 1341698976 14.073 100 22 44 0 1357634608 11217008 4718624
18:59:13 83131 8192000000 10258176928 10715971584 1346786464 14.119 99 18 36 0 1365485248 14702320 3996464
19:01:37 63111 8192000000 10246464160 10708746240 1325050176 13.922 98 4 8 0 1336145088 10419968 674944
19:03:41 28879 8192000000 10272988512 10720174080 1353082560 14.175 99 36 72 0 1371403712 10585904 7735248
19:05:13 27345 8192000000 10273130896 10717921280 1352766048 14.172 99 31 61 0 1368638464 9284128 6588288
19:06:43 27090 8192000000 10300772336 10741727232 1366930464 14.300 100 49 98 0 1392714896 15368032 10416400
19:08:12 90335 8192000000 10292090800 10750836736 1369829248 14.326 99 13 26 0 1393408768 20812784 2766736
19:10:44 31619 8192000000 10307158752 10755174400 1380653904 14.422 99 38 76 0 1402540544 13272880 8613760
19:12:24 113199 8192000000 10301844080 10760380416 1368795072 14.316 98 9 18 0 1387665360 17153168 1717120
19:15:17 131437 8192000000 10275951840 10745499648 1361960176 14.255 98 23 46 0 1378917200 11117248 5839776
19:18:32 72359 8192000000 10274908288 10748166144 1363428528 14.268 98 12 25 0 1377181568 10917776 2835264
19:20:45 82414 8192000000 10312087040 10767396864 1381422352 14.429 98 15 31 0 1402329264 17000032 3906880
19:23:29 57342 8192000000 10281779856 10770903040 1367418848 14.304 99 10 20 0 1396981824 26650112 2912864
19:25:46 11544 8192000000 10268002656 10720911360 1365186640 14.284 99 22 44 1 1377390160 6838288 5365232
19:27:03 21744 8192000000 10284044912 10726805504 1357874464 14.218 99 29 58 0 1374624272 10736480 6013328
19:28:26 66715 8192000000 10297166432 10748289024 1375577872 14.377 100 26 52 0 1400664656 18909072 6177712
19:30:40 75879 8192000000 10295232896 10737061888 1329019248 13.958 96 21 42 0 1346971136 13323008 4628880
19:32:59 25546 8192000000 10281678448 10719801344 1322599280 13.900 97 46 92 0 1351059936 18435584 10025072
19:34:28 39986 8192000000 10269910464 10707816448 1315426816 13.835 97 27 54 0 1335333072 14276544 5629712
19:36:08 104443 8192000000 10270140160 10722852864 1347965568 14.129 99 37 74 0 1376662368 20075472 8621328

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 21-12-2022 19:40:39 to 21-12-2022 19:40:54.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 102 v_add_invoice_to_stock 335544517
except 4 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 21-12-2022 19:40:55 to 21-12-2022 19:40:57.

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          244123
MON$OLDEST_ACTIVE               245126
MON$OLDEST_SNAPSHOT             245126
MON$NEXT_TRANSACTION            245126
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                       2053216
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.33648 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33648 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33648 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33648 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 ---
:973: 
:974: mscs-test-161.budzdorov.ru    Wed Dec 21 19:38:36 2022
:975:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:976: 
:977: 
:978: mscs-test-161.budzdorov.ru    Wed Dec 21 19:38:41 2022
:979:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:980: 
:981: 
:982: mscs-test-161.budzdorov.ru    Wed Dec 21 19:38:43 2022
:983:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:984: 
--- end of diff output ---

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

21.12.22 19:40:58. 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
2022-12-21 19:41:01 No severe PSQL-related problems occured
21.12.22 19:41:02. End of report.