Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 14.12.2022 16:44
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 15172456 kB
MemAvailable 30103360 kB
Buffers 0 kB
Cached 16557924 kB
SwapCached 3324 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-14 16:42:45
DTS_END                         2022-12-14 16:42:45.1240
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/51186
TRN_ID                          232054
ATT_ID                          657
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: 9067.55 17213 1088106
customer order: creation 169.39 347 20327
customer order: refuse 13.84 419 1661
order to supplier: creation 44.31 1296 5317
order to supplier: removal 3.32 113013 398
invoice (draft): creation 46.15 3529 5538
invoice (draft): removal 51.33 881 6160
invoice accept: apply 40.88 19350 4905
invoice accept: cancel 46.53 853 5584
customer reserve: creation 3746.57 194 449588
customer reserve: removal 4617.60 63 554112
realization accept: apply 81.21 186 9745
realization accept: cancel 78.68 119 9441
payment from customer: creation 59.26 124 7111
payment from customer: removal 5.36 101 643
payment to supplier: creation 47.63 155 5716
payment to supplier: removal 3.45 64 414
service: total inventory turnovers 4.83 1205 580
service: total monetary turnovers 6.38 28 765
service: aggregate perf. data 0.81 26125 97
service: refresh index statistics 0.03 176201 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 14-12-2022 16:44:39 to 14-12-2022 16:44:41.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4149
TEST_TIME 2 3177
TEST_TIME 3 3170
TEST_TIME 4 4698
TEST_TIME 5 4753
TEST_TIME 6 4492
TEST_TIME 7 4393
TEST_TIME 8 4275
TEST_TIME 9 4586
TEST_TIME 10 4591
TEST_TIME 11 4453
TEST_TIME 12 4725
TEST_TIME 13 5165
TEST_TIME 14 5250
TEST_TIME 15 5218
TEST_TIME 16 5891
TEST_TIME 17 5950
TEST_TIME 18 6279
TEST_TIME 19 6441
TEST_TIME 20 6574
TEST_TIME 21 6576
TEST_TIME 22 6539
TEST_TIME 23 6800
TEST_TIME 24 7287
TEST_TIME 25 7296
TEST_TIME 26 7370
TEST_TIME 27 7488
TEST_TIME 28 8015
TEST_TIME 29 8060
TEST_TIME 30 8170
TEST_TIME 31 8842
TEST_TIME 32 9609
TEST_TIME 33 9731
TEST_TIME 34 9566
TEST_TIME 35 9519
TEST_TIME 36 9470
TEST_TIME 37 9375
TEST_TIME 38 9512
TEST_TIME 39 9568
TEST_TIME 40 9435
TEST_TIME 41 9318
TEST_TIME 42 9262
TEST_TIME 43 9260
TEST_TIME 44 9291
TEST_TIME 45 9277
TEST_TIME 46 9326
TEST_TIME 47 9327
TEST_TIME 48 9296
TEST_TIME 49 9200
TEST_TIME 50 9113
TEST_TIME 51 9055
TEST_TIME 52 8905
TEST_TIME 53 8979
TEST_TIME 54 8982
TEST_TIME 55 8926
TEST_TIME 56 8887
TEST_TIME 57 8918
TEST_TIME 58 8838
TEST_TIME 59 8912
TEST_TIME 60 8962
TEST_TIME 61 8970
TEST_TIME 62 8974
TEST_TIME 63 8981
TEST_TIME 64 8983
TEST_TIME 65 9059
TEST_TIME 66 9028
TEST_TIME 67 9097
TEST_TIME 68 9135
TEST_TIME 69 9184
TEST_TIME 70 9176
TEST_TIME 71 9214
TEST_TIME 72 9107
TEST_TIME 73 9037
TEST_TIME 74 9014
TEST_TIME 75 8990
TEST_TIME 76 8988
TEST_TIME 77 8965
TEST_TIME 78 8928
TEST_TIME 79 8935
TEST_TIME 80 8923
TEST_TIME 81 8916
TEST_TIME 82 8980
TEST_TIME 83 8937
TEST_TIME 84 8946
TEST_TIME 85 8956
TEST_TIME 86 9017
TEST_TIME 87 8986
TEST_TIME 88 9029
TEST_TIME 89 9081
TEST_TIME 90 9092
TEST_TIME 91 9088
TEST_TIME 92 9072
TEST_TIME 93 9117
TEST_TIME 94 9097
TEST_TIME 95 9068
TEST_TIME 96 9041
TEST_TIME 97 9022
TEST_TIME 98 8993
TEST_TIME 99 8975
TEST_TIME 100 8967
TEST_TIME 101 8959
TEST_TIME 102 8942
TEST_TIME 103 8884
TEST_TIME 104 8869
TEST_TIME 105 8913
TEST_TIME 106 8898
TEST_TIME 107 8888
TEST_TIME 108 8950
TEST_TIME 109 8977
TEST_TIME 110 8990
TEST_TIME 111 9039
TEST_TIME 112 9017
TEST_TIME 113 9021
TEST_TIME 114 9042
TEST_TIME 115 9093
TEST_TIME 116 9090
TEST_TIME 117 9150
TEST_TIME 118 9162
TEST_TIME 119 9105
TEST_TIME 120 9068

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 14-12-2022 16:44:43 to 14-12-2022 16:44:46.

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 1504587 1504587 0 0.00 0 18616 7 0 0
doc_list_biud 1504587 1504587 0 0.00 0 80831 30 0 0
sp_add_invoice_to_stock 4989 4905 84 1.68 45 197166 19350 0 84
sp_cancel_adding_invoice 5584 5584 0 0.00 5 61659 854 0 0
sp_cancel_client_order 1661 1661 0 0.00 4 14549 419 0 0
sp_cancel_customer_reserve 554112 554112 0 0.00 0 39174 64 0 0
sp_cancel_pay_from_customer 643 643 0 0.00 3 5784 102 0 0
sp_cancel_pay_to_supplier 414 414 0 0.00 2 2020 64 0 0
sp_cancel_supplier_invoice 6172 6160 12 0.19 2 80838 881 0 12
sp_cancel_supplier_order 398 398 0 0.00 7 1073731 113013 0 0
sp_cancel_write_off 9441 9441 0 0.00 1 18669 119 0 0
sp_client_order 20327 20327 0 0.00 3 27921 347 0 0
sp_customer_reserve 449588 449588 0 0.00 0 53714 195 0 0
sp_fill_shopping_cart 31169 31169 0 0.00 1 16806 45 0 0
sp_fill_shopping_cart_clo_res 449586 449586 0 0.00 0 13911 14 0 0
sp_get_clo_for_invoice 4903 4903 0 0.00 0 22114 300 0 0
sp_kill_cost_storno 16082 16082 0 0.00 0 6408 60 0 0
sp_kill_qstorno_ret_qs2qd 1124223 1124223 0 0.00 0 80829 35 0 0
sp_kill_qty_storno 592004 592004 0 0.00 0 80830 75 0 0
sp_lock_selected_doc 20219 20219 0 0.00 0 8620 5 0 0
sp_make_cost_storno 27475 27475 0 0.00 0 18605 55 0 0
sp_multiply_rows_for_pdistr 27475 27475 0 0.00 0 7082 28 0 0
sp_multiply_rows_for_qdistr 20327 20327 0 0.00 0 24367 163 0 0
sp_pay_from_customer 7111 7111 0 0.00 4 24030 124 0 0
sp_pay_to_supplier 5716 5716 0 0.00 4 24475 155 0 0
sp_payment_common 12827 12827 0 0.00 1 24121 37 0 0
sp_qd_handle_on_cancel_clo 1661 1661 0 0.00 0 14525 392 0 0
sp_qd_handle_on_invoice_upd_sts 10487 10487 0 0.00 0 25194 333 0 0
sp_qd_handle_on_reserve_upd_sts 9745 9745 0 0.00 0 22685 66 0 0
sp_reserve_write_off 9745 9745 0 0.00 6 22699 186 0 0
sp_supplier_invoice 5538 5538 0 0.00 5 94160 3530 0 0
sp_supplier_order 5317 5317 0 0.00 5 38764 1297 0 0
srv_aggregate_perf_data 98 97 1 1.02 2351 532687 26125 0 1
srv_fill_mon_cache_memory 65 65 0 0.00 1294 131200 41476 0 0
srv_find_qd_qs_mism 1041359 1041359 0 0.00 0 14068 6 0 0
srv_make_invnt_saldo 580 580 0 0.00 60 136847 1205 0 0
srv_make_money_saldo 765 765 0 0.00 0 2042 28 0 0
srv_recalc_idx_stat 4 4 0 0.00 84776 236657 176202 0 0
t$perf-abend:sp_add_invoice_to_stock 84 84 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-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4905 4905 0 0.00 0 21597 172 0 0
t$perf-norm:sp_cancel_adding_invoice 425 425 0 0.00 0 2225 84 0 0
t$perf-norm:sp_cancel_client_order 1661 1661 0 0.00 0 9072 18 0 0
t$perf-norm:sp_cancel_customer_reserve 665 665 0 0.00 0 1035 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 643 643 0 0.00 0 5017 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 414 414 0 0.00 0 873 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 416 416 0 0.00 0 1322 10 0 0
t$perf-norm:sp_cancel_supplier_order 398 398 0 0.00 0 23758 1649 0 0
t$perf-norm:sp_cancel_write_off 949 949 0 0.00 0 5691 16 0 0
t$perf-norm:sp_client_order 20327 20327 0 0.00 0 10176 19 0 0
t$perf-norm:sp_customer_reserve 990 990 0 0.00 0 1198 4 0 0
t$perf-norm:sp_pay_from_customer 7111 7111 0 0.00 0 5741 12 0 0
t$perf-norm:sp_pay_to_supplier 5716 5716 0 0.00 0 9047 15 0 0
t$perf-norm:sp_reserve_write_off 9745 9745 0 0.00 0 14206 12 0 0
t$perf-norm:sp_supplier_invoice 5538 5538 0 0.00 0 10153 20 0 0
t$perf-norm:sp_supplier_order 5317 5317 0 0.00 0 10485 21 0 0
t$perf-norm:srv_aggregate_perf_data 97 97 0 0.00 0 227 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 78 2 0 0
t$perf-norm:srv_make_invnt_saldo 580 580 0 0.00 0 971 26 0 0
t$perf-norm:srv_make_money_saldo 765 765 0 0.00 0 1025 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 2 2 0 0
v_add_invoice_to_stock 9974 9806 168 1.68 0 5754 18 0 168
v_all_customers 36926 36926 0 0.00 0 17 1 0 0
v_all_suppliers 30252 30252 0 0.00 0 27 1 0 0
v_all_wares 40654 40654 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 850 850 0 0.00 0 4883 14 0 0
v_cancel_client_order 3322 3322 0 0.00 0 4718 7 0 0
v_cancel_customer_prepayment 1286 1286 0 0.00 0 1600 7 0 0
v_cancel_customer_reserve 1330 1330 0 0.00 0 4340 11 0 0
v_cancel_payment_to_supplier 828 828 0 0.00 0 525 4 0 0
v_cancel_supplier_invoice 856 832 24 2.80 0 2203 17 0 24
v_cancel_supplier_order 796 796 0 0.00 0 676 5 0 0
v_cancel_write_off 1898 1898 0 0.00 0 7428 21 0 0
v_max_id_clo_ord 5316 5316 0 0.00 0 450 1 0 0
v_max_id_clo_res 990 990 0 0.00 0 3890 28 0 0
v_max_id_ord_sup 5525 5525 0 0.00 0 5505 8 0 0
v_max_non_paid_invoice 1431 1431 0 0.00 0 345 2 0 0
v_max_non_paid_realizn 3876 3876 0 0.00 0 5339 5 0 0
v_min_id_clo_ord 5316 5316 0 0.00 0 16705 18 0 0
v_min_id_clo_res 990 990 0 0.00 0 6122 27 0 0
v_min_id_ord_sup 5525 5525 0 0.00 0 7732 15 0 0
v_min_non_paid_invoice 5716 5716 0 0.00 0 24116 29 0 0
v_min_non_paid_realizn 7111 7111 0 0.00 0 24020 27 0 0
v_our_firm 10198 10198 0 0.00 0 18 1 0 0
v_reserve_write_off 19490 19490 0 0.00 0 6127 10 0 0
x_lock_depdocs_on_canc_invoice 11328 11328 0 0.00 0 29471 124 0 0
x_lock_depdocs_on_canc_sup_ord 398 398 0 0.00 0 3865 343 0 0
x_make_qstorno_1000_1200 5317 5317 0 0.00 1 38755 1203 0 0
x_make_qstorno_1200_2000 5538 5538 0 0.00 3 87989 3367 0 0
x_make_qstorno_2100_3300 449588 449588 0 0.00 0 53711 177 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 14-12-2022 16:44:47 to 14-12-2022 16:44:51.

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:12:49 2 8192000000 8798893488 8810168320 5311392 0.064 2 1 2 0 5410992 56816 42784
14:13:50 27161 8192000000 9903569136 10215120896 982294512 10.707 100 91 183 0 1011811200 5679248 23837440
14:15:25 14542 8192000000 9942750608 10266058752 1028722928 11.156 100 87 172 0 1058165376 7217584 22224864
14:16:46 19966 8192000000 9984420928 10315169792 1071780880 11.569 100 85 170 0 1101802688 8832192 21189616
14:18:11 14694 8192000000 10028440624 10376519680 1118003024 12.008 100 72 144 1 1144642064 9659104 16979936
14:19:33 6519 8192000000 10079049184 10435813376 1164217152 12.443 100 74 148 0 1192504560 11175760 17111648
14:20:45 3324 8192000000 10114252736 10481152000 1202017888 12.795 100 77 154 0 1231642416 12077072 17547456
14:21:51 3175 8192000000 10148554816 10517852160 1232087680 13.073 100 80 155 0 1263343424 14555936 16699808
14:22:56 1384 8192000000 10173917200 10550824960 1259730368 13.328 100 96 188 0 1293263136 12556736 20976032
14:23:59 1370 8192000000 10192928720 10574966784 1279107472 13.505 100 86 173 2 1311794496 13552656 19134368
14:25:03 1214 8192000000 10212271152 10597957632 1295494560 13.654 100 91 183 0 1329565984 14492192 19579232
14:26:05 20918 8192000000 10205165744 10600665088 1299738880 13.693 100 56 111 0 1323237840 11551456 11947504
14:27:27 3981 8192000000 10200008784 10606125056 1305452304 13.745 100 38 74 0 1322861344 9593312 7815728
14:28:33 5723 8192000000 10201844352 10618880000 1316797984 13.848 100 17 32 0 1325637680 4970624 3869072
14:29:41 11580 8192000000 10208557520 10628689920 1325078464 13.923 100 18 36 0 1338146912 9401072 3667376
14:30:56 58411 8192000000 10239953568 10652311552 1332745408 13.992 100 30 59 0 1349568384 9363824 7459152
14:33:09 5288 8192000000 10198272944 10617806848 1283134864 13.542 97 28 56 1 1295590640 6597728 5858048
14:34:15 7098 8192000000 10198817808 10614452224 1301812128 13.712 99 18 34 0 1310906480 5374560 3719792
14:35:23 51493 8192000000 10201978800 10593841152 1279726736 13.511 99 50 92 0 1295147184 5001616 10418832
14:37:15 6936 8192000000 10126516528 10518048768 1203298560 12.807 97 27 54 0 1215016544 5510240 6207744
14:38:25 11731 8192000000 10150370432 10548490240 1243842320 13.182 99 23 48 0 1254093456 4778816 5472320
14:39:37 38808 8192000000 10148896832 10559197184 1246058000 13.202 98 35 69 0 1259520000 5180592 8281408
14:41:23 7684 8192000000 10179471360 10574389248 1274412128 13.462 99 27 54 0 1285973456 5234480 6326848
14:42:31 6828 8192000000 10213437936 10624176128 1313574464 13.818 100 14 28 0 1325242960 8590256 3078240
14:43:43 3174 8192000000 10251600272 10657091584 1334164976 14.005 100 57 112 0 1360308416 14047392 12096048
14:44:49 28224 8192000000 10241947456 10659368960 1341427600 14.070 100 48 94 0 1362545232 10304992 10812640
14:46:32 40682 8192000000 10260698544 10684137472 1353751632 14.181 100 43 86 1 1374449312 11153712 9543968
14:48:17 13802 8192000000 10280230912 10698698752 1374390576 14.366 100 34 68 0 1392216912 9707248 8119088
14:49:34 16120 8192000000 10296844864 10720620544 1388526128 14.493 100 46 92 0 1414241808 14625776 11089904
14:50:54 6968 8192000000 10311535360 10736607232 1396865392 14.567 100 53 106 0 1429075168 19135600 13074176
14:52:09 7123 8192000000 10322652176 10746454016 1402504720 14.617 100 59 116 1 1435930736 18900928 14525088
14:53:20 8090 8192000000 10320901376 10747998208 1401280640 14.606 100 48 92 0 1433366496 20716992 11368864
14:54:33 14400 8192000000 10340416624 10762711040 1409264608 14.677 100 54 106 0 1450426144 28440112 12721424
14:55:54 10012 8192000000 10319864800 10737836032 1381796448 14.433 98 54 106 0 1420009984 25417200 12796336
14:57:13 2980 8192000000 10320320320 10743746560 1391354432 14.518 100 54 107 0 1433651104 28514688 13781984
14:58:20 8721 8192000000 10337990160 10756136960 1396910576 14.567 100 76 150 0 1450353824 34351616 19091632
14:59:33 7346 8192000000 10354248080 10777968640 1412173648 14.703 100 59 114 1 1459808320 33137504 14497168
15:00:46 3489 8192000000 10363246832 10782670848 1409419056 14.679 100 69 135 1 1464292192 37350368 17522768
15:01:56 1530 8192000000 10364873792 10778013696 1403984192 14.630 99 83 164 0 1468903312 43991536 20927584
15:03:10 6720 8192000000 10361963456 10789781504 1418646064 14.761 100 48 96 0 1469690784 38387184 12657536
15:04:23 4461 8192000000 10355984528 10775543808 1393512016 14.537 99 58 116 0 1453794016 44937824 15344176
15:05:35 15094 8192000000 10328070160 10757025792 1360021120 14.238 98 39 78 1 1423126912 51948768 11157024
15:06:53 9474 8192000000 10318621360 10745700352 1372566448 14.350 99 45 90 1 1423742272 39093088 12082736
15:08:09 4649 8192000000 10354554288 10776207360 1392853840 14.531 100 51 101 0 1455238112 48375296 14008976
15:09:25 1288 8192000000 10366874176 10786840576 1397862400 14.576 100 61 123 0 1468661616 54477616 16321600
15:10:37 14703 8192000000 10343665392 10783920128 1396046784 14.560 100 26 52 0 1453163648 49561408 7555456
15:12:09 33840 8192000000 10313156304 10750590976 1355667376 14.198 98 19 38 0 1413958720 52740096 5551248
15:14:12 27665 8192000000 10279031792 10728218624 1365540000 14.287 100 22 44 0 1405636480 33842000 6254480
15:15:40 84674 8192000000 10292862768 10720542720 1344536448 14.098 98 26 51 0 1366380720 16237696 5606576
15:18:06 66296 8192000000 10286071152 10733285376 1340008128 14.057 97 17 34 0 1357181648 12704512 4469008
15:20:26 23984 8192000000 10271551520 10714439680 1351057520 14.157 98 34 68 0 1367691536 9350976 7283040
15:21:57 95190 8192000000 10263783952 10712924160 1332616224 13.991 97 8 16 0 1345441904 10859776 1965904
15:24:34 50780 8192000000 10298445040 10749763584 1389138240 14.498 100 16 33 3 1404037824 11392768 3506816
15:26:27 19624 8192000000 10314742672 10747527168 1381260368 14.428 99 51 100 1 1407072480 13669968 12142144
15:27:52 33757 8192000000 10300159248 10753597440 1395527264 14.555 100 38 71 4 1418399488 15432272 7439952
15:30:05 25461 8192000000 10282097376 10745790464 1387022576 14.479 100 8 16 0 1398136992 9071184 2043232
15:31:55 60228 8192000000 10292651680 10746441728 1375195664 14.374 99 19 36 0 1389277072 9075168 5006240
15:34:48 51607 8192000000 10285015952 10752212992 1386160816 14.472 100 10 20 0 1401277616 13210304 1906496
15:37:24 42243 8192000000 10281626656 10745643008 1366987536 14.300 98 7 14 0 1380956848 12566304 1403008
15:39:17 47186 8192000000 10291698784 10750205952 1365471648 14.286 98 15 30 0 1382280928 13094512 3714768
15:41:18 62467 8192000000 10293130704 10752618496 1377234288 14.392 99 15 30 0 1392183600 12034944 2914368
15:43:22 21475 8192000000 10314051600 10762932224 1399968960 14.595 100 47 93 0 1420709504 10706304 10034240
15:44:44 49545 8192000000 10268885040 10726219776 1340744528 14.064 97 24 48 1 1354058448 7874752 5439168
15:46:34 38340 8192000000 10304542912 10747510784 1379934416 14.416 100 33 66 1 1399960992 13087344 6939232
15:48:16 65140 8192000000 10279060336 10742218752 1345072608 14.103 97 11 20 1 1366572752 19182528 2317616
15:50:22 56027 8192000000 10255645168 10708910080 1319739712 13.874 97 29 58 0 1338843776 12812496 6291568
15:52:18 95121 8192000000 10258936112 10709577728 1327815344 13.947 98 18 35 0 1346849792 14999088 4035360
15:54:53 131200 8192000000 10264983024 10720686080 1352464576 14.170 99 17 34 0 1364027104 8162784 3399744
15:58:11 73447 8192000000 10251346320 10710450176 1340693232 14.064 98 21 42 0 1359714240 13709632 5311376
16:00:25 84106 8192000000 10267391952 10722426880 1316720640 13.847 96 18 36 0 1332368256 11085440 4562176
16:03:09 76515 8192000000 10281798144 10733010944 1348884656 14.137 98 16 32 0 1367730256 15120288 3725312
16:05:30 74081 8192000000 10289716304 10746056704 1361414096 14.250 98 24 48 0 1375288688 8858896 5015696
16:08:39 52459 8192000000 10276144528 10727927808 1351754048 14.163 99 20 40 0 1366698672 10946224 3998400
16:10:37 23418 8192000000 10286293264 10722304000 1365433216 14.286 100 38 76 0 1383650528 10228512 7988800
16:12:11 74635 8192000000 10270651856 10710388736 1315161664 13.833 96 18 35 1 1330285248 11428016 3695568
16:14:28 45969 8192000000 10304285904 10744631296 1351659344 14.162 98 35 70 0 1370421632 11672112 7090176
16:16:15 27586 8192000000 10297567168 10736914432 1367731888 14.307 99 24 48 0 1383156736 10168816 5256032
16:17:45 96102 8192000000 10298896240 10743521280 1360699296 14.244 98 31 63 0 1377524416 9728016 7097104
16:20:24 18334 8192000000 10284986032 10729955328 1363939088 14.273 99 39 80 0 1382147328 9470720 8737520
16:21:45 54872 8192000000 10278760032 10741350400 1375590784 14.377 99 12 24 1 1387294080 9206640 2496656
16:23:40 33193 8192000000 10277382704 10732879872 1347576800 14.126 97 10 18 0 1356658800 6708016 2373984
16:25:17 82564 8192000000 10291088800 10757648384 1394419568 14.545 100 17 35 0 1410358144 11887840 4050736
16:28:01 59818 8192000000 10289097984 10754179072 1361878256 14.254 98 11 22 0 1376373088 11551872 2942960
16:30:05 84645 8192000000 10304842560 10767601664 1379420064 14.411 99 10 20 0 1399464160 17891392 2152704
16:33:11 75258 8192000000 10288100320 10740396032 1354540864 14.188 98 11 23 1 1366964496 9803040 2620592
16:35:26 70704 8192000000 10276264544 10712535040 1308060768 13.768 95 25 50 0 1333210704 19403664 5746272
16:37:38 28696 8192000000 10278219504 10715131904 1358589936 14.225 100 25 50 0 1379801872 16023872 5188064
16:39:07 57018 8192000000 10278770304 10721488896 1322812432 13.902 97 39 78 0 1346157776 14844128 8501216
16:41:14 91533 8192000000 10212827520 10656587776 1299022640 13.686 95 14 28 0 1313730336 11888544 2819152

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 14-12-2022 16:44:53 to 14-12-2022 16:45:08.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 84 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 14-12-2022 16:45:09 to 14-12-2022 16:45:11.

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          231644
MON$OLDEST_ACTIVE               232728
MON$OLDEST_SNAPSHOT             232728
MON$NEXT_TRANSACTION            232728
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                       2019200
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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Wed Dec 14 16:42:54 2022
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:9: 
--- end of diff output ---

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

14.12.22 16:45:12. 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-14 16:45:15 No severe PSQL-related problems occured
14.12.22 16:45:16. End of report.