Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 20.02.2023 16: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 15384748 kB
MemAvailable 30411756 kB
Buffers 0 kB
Cached 16306760 kB
SwapCached 3140 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 3.0.11
DB_NAME                         /home/bases/oltp30-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3333
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp30-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp30
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2023-02-20 16:38:42
DTS_END                         2023-02-20 16:38:42.8500
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_ADDING_INVOICE(33:5) ==> SP_ADD_INVOICE_TO_STOCK(141:26) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/47630
TRN_ID                          245309
ATT_ID                          721
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: 9342.18 22713 1121062
customer order: creation 171.40 407 20568
customer order: refuse 14.20 340 1704
order to supplier: creation 46.97 1237 5636
order to supplier: removal 3.56 88675 427
invoice (draft): creation 46.93 3301 5631
invoice (draft): removal 52.14 732 6257
invoice accept: apply 43.05 17319 5166
invoice accept: cancel 47.58 748 5710
customer reserve: creation 3940.08 173 472809
customer reserve: removal 4681.73 53 561808
realization accept: apply 82.78 178 9934
realization accept: cancel 81.98 97 9837
payment from customer: creation 59.63 108 7156
payment from customer: removal 5.61 74 673
payment to supplier: creation 49.20 121 5904
payment to supplier: removal 3.48 71 417
service: total inventory turnovers 4.58 1216 549
service: total monetary turnovers 6.47 32 776
service: aggregate perf. data 0.80 25320 96
service: refresh index statistics 0.03 314064 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 20-02-2023 16:39:27 to 20-02-2023 16:39:30.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 404
TEST_TIME 2 1953
TEST_TIME 3 3214
TEST_TIME 4 4090
TEST_TIME 5 3949
TEST_TIME 6 3994
TEST_TIME 7 4322
TEST_TIME 8 5007
TEST_TIME 9 4834
TEST_TIME 10 5530
TEST_TIME 11 6125
TEST_TIME 12 6494
TEST_TIME 13 6596
TEST_TIME 14 7136
TEST_TIME 15 7122
TEST_TIME 16 7388
TEST_TIME 17 7410
TEST_TIME 18 7864
TEST_TIME 19 8313
TEST_TIME 20 9103
TEST_TIME 21 9283
TEST_TIME 22 9428
TEST_TIME 23 9416
TEST_TIME 24 9668
TEST_TIME 25 9990
TEST_TIME 26 10368
TEST_TIME 27 10205
TEST_TIME 28 10126
TEST_TIME 29 10131
TEST_TIME 30 10181
TEST_TIME 31 10044
TEST_TIME 32 9850
TEST_TIME 33 9849
TEST_TIME 34 9733
TEST_TIME 35 9752
TEST_TIME 36 9792
TEST_TIME 37 9696
TEST_TIME 38 9537
TEST_TIME 39 9560
TEST_TIME 40 9414
TEST_TIME 41 9401
TEST_TIME 42 9334
TEST_TIME 43 9299
TEST_TIME 44 9155
TEST_TIME 45 9153
TEST_TIME 46 9160
TEST_TIME 47 9019
TEST_TIME 48 9056
TEST_TIME 49 9026
TEST_TIME 50 9046
TEST_TIME 51 9175
TEST_TIME 52 9191
TEST_TIME 53 9279
TEST_TIME 54 9261
TEST_TIME 55 9362
TEST_TIME 56 9412
TEST_TIME 57 9465
TEST_TIME 58 9440
TEST_TIME 59 9507
TEST_TIME 60 9445
TEST_TIME 61 9374
TEST_TIME 62 9284
TEST_TIME 63 9259
TEST_TIME 64 9240
TEST_TIME 65 9175
TEST_TIME 66 9170
TEST_TIME 67 9158
TEST_TIME 68 9126
TEST_TIME 69 9171
TEST_TIME 70 9130
TEST_TIME 71 9124
TEST_TIME 72 9175
TEST_TIME 73 9146
TEST_TIME 74 9130
TEST_TIME 75 9300
TEST_TIME 76 9295
TEST_TIME 77 9290
TEST_TIME 78 9281
TEST_TIME 79 9312
TEST_TIME 80 9362
TEST_TIME 81 9283
TEST_TIME 82 9277
TEST_TIME 83 9279
TEST_TIME 84 9250
TEST_TIME 85 9205
TEST_TIME 86 9167
TEST_TIME 87 9138
TEST_TIME 88 9192
TEST_TIME 89 9200
TEST_TIME 90 9168
TEST_TIME 91 9170
TEST_TIME 92 9187
TEST_TIME 93 9180
TEST_TIME 94 9248
TEST_TIME 95 9259
TEST_TIME 96 9322
TEST_TIME 97 9368
TEST_TIME 98 9363
TEST_TIME 99 9367
TEST_TIME 100 9518
TEST_TIME 101 9474
TEST_TIME 102 9416
TEST_TIME 103 9356
TEST_TIME 104 9315
TEST_TIME 105 9311
TEST_TIME 106 9284
TEST_TIME 107 9255
TEST_TIME 108 9255
TEST_TIME 109 9239
TEST_TIME 110 9246
TEST_TIME 111 9223
TEST_TIME 112 9293
TEST_TIME 113 9280
TEST_TIME 114 9296
TEST_TIME 115 9297
TEST_TIME 116 9297
TEST_TIME 117 9338
TEST_TIME 118 9354
TEST_TIME 119 9354
TEST_TIME 120 9342

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 20-02-2023 16:39:31 to 20-02-2023 16:39:35.

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 1558604 1558604 0 0.00 0 36600 6 0 0
doc_list_biud 1558593 1558593 0 0.00 0 21539 25 0 0
sp_add_invoice_to_stock 5243 5166 77 1.46 21 173175 17320 0 77
sp_cancel_adding_invoice 5710 5710 0 0.00 4 51504 749 0 0
sp_cancel_client_order 1704 1704 0 0.00 5 12120 340 0 0
sp_cancel_customer_reserve 561808 561808 0 0.00 0 15395 54 0 0
sp_cancel_pay_from_customer 673 673 0 0.00 3 4442 75 0 0
sp_cancel_pay_to_supplier 417 417 0 0.00 3 3893 71 0 0
sp_cancel_supplier_invoice 6267 6257 10 0.15 1 21546 733 0 10
sp_cancel_supplier_order 427 427 0 0.00 9 1008130 88675 0 0
sp_cancel_write_off 9837 9837 0 0.00 1 7519 97 0 0
sp_client_order 20568 20568 0 0.00 3 36629 407 0 0
sp_customer_reserve 472809 472809 0 0.00 0 60631 174 0 0
sp_fill_shopping_cart 31827 31827 0 0.00 1 16681 39 0 0
sp_fill_shopping_cart_clo_res 472803 472803 0 0.00 0 16858 16 0 0
sp_get_clo_for_invoice 5159 5159 0 0.00 0 29785 348 0 0
sp_kill_cost_storno 16637 16637 0 0.00 0 10437 48 0 0
sp_kill_qstorno_ret_qs2qd 1140137 1140137 0 0.00 0 21535 29 0 0
sp_kill_qty_storno 600836 600836 0 0.00 0 21538 63 0 0
sp_lock_selected_doc 20864 20864 0 0.00 0 7807 8 0 0
sp_make_cost_storno 28153 28153 0 0.00 0 36587 56 0 0
sp_multiply_rows_for_pdistr 28153 28153 0 0.00 0 5596 21 0 0
sp_multiply_rows_for_qdistr 20568 20568 0 0.00 0 13005 186 0 0
sp_pay_from_customer 7156 7156 0 0.00 4 14917 109 0 0
sp_pay_to_supplier 5904 5904 0 0.00 4 13707 121 0 0
sp_payment_common 13059 13059 0 0.00 1 7776 24 0 0
sp_qd_handle_on_cancel_clo 1704 1704 0 0.00 0 12067 306 0 0
sp_qd_handle_on_invoice_upd_sts 10869 10869 0 0.00 0 14621 290 0 0
sp_qd_handle_on_reserve_upd_sts 9934 9934 0 0.00 0 12859 60 0 0
sp_reserve_write_off 9934 9934 0 0.00 6 19119 179 0 0
sp_supplier_invoice 5631 5631 0 0.00 7 92687 3301 0 0
sp_supplier_order 5636 5636 0 0.00 4 53235 1238 0 0
srv_aggregate_perf_data 96 96 0 0.00 2875 498942 25321 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 988 113276 45145 0 0
srv_find_qd_qs_mism 1072646 1072646 0 0.00 0 34963 5 0 0
srv_make_invnt_saldo 549 549 0 0.00 46 78090 1217 0 0
srv_make_money_saldo 776 776 0 0.00 1 5872 32 0 0
srv_recalc_idx_stat 4 4 0 0.00 208690 397938 314064 0 0
t$perf-abend:sp_add_invoice_to_stock 77 77 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5166 5166 0 0.00 0 13369 148 0 0
t$perf-norm:sp_cancel_adding_invoice 445 445 0 0.00 0 5642 121 0 0
t$perf-norm:sp_cancel_client_order 1704 1704 0 0.00 0 6244 19 0 0
t$perf-norm:sp_cancel_customer_reserve 708 708 0 0.00 0 1063 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 673 673 0 0.00 0 9228 18 0 0
t$perf-norm:sp_cancel_pay_to_supplier 417 417 0 0.00 0 369 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 401 401 0 0.00 0 983 12 0 0
t$perf-norm:sp_cancel_supplier_order 427 427 0 0.00 0 27347 1489 0 0
t$perf-norm:sp_cancel_write_off 996 996 0 0.00 0 3955 9 0 0
t$perf-norm:sp_client_order 20568 20568 0 0.00 0 8027 14 0 0
t$perf-norm:sp_customer_reserve 983 983 0 0.00 0 826 6 0 0
t$perf-norm:sp_pay_from_customer 7156 7156 0 0.00 0 11800 14 0 0
t$perf-norm:sp_pay_to_supplier 5904 5904 0 0.00 0 8410 10 0 0
t$perf-norm:sp_reserve_write_off 9934 9934 0 0.00 0 5362 10 0 0
t$perf-norm:sp_supplier_invoice 5631 5631 0 0.00 0 7434 18 0 0
t$perf-norm:sp_supplier_order 5636 5636 0 0.00 0 9661 15 0 0
t$perf-norm:srv_aggregate_perf_data 96 96 0 0.00 0 31 1 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 11 1 0 0
t$perf-norm:srv_make_invnt_saldo 549 549 0 0.00 0 834 19 0 0
t$perf-norm:srv_make_money_saldo 776 776 0 0.00 0 3837 23 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 60 17 0 0
v_add_invoice_to_stock 10470 10316 154 1.47 0 12478 22 0 154
v_all_customers 37324 37324 0 0.00 0 29 1 0 0
v_all_suppliers 30948 30948 0 0.00 0 83 1 0 0
v_all_wares 41130 41130 0 0.00 0 26 1 0 0
v_cancel_adding_invoice 890 890 0 0.00 0 2626 11 0 0
v_cancel_client_order 3408 3408 0 0.00 0 2856 8 0 0
v_cancel_customer_prepayment 1346 1346 0 0.00 0 2857 10 0 0
v_cancel_customer_reserve 1416 1416 0 0.00 0 3365 9 0 0
v_cancel_payment_to_supplier 834 834 0 0.00 0 1836 7 0 0
v_cancel_supplier_invoice 822 802 20 2.43 0 1948 22 0 20
v_cancel_supplier_order 854 854 0 0.00 0 1311 8 0 0
v_cancel_write_off 1992 1992 0 0.00 0 5103 23 0 0
v_max_id_clo_ord 5634 5634 0 0.00 0 824 1 0 0
v_max_id_clo_res 983 983 0 0.00 0 2905 11 0 0
v_max_id_ord_sup 5627 5627 0 0.00 0 4411 4 0 0
v_max_non_paid_invoice 1691 1691 0 0.00 0 3973 7 0 0
v_max_non_paid_realizn 3868 3868 0 0.00 0 3430 3 0 0
v_min_id_clo_ord 5634 5634 0 0.00 0 1189 4 0 0
v_min_id_clo_res 983 983 0 0.00 0 4838 9 0 0
v_min_id_ord_sup 5627 5627 0 0.00 0 5961 9 0 0
v_min_non_paid_invoice 5904 5904 0 0.00 0 6353 15 0 0
v_min_non_paid_realizn 7155 7155 0 0.00 0 7767 19 0 0
v_our_firm 10380 10380 0 0.00 0 15 1 0 0
v_reserve_write_off 19868 19868 0 0.00 0 5675 6 0 0
x_lock_depdocs_on_canc_invoice 11566 11566 0 0.00 0 19897 109 0 0
x_lock_depdocs_on_canc_sup_ord 427 427 0 0.00 0 9553 309 0 0
x_make_qstorno_1000_1200 5636 5636 0 0.00 1 53225 1174 0 0
x_make_qstorno_1200_2000 5631 5631 0 0.00 3 79711 3151 0 0
x_make_qstorno_2100_3300 472809 472809 0 0.00 0 60629 156 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 20-02-2023 16:39:36 to 20-02-2023 16:39:40.

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
14:08:46 3 8192000000 8801766176 8814104576 7792032 0.095 2 2 3 0 7933136 66144 74960
14:09:47 27133 8192000000 9905916544 10217627648 992855264 10.809 100 91 182 0 1022526960 6190848 23480848
14:11:24 14315 8192000000 9946865776 10270248960 1033179616 11.199 100 82 164 0 1061941392 7852336 20909440
14:12:44 16617 8192000000 9981390928 10309672960 1064661232 11.501 100 88 176 0 1096201632 9472688 22067712
14:14:09 5282 8192000000 10033611792 10380673024 1117314768 12.002 100 77 153 0 1146295728 10517520 18463440
14:15:21 3206 8192000000 10076598592 10433245184 1165643600 12.456 100 79 158 0 1196150592 12419040 18087952
14:16:29 6730 8192000000 10131423792 10500718592 1220447280 12.966 100 79 155 1 1250447760 12542304 17458176
14:17:37 2505 8192000000 10166418752 10542292992 1253374928 13.269 100 75 149 0 1281815856 12706976 15733952
14:18:42 1806 8192000000 10201368160 10584174592 1287093936 13.578 100 84 168 0 1318823408 13259424 18470048
14:19:45 1744 8192000000 10223297120 10609389568 1308110624 13.769 100 84 168 1 1340251312 12336544 19804144
14:20:47 6384 8192000000 10203521792 10610122752 1306573376 13.755 100 36 73 0 1326471776 12559360 7339040
14:21:58 9260 8192000000 10209623936 10619301888 1316755232 13.847 100 16 33 0 1325782224 5283888 3743104
14:23:07 7844 8192000000 10209632640 10626990080 1325477296 13.926 100 17 34 0 1335486000 5865552 4143152
14:24:17 53552 8192000000 10217796976 10637910016 1328593040 13.954 100 24 48 1 1339096560 5190304 5313216
14:26:11 7914 8192000000 10224667936 10648092672 1335039616 14.013 100 16 30 2 1344102416 5755600 3307200
14:27:20 9341 8192000000 10235244208 10653573120 1324430288 13.917 99 26 52 0 1340411952 10251744 5729920
14:28:30 60491 8192000000 10211570560 10635874304 1316612400 13.846 99 34 68 2 1330063392 5007472 8443520
14:30:32 8460 8192000000 10164841552 10566729728 1219397008 12.956 95 23 46 0 1230518896 6221088 4900800
14:31:43 9694 8192000000 10139436544 10534879232 1208499184 12.855 96 20 40 0 1218415824 5370784 4545856
14:32:53 12799 8192000000 10142211280 10544967680 1223040368 12.990 97 17 35 1 1236397280 9510192 3846720
14:34:07 25646 8192000000 10156726272 10568003584 1260165024 13.332 99 28 55 1 1272389664 6122336 6102304
14:35:33 7428 8192000000 10184033392 10596274176 1293702800 13.638 100 21 42 1 1304732848 4911152 6118896
14:36:40 10510 8192000000 10188744784 10607792128 1302550496 13.718 100 23 45 1 1312692656 5012144 5130016
14:37:51 14936 8192000000 10206697568 10619179008 1310565120 13.791 100 12 24 1 1318047392 5041328 2440944
14:39:07 62873 8192000000 10234673712 10647134208 1324072432 13.914 99 37 73 0 1338885440 6443872 8369136
14:41:19 9605 8192000000 10266508304 10678923264 1351899568 14.165 100 50 98 1 1380588128 17745792 10942768
14:42:35 27941 8192000000 10264861440 10685018112 1358622272 14.225 99 41 81 1 1379811744 11519584 9669888
14:44:11 11784 8192000000 10302434256 10719608832 1376912352 14.389 99 46 89 0 1401363888 14018000 10433536
14:45:32 5623 8192000000 10315641808 10733899776 1398321760 14.580 100 53 105 0 1427582208 15922368 13338080
14:46:42 13015 8192000000 10314063728 10744074240 1403742096 14.628 100 41 80 1 1432603104 18797904 10063104
14:47:59 5105 8192000000 10339536464 10759598080 1411149712 14.694 100 56 112 1 1447589792 22439984 14000096
14:49:08 9050 8192000000 10328406544 10748518400 1400291632 14.598 100 55 107 0 1435670080 22062176 13316272
14:50:24 4421 8192000000 10331435120 10755194880 1403212880 14.624 100 55 110 1 1440485168 23317488 13954800
14:51:36 7379 8192000000 10320258272 10738348032 1393746400 14.539 100 57 113 0 1430995184 23142128 14106656
14:52:48 5139 8192000000 10327584288 10740871168 1385696080 14.467 99 68 134 0 1423393136 21083248 16613808
14:54:00 986 8192000000 10344640128 10755059712 1396466256 14.564 100 78 153 0 1447651072 32465744 18719072
14:55:04 5346 8192000000 10325151536 10734317568 1376666352 14.387 100 78 153 2 1430048960 33360800 20021808
14:56:12 5884 8192000000 10321858672 10744643584 1378449680 14.403 100 52 102 1 1433189536 41070464 13669392
14:57:29 2853 8192000000 10333021888 10740809728 1370414816 14.331 100 74 148 0 1434305488 44533600 19357072
14:58:35 5299 8192000000 10295314112 10721902592 1365572368 14.287 100 45 90 0 1405291520 26741920 12977232
14:59:45 21035 8192000000 10303251408 10735996928 1370607232 14.332 100 31 62 0 1408803648 29594656 8601760
15:01:15 15932 8192000000 10303353008 10734866432 1371549632 14.341 100 27 54 1 1408871872 30181296 7140944
15:02:37 27980 8192000000 10290682880 10740994048 1363637392 14.270 99 18 36 0 1398253616 29527600 5088624
15:04:06 30678 8192000000 10270366016 10718744576 1336311216 14.024 98 17 34 1 1367640496 26721488 4607792
15:05:43 63645 8192000000 10272844432 10709127168 1357343616 14.213 100 37 74 0 1375068784 9045024 8680144
15:07:50 49555 8192000000 10261938288 10693115904 1301412880 13.708 95 43 86 4 1319797840 8879696 9505264
15:09:41 51678 8192000000 10281994896 10711097344 1362612448 14.261 100 22 41 0 1381137616 13904352 4620816
15:11:36 26748 8192000000 10277082928 10712125440 1354910480 14.192 99 39 79 0 1373778496 9367568 9500448
15:13:08 19798 8192000000 10251467984 10694397952 1338544208 14.044 97 16 32 0 1351131600 8928864 3658528
15:15:24 44728 8192000000 10274585008 10721980416 1371691408 14.342 100 13 26 0 1382714720 8071184 2952128
15:17:10 37574 8192000000 10283400160 10738618368 1382611344 14.440 100 8 16 0 1396580672 11887696 2081632
15:18:48 38064 8192000000 10287045760 10741952512 1384171696 14.454 100 19 37 0 1400195712 11790576 4233440
15:20:30 78395 8192000000 10283827984 10741428224 1388293136 14.491 100 27 54 1 1402867712 8574704 5999872
15:22:51 28312 8192000000 10305938624 10758500352 1399803184 14.593 100 56 110 0 1430192784 18207104 12182496
15:24:21 96587 8192000000 10300320416 10762375168 1401553008 14.609 100 10 20 0 1418107552 14240592 2313952
15:26:58 45143 8192000000 10308777776 10758770688 1374534624 14.368 98 18 37 0 1390041824 11288304 4218896
15:28:45 64426 8192000000 10318578320 10766188544 1405398464 14.643 100 17 34 0 1427846048 18234704 4212880
15:30:56 84842 8192000000 10290073632 10741174272 1325876512 13.930 94 22 44 0 1347136112 16568560 4691040
15:33:30 108782 8192000000 10291475456 10747867136 1347150192 14.122 96 17 34 0 1368923264 17694880 4078192
15:36:18 10359 8192000000 10311081680 10744287232 1363005264 14.264 98 54 109 0 1385907376 10671040 12231072
15:37:31 39257 8192000000 10269605824 10705694720 1293481008 13.636 94 22 44 0 1308380432 10245264 4654160
15:39:13 74158 8192000000 10267178768 10700197888 1314486128 13.827 96 36 70 2 1330079008 7804064 7788816
15:41:28 65990 8192000000 10261435440 10720796672 1337137696 14.032 97 17 34 0 1348624096 7831440 3654960
15:45:02 68639 8192000000 10272421376 10724130816 1320224064 13.879 95 8 16 0 1329491440 7753856 1513520
15:47:19 77787 8192000000 10261733984 10707263488 1308410288 13.772 95 20 40 0 1320648208 7466368 4771552
15:49:40 57028 8192000000 10255713984 10704654336 1332482032 13.990 98 22 43 0 1347252640 10030128 4740480
15:51:47 38563 8192000000 10285962512 10729123840 1361817808 14.254 99 21 42 0 1381335088 14734416 4782864
15:53:37 113275 8192000000 10270303392 10725371904 1336084880 14.022 98 4 8 0 1350479216 13693856 700480
15:56:30 55271 8192000000 10291983104 10721980416 1351909456 14.165 99 27 53 0 1367196944 9590896 5696592
15:58:26 87529 8192000000 10271027904 10718474240 1335325984 14.015 97 20 40 0 1348725728 9488784 3910960
16:00:59 73041 8192000000 10274275776 10736873472 1351295392 14.159 98 11 20 1 1368739488 15409584 2034512
16:03:13 61459 8192000000 10263219520 10716708864 1341968576 14.075 98 17 34 0 1360789152 14858592 3961984
16:05:26 68819 8192000000 10263389920 10732990464 1360759888 14.244 99 12 24 0 1379851168 16479904 2611376
16:07:48 59925 8192000000 10288009104 10741825536 1381854240 14.433 100 11 22 0 1392825024 8378128 2592656
16:10:46 40255 8192000000 10307190864 10765914112 1405833456 14.647 100 19 37 0 1419220176 8833776 4552944
16:12:31 59965 8192000000 10295911184 10740404224 1363485056 14.269 97 26 50 1 1382614176 13515632 5613488
16:14:33 46301 8192000000 10304878736 10764386304 1382838128 14.442 99 14 28 0 1404997680 19030160 3129392
16:16:21 39832 8192000000 10326333520 10763862016 1402984240 14.622 100 44 90 0 1427434144 14527744 9922160
16:18:01 70411 8192000000 10317977232 10795286528 1382357744 14.438 99 4 8 0 1408525584 25410608 757232
16:20:18 84023 8192000000 10300497728 10737508352 1352822240 14.173 97 31 62 0 1369167136 8651664 7693232
16:22:47 27647 8192000000 10278566240 10726133760 1366334864 14.294 99 30 61 0 1382991360 10378608 6277888
16:24:16 62921 8192000000 10282422992 10739507200 1369006832 14.318 99 18 36 0 1383486704 10339600 4140272
16:26:21 61614 8192000000 10285704784 10747404288 1375821792 14.379 99 14 29 0 1391408432 12438800 3147840
16:28:29 76827 8192000000 10282006864 10741739520 1347461264 14.125 97 21 42 0 1365952016 13707184 4783568
16:31:30 52479 8192000000 10280934368 10737373184 1342567584 14.081 97 25 50 0 1357248848 8934448 5746816
16:33:30 53963 8192000000 10271866032 10726846464 1354508976 14.188 99 25 50 1 1376044528 15776192 5759360
16:35:26 101556 8192000000 10259992816 10709745664 1265583264 13.381 92 17 34 0 1282597648 13390896 3623488
16:38:08 44143 8192000000 9251891600 9522147328 403137072 4.690 30 3 6 0 408674176 5073488 463616

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 20-02-2023 16:39:42 to 20-02-2023 16:39:57.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 77 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 20-02-2023 16:39:58 to 20-02-2023 16:40:00.

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          245274
MON$OLDEST_ACTIVE               245586
MON$OLDEST_SNAPSHOT             245586
MON$NEXT_TRANSACTION            245586
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                       2019712
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33665 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33665 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33665 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33665 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
:278: 
:279: mscs-test-161.budzdorov.ru    Mon Feb 20 16:38:54 2023
:280:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:281: 
:282: 
:283: mscs-test-161.budzdorov.ru    Mon Feb 20 16:38:55 2023
:284:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:285: 
:286: 
:287: mscs-test-161.budzdorov.ru    Mon Feb 20 16:38:55 2023
:288:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:289: 
:290: 
:291: mscs-test-161.budzdorov.ru    Mon Feb 20 16:38:55 2023
:292:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:293: 
--- end of diff output ---

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

20.02.23 16:40:01. There are 3 logs in /var/tmp/logs-oltp30 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2023-02-20 16:40:04 No severe PSQL-related problems occured
20.02.23 16:40:05. End of report.