Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 08.05.2022 13:37
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 16321076 kB
MemAvailable 31451260 kB
Buffers 0 kB
Cached 15344948 kB
SwapCached 2548 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 4.0.2
DB_NAME                         /home/bases/oltp40-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/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/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=3400
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/oltp40-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-oltp40
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-05-08 13:37:13
DTS_END                         2022-05-08 13:37:13.7250
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(127:13) ==> X_MAKE_QSTORNO_2100_3300(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/34152
TRN_ID                          221632
ATT_ID                          608
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: 9098.48 22428 1091817
customer order: creation 172.83 477 20740
customer order: refuse 14.51 383 1741
order to supplier: creation 46.11 1350 5533
order to supplier: removal 3.25 169837 390
invoice (draft): creation 46.21 2837 5545
invoice (draft): removal 51.99 980 6239
invoice accept: apply 42.21 24947 5065
invoice accept: cancel 46.96 1113 5635
customer reserve: creation 3870.50 242 464460
customer reserve: removal 4504.38 96 540525
realization accept: apply 84.71 260 10165
realization accept: cancel 85.22 163 10226
payment from customer: creation 59.68 155 7162
payment from customer: removal 5.58 97 670
payment to supplier: creation 48.82 192 5858
payment to supplier: removal 3.26 131 391
service: total inventory turnovers 4.08 3918 490
service: total monetary turnovers 7.17 386 860
service: aggregate perf. data 0.98 13884 118
service: refresh index statistics 0.03 227118 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 08-05-2022 13:38:18 to 08-05-2022 13:38:20.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9255
TEST_TIME 2 7934
TEST_TIME 3 7379
TEST_TIME 4 6782
TEST_TIME 5 6739
TEST_TIME 6 6812
TEST_TIME 7 6857
TEST_TIME 8 6810
TEST_TIME 9 6852
TEST_TIME 10 7013
TEST_TIME 11 7337
TEST_TIME 12 7392
TEST_TIME 13 7478
TEST_TIME 14 7659
TEST_TIME 15 7559
TEST_TIME 16 7665
TEST_TIME 17 7659
TEST_TIME 18 7880
TEST_TIME 19 8238
TEST_TIME 20 8220
TEST_TIME 21 8397
TEST_TIME 22 8278
TEST_TIME 23 8357
TEST_TIME 24 8358
TEST_TIME 25 8844
TEST_TIME 26 8793
TEST_TIME 27 9524
TEST_TIME 28 10320
TEST_TIME 29 10634
TEST_TIME 30 10863
TEST_TIME 31 10928
TEST_TIME 32 11032
TEST_TIME 33 11266
TEST_TIME 34 11348
TEST_TIME 35 11275
TEST_TIME 36 11139
TEST_TIME 37 11039
TEST_TIME 38 10890
TEST_TIME 39 10808
TEST_TIME 40 10620
TEST_TIME 41 10615
TEST_TIME 42 10458
TEST_TIME 43 10382
TEST_TIME 44 10301
TEST_TIME 45 10212
TEST_TIME 46 10154
TEST_TIME 47 10204
TEST_TIME 48 10155
TEST_TIME 49 10113
TEST_TIME 50 10022
TEST_TIME 51 10144
TEST_TIME 52 10077
TEST_TIME 53 10155
TEST_TIME 54 10181
TEST_TIME 55 10140
TEST_TIME 56 10116
TEST_TIME 57 10134
TEST_TIME 58 10178
TEST_TIME 59 10183
TEST_TIME 60 10163
TEST_TIME 61 10058
TEST_TIME 62 10091
TEST_TIME 63 10214
TEST_TIME 64 10141
TEST_TIME 65 10007
TEST_TIME 66 9959
TEST_TIME 67 9877
TEST_TIME 68 9841
TEST_TIME 69 9792
TEST_TIME 70 9708
TEST_TIME 71 9666
TEST_TIME 72 9646
TEST_TIME 73 9572
TEST_TIME 74 9482
TEST_TIME 75 9536
TEST_TIME 76 9514
TEST_TIME 77 9536
TEST_TIME 78 9529
TEST_TIME 79 9529
TEST_TIME 80 9500
TEST_TIME 81 9471
TEST_TIME 82 9463
TEST_TIME 83 9510
TEST_TIME 84 9625
TEST_TIME 85 9720
TEST_TIME 86 9733
TEST_TIME 87 9714
TEST_TIME 88 9658
TEST_TIME 89 9611
TEST_TIME 90 9578
TEST_TIME 91 9535
TEST_TIME 92 9549
TEST_TIME 93 9469
TEST_TIME 94 9451
TEST_TIME 95 9482
TEST_TIME 96 9478
TEST_TIME 97 9477
TEST_TIME 98 9479
TEST_TIME 99 9518
TEST_TIME 100 9515
TEST_TIME 101 9520
TEST_TIME 102 9462
TEST_TIME 103 9438
TEST_TIME 104 9537
TEST_TIME 105 9516
TEST_TIME 106 9537
TEST_TIME 107 9522
TEST_TIME 108 9454
TEST_TIME 109 9426
TEST_TIME 110 9418
TEST_TIME 111 9374
TEST_TIME 112 9326
TEST_TIME 113 9279
TEST_TIME 114 9261
TEST_TIME 115 9240
TEST_TIME 116 9212
TEST_TIME 117 9221
TEST_TIME 118 9222
TEST_TIME 119 9191
TEST_TIME 120 9124

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 08-05-2022 13:38:22 to 08-05-2022 13:38:25.

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 1521048 1521048 0 0.00 0 20995 13 0 0
doc_list_biud 1521037 1521037 0 0.00 0 52874 40 0 0
sp_add_invoice_to_stock 5176 5065 111 2.14 67 203977 24948 0 111
sp_cancel_adding_invoice 5635 5635 0 0.00 7 67164 1113 0 0
sp_cancel_client_order 1741 1741 0 0.00 5 12122 383 0 0
sp_cancel_customer_reserve 540525 540525 0 0.00 1 52879 97 0 0
sp_cancel_pay_from_customer 670 670 0 0.00 4 3523 98 0 0
sp_cancel_pay_to_supplier 391 391 0 0.00 4 9644 131 0 0
sp_cancel_supplier_invoice 6247 6239 8 0.12 3 41605 981 0 8
sp_cancel_supplier_order 390 390 0 0.00 7 1370949 169837 0 0
sp_cancel_write_off 10226 10226 0 0.00 2 21149 163 0 0
sp_client_order 20740 20740 0 0.00 6 51521 477 0 0
sp_customer_reserve 464460 464460 0 0.00 1 90277 242 0 0
sp_fill_shopping_cart 31805 31805 0 0.00 1 11990 69 0 0
sp_fill_shopping_cart_clo_res 464426 464426 0 0.00 0 14606 18 0 0
sp_get_clo_for_invoice 5025 5025 0 0.00 0 37918 284 0 0
sp_kill_cost_storno 16922 16922 0 0.00 0 20722 65 0 0
sp_kill_qstorno_ret_qs2qd 1097905 1097905 0 0.00 0 52859 43 0 0
sp_kill_qty_storno 579944 579944 0 0.00 0 52874 97 0 0
sp_lock_selected_doc 20906 20906 0 0.00 0 9347 8 0 0
sp_make_cost_storno 28209 28209 0 0.00 0 20942 76 0 0
sp_multiply_rows_for_pdistr 28209 28209 0 0.00 0 11067 32 0 0
sp_multiply_rows_for_qdistr 20740 20740 0 0.00 0 51478 183 0 0
sp_pay_from_customer 7162 7162 0 0.00 5 16562 156 0 0
sp_pay_to_supplier 5858 5858 0 0.00 6 15175 193 0 0
sp_payment_common 13019 13019 0 0.00 2 8837 39 0 0
sp_qd_handle_on_cancel_clo 1741 1741 0 0.00 0 12097 295 0 0
sp_qd_handle_on_invoice_upd_sts 10658 10658 0 0.00 0 19126 458 0 0
sp_qd_handle_on_reserve_upd_sts 10165 10165 0 0.00 0 22712 59 0 0
sp_reserve_write_off 10165 10165 0 0.00 9 22750 261 0 0
sp_supplier_invoice 5545 5545 0 0.00 10 97737 2837 0 0
sp_supplier_order 5533 5533 0 0.00 7 22173 1351 0 0
srv_aggregate_perf_data 118 118 0 0.00 2469 120507 13885 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 763 143473 37751 0 0
srv_find_qd_qs_mism 1043471 1043471 0 0.00 0 16212 11 0 0
srv_make_invnt_saldo 490 490 0 0.00 50 118910 3918 0 0
srv_make_money_saldo 860 860 0 0.00 1 22962 386 0 0
srv_recalc_idx_stat 4 4 0 0.00 72423 366815 227118 0 0
t$perf-abend:sp_add_invoice_to_stock 111 111 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5072 5072 0 0.00 1 22917 826 0 0
t$perf-norm:sp_cancel_adding_invoice 403 403 0 0.00 1 6609 387 0 0
t$perf-norm:sp_cancel_client_order 1741 1741 0 0.00 0 2651 24 0 0
t$perf-norm:sp_cancel_customer_reserve 731 731 0 0.00 0 730 24 0 0
t$perf-norm:sp_cancel_pay_from_customer 670 670 0 0.00 0 10641 27 0 0
t$perf-norm:sp_cancel_pay_to_supplier 391 391 0 0.00 0 586 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 396 396 0 0.00 0 822 30 0 0
t$perf-norm:sp_cancel_supplier_order 390 390 0 0.00 0 97677 6455 0 0
t$perf-norm:sp_cancel_write_off 1005 1005 0 0.00 0 4509 22 0 0
t$perf-norm:sp_client_order 20740 20740 0 0.00 0 4855 32 0 0
t$perf-norm:sp_customer_reserve 968 968 0 0.00 0 1218 14 0 0
t$perf-norm:sp_pay_from_customer 7162 7162 0 0.00 0 6552 17 0 0
t$perf-norm:sp_pay_to_supplier 5858 5858 0 0.00 0 4386 16 0 0
t$perf-norm:sp_reserve_write_off 10167 10167 0 0.00 0 20818 23 0 0
t$perf-norm:sp_supplier_invoice 5545 5545 0 0.00 0 25181 31 0 0
t$perf-norm:sp_supplier_order 5533 5533 0 0.00 0 7515 29 0 0
t$perf-norm:srv_aggregate_perf_data 118 118 0 0.00 0 370 19 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 1482 28 0 0
t$perf-norm:srv_make_invnt_saldo 490 490 0 0.00 0 4629 34 0 0
t$perf-norm:srv_make_money_saldo 860 860 0 0.00 0 230 3 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 5 210 70 0 0
v_add_invoice_to_stock 10252 10030 222 2.16 0 9762 24 0 222
v_all_customers 38502 38502 0 0.00 0 112 3 0 0
v_all_suppliers 30872 30872 0 0.00 0 62 3 0 0
v_all_wares 41471 41471 0 0.00 0 101 3 0 0
v_cancel_adding_invoice 806 806 0 0.00 0 2505 14 0 0
v_cancel_client_order 3482 3482 0 0.00 0 7359 12 0 0
v_cancel_customer_prepayment 1340 1340 0 0.00 0 659 9 0 0
v_cancel_customer_reserve 1462 1462 0 0.00 0 1265 9 0 0
v_cancel_payment_to_supplier 782 782 0 0.00 0 9589 29 0 0
v_cancel_supplier_invoice 808 792 16 1.98 0 4674 24 0 16
v_cancel_supplier_order 780 780 0 0.00 0 2493 15 0 0
v_cancel_write_off 2010 2010 0 0.00 0 5782 29 0 0
v_max_id_clo_ord 5528 5528 0 0.00 0 1324 4 0 0
v_max_id_clo_res 968 968 0 0.00 0 7399 27 0 0
v_max_id_ord_sup 5538 5538 0 0.00 0 9254 8 0 0
v_max_non_paid_invoice 1486 1486 0 0.00 0 1439 8 0 0
v_max_non_paid_realizn 3515 3515 0 0.00 0 8302 10 0 0
v_min_id_clo_ord 5528 5528 0 0.00 0 3519 8 0 0
v_min_id_clo_res 968 968 0 0.00 0 4764 11 0 0
v_min_id_ord_sup 5538 5538 0 0.00 0 5189 9 0 0
v_min_non_paid_invoice 5858 5858 0 0.00 0 7802 15 0 0
v_min_non_paid_realizn 7161 7161 0 0.00 0 8287 14 0 0
v_our_firm 10258 10258 0 0.00 0 93 4 0 0
v_reserve_write_off 20328 20328 0 0.00 0 8473 11 0 0
x_lock_depdocs_on_canc_invoice 11478 11478 0 0.00 0 23025 129 0 0
x_lock_depdocs_on_canc_sup_ord 390 390 0 0.00 0 12511 344 0 0
x_make_qstorno_1000_1200 5533 5533 0 0.00 3 22112 1206 0 0
x_make_qstorno_1200_2000 5545 5545 0 0.00 5 97700 2658 0 0
x_make_qstorno_2100_3300 464460 464460 0 0.00 0 90267 213 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 08-05-2022 13:38:26 to 08-05-2022 13:38:30.

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
11:07:17 884 8192000000 8929028240 8986890240 47353360 0.574 9 8 17 0 49250736 341632 1555744
11:08:20 30996 8192000000 9714779024 10008453120 778810576 8.681 100 88 176 0 808715648 7895104 22009968
11:09:53 23397 8192000000 9745375872 10048692224 814403984 9.042 100 85 170 0 846949552 11805360 20740208
11:11:19 20382 8192000000 9789121680 10108440576 852474336 9.425 100 73 146 1 891874080 22496896 16902848
11:12:42 13058 8192000000 9866497232 10202742784 913971904 10.037 100 79 158 0 970288928 39222768 17094256
11:14:00 4153 8192000000 9913205200 10255794176 947607664 10.368 100 86 169 0 1019450944 54102560 17740720
11:15:07 3090 8192000000 9964177376 10313465856 984840336 10.731 100 90 180 2 1073090880 70147584 18102960
11:16:14 1122 8192000000 9988561824 10350743552 1007052960 10.947 100 92 176 2 1098633488 73574656 18005872
11:17:18 1085 8192000000 10019153248 10389757952 1032873104 11.196 100 91 178 0 1127294432 76562448 17858880
11:18:21 1136 8192000000 10017540496 10392662016 1043528048 11.299 100 90 176 1 1130422960 69861424 17033488
11:19:23 1210 8192000000 10026260848 10403725312 1046691616 11.329 100 96 184 2 1138716656 73584816 18440224
11:20:26 1194 8192000000 10042144096 10427441152 1055046336 11.409 100 79 155 0 1155482992 84304320 16132336
11:21:28 1084 8192000000 10050767104 10435026944 1060898512 11.465 100 87 169 0 1164898224 86232912 17766800
11:22:31 1210 8192000000 10046092688 10427801600 1068363984 11.536 100 84 160 1 1149625616 65389504 15872128
11:23:33 1170 8192000000 10051748688 10435969024 1075587344 11.605 100 97 186 1 1162284352 68327248 18369760
11:24:37 995 8192000000 10081448080 10469019648 1082171216 11.668 100 92 182 0 1194250064 93952000 18126848
11:25:45 991 8192000000 10044833408 10434068480 1082860608 11.675 100 88 166 1 1154560224 53877216 17822400
11:26:47 1039 8192000000 10040713856 10424066048 1083205184 11.678 100 93 181 0 1146172224 45191008 17776032
11:27:50 1165 8192000000 10082264256 10469752832 1086883456 11.713 100 87 169 1 1187790752 83152160 17755136
11:28:52 3339 8192000000 10051635936 10441875456 1087061760 11.715 100 73 138 0 1166121184 65733520 13325904
11:29:56 2329 8192000000 10046721904 10433667072 1088033568 11.724 100 82 156 3 1166287600 62858144 15395888
11:31:00 941 8192000000 10050782672 10432925696 1082198960 11.668 100 92 178 2 1176602128 74911264 19491904
11:32:01 1064 8192000000 10028945232 10398408704 1048399344 11.345 100 97 187 0 1145119104 78139184 18580576
11:33:04 1280 8192000000 10011140608 10386071552 1053897648 11.398 100 80 147 2 1133492608 65627360 13967600
11:34:06 917 8192000000 9984215584 10364047360 1045545680 11.318 100 86 165 1 1112612080 49266656 17799744
11:35:08 1014 8192000000 9984662288 10351816704 1019336976 11.066 99 95 178 1 1104540000 66965344 18237680
11:36:10 1026 8192000000 9945409008 10318217216 1013111360 11.005 99 74 134 1 1070412944 43951152 13350432
11:37:12 1040 8192000000 9987664208 10362875904 1040397024 11.268 100 90 175 2 1108004224 50419792 17187408
11:38:21 15777 8192000000 10013028976 10389204992 1052120928 11.381 100 83 141 1 1128099472 62969088 13009456
11:39:40 3429 8192000000 10051227856 10431586304 1071579808 11.567 100 88 162 0 1171498640 83571824 16347008
11:40:48 1542 8192000000 10074204320 10459807744 1083291184 11.679 100 90 166 1 1189861952 88827648 17743120
11:41:53 3684 8192000000 10109603024 10498002944 1101947104 11.856 100 76 132 4 1222792704 107220848 13624752
11:43:03 2887 8192000000 10159369136 10556547072 1118565616 12.013 100 79 151 2 1276077568 139871936 17640016
11:44:16 7489 8192000000 10177948032 10584842240 1134245136 12.161 100 77 136 5 1286783984 137942368 14596480
11:45:30 1384 8192000000 10204704480 10601459712 1142919776 12.243 100 84 155 2 1320087664 159530896 17636992
11:46:36 3312 8192000000 10249021952 10656641024 1151081008 12.320 100 85 164 0 1365590240 195931856 18577376
11:47:55 755 8192000000 10264309504 10670084096 1169550592 12.493 100 93 185 0 1371863168 181371648 20940928
11:48:58 1873 8192000000 10320848816 10724380672 1176578512 12.558 100 91 179 0 1430199136 233056880 20563744
11:50:03 957 8192000000 10339661200 10751299584 1186898384 12.654 100 96 182 0 1449192592 241491680 20802528
11:51:07 779 8192000000 10397437696 10803445760 1199084624 12.768 100 89 174 1 1503501408 284475296 19941488
11:52:12 1478 8192000000 10432685600 10836193280 1194214048 12.723 100 95 185 1 1539850896 324417024 21219824
11:53:16 1201 8192000000 10472272512 10881138688 1182723536 12.616 99 95 182 1 1579141024 375410256 21007232
11:54:20 911 8192000000 10495972928 10918510592 1183651200 12.624 100 96 185 0 1604532128 399194400 21686528
11:55:24 1019 8192000000 10537314064 10983448576 1206701280 12.839 100 97 189 1 1644865984 414636928 23527776
11:56:27 7860 8192000000 10519386800 10952835072 1204668512 12.820 100 63 125 1 1623455104 403188112 15598480
11:57:35 7807 8192000000 10553453136 10980356096 1201037728 12.786 100 58 116 1 1651935680 435930784 14967168
11:58:51 14649 8192000000 10547525568 10991583232 1195323616 12.733 100 45 86 3 1658057200 451468864 11264720
12:00:08 3143 8192000000 10563293744 11025092608 1190052352 12.684 100 52 104 1 1671165968 467548784 13564832
12:01:22 12854 8192000000 10622270688 11056238592 1179812672 12.588 100 42 84 0 1708184976 517066432 11305872
12:02:36 13194 8192000000 10563704704 11050500096 1166185264 12.461 99 44 88 1 1657022336 478958096 11878976
12:03:51 14295 8192000000 10478994416 11022299136 1140658944 12.222 99 37 74 1 1590025968 438218688 11148336
12:05:06 8733 8192000000 10379481024 10891120640 1149576704 12.306 100 46 90 1 1490884448 330279280 11028464
12:06:23 30558 8192000000 10222777072 10710814720 1148272000 12.293 100 37 74 2 1375917056 218429920 9215136
12:07:55 18590 8192000000 10224602864 10670178304 1154848704 12.355 100 23 44 3 1311821120 152301936 4670480
12:09:14 35351 8192000000 10168612304 10638610432 1138049872 12.197 99 16 32 3 1280691040 139202128 3439040
12:10:51 22600 8192000000 10093409840 10555785216 1149246640 12.302 100 25 49 3 1202339104 46967104 6125360
12:12:15 56765 8192000000 10073974688 10497335296 1137377568 12.191 99 48 84 6 1181862000 34105152 10379280
12:14:17 76118 8192000000 10097525440 10562076672 1095048128 11.791 96 30 60 0 1153352496 52240240 6064128
12:18:04 99003 8192000000 10101559728 10531807232 1117204944 12.001 99 35 65 1 1168240208 44341600 6693664
12:20:44 14081 8192000000 10111516032 10546491392 1125585616 12.080 99 43 87 1 1207087824 72436608 9065600
12:22:11 27835 8192000000 10185023376 10631168000 1138848800 12.205 99 38 76 0 1268472944 121526784 8097360
12:23:53 41833 8192000000 10211999904 10693136384 1151335056 12.322 100 21 42 0 1324743168 168130080 5278032
12:25:52 63388 8192000000 10154287072 10610577408 1114050032 11.971 98 21 42 0 1257436688 138173568 5213088
12:27:59 106845 8192000000 10130377808 10607386624 1101395952 11.851 97 24 46 2 1243146128 136205760 5544416
12:30:46 53834 8192000000 10042359872 10507915264 1103890512 11.875 98 18 34 0 1142810992 35094304 3826176
12:32:40 10289 8192000000 10080332720 10510376960 1119651232 12.024 99 42 84 0 1179624432 52057984 7915216
12:33:52 34619 8192000000 10087814864 10525167616 1104628080 11.882 98 39 76 3 1186596544 74283264 7685200
12:35:27 143472 8192000000 10056412352 10519703552 1069085200 11.543 95 12 24 0 1120415696 48552624 2777872
12:38:57 52968 8192000000 10120927536 10590478336 1125419392 12.078 100 25 50 0 1228358128 97820048 5118688
12:40:50 56812 8192000000 10058090016 10495369216 1098455952 11.823 97 19 38 0 1131635712 29393184 3786576
12:43:02 114769 8192000000 10073851952 10521460736 1117784048 12.006 99 36 72 2 1171274592 45751776 7738768
12:45:58 56615 8192000000 10077943088 10542739456 1104553840 11.881 97 23 44 1 1173923904 64297360 5072704
12:48:03 105518 8192000000 10145859456 10595471360 1110807984 11.940 97 20 40 0 1209847232 94579808 4459440
12:50:49 83437 8192000000 10107920288 10597150720 1139393312 12.210 100 11 22 0 1227286144 85305584 2587248
12:53:23 67993 8192000000 10077686032 10530414592 1088579536 11.729 97 16 32 1 1190763568 98476192 3707840
12:55:31 36700 8192000000 10115332944 10593796096 1137188320 12.189 100 22 44 0 1219587264 77193648 5205296
12:57:16 22815 8192000000 10174104160 10612461568 1130114272 12.122 98 39 78 0 1252683920 114205408 8364240
12:58:46 40214 8192000000 10224692912 10689224704 1152200512 12.330 100 44 85 2 1328569360 166861696 9507152
13:00:33 51776 8192000000 10167009056 10670313472 1132935504 12.149 99 38 76 0 1308021904 167015168 8071232
13:02:40 51141 8192000000 10128107712 10599555072 1122517120 12.051 98 28 56 2 1184918352 56794784 5606448
13:04:32 117494 8192000000 10077978688 10518499328 1113090704 11.962 98 33 64 1 1154560576 34779184 6690688
13:07:51 4301 8192000000 10119709584 10576834560 1138323920 12.200 100 32 60 1 1223480512 78860624 6295968
13:08:55 76383 8192000000 10116550496 10628042752 1134466768 12.163 99 26 52 0 1255445712 115185920 5793024
13:11:21 42175 8192000000 10091919680 10551840768 1125790160 12.082 99 16 31 3 1198071856 68671008 3610688
13:13:05 42892 8192000000 10111876960 10572361728 1143903216 12.252 100 28 47 3 1203307952 52919776 6484960
13:14:48 46270 8192000000 10128169216 10571571200 1134138832 12.160 99 24 49 0 1198395008 58583024 5673152
13:16:44 43477 8192000000 10064254000 10515865600 1106874448 11.903 97 26 52 1 1167850208 55856640 5119120
13:18:29 50620 8192000000 10115032992 10566303744 1114090688 11.971 98 35 70 1 1211035584 89908400 7036496
13:20:20 24470 8192000000 10146738768 10589908992 1130622208 12.127 99 43 84 0 1233739840 93728752 9388880
13:21:46 57604 8192000000 10111567632 10551087104 1117001008 11.999 98 40 80 0 1182219456 57514448 7704000
13:24:01 27427 8192000000 10103256720 10538504192 1121783120 12.044 100 41 80 0 1203661120 72704432 9173568
13:25:32 72253 8192000000 10072999568 10534690816 1110885856 11.941 99 38 76 0 1178671488 60085008 7700624
13:27:48 60839 8192000000 10029530224 10475069440 1090220656 11.745 97 9 17 1 1121675328 29715360 1739312
13:29:57 80880 8192000000 10050623392 10521387008 1109810576 11.931 99 15 30 1 1153009856 40061776 3137504
13:32:20 66980 8192000000 10037493344 10492395520 1062997120 11.485 96 13 27 1 1092101392 26164560 2939712
13:34:29 46167 8192000000 10045096272 10487414784 1110517280 11.937 100 25 48 1 1150008000 33972352 5518368
13:36:20 37453 8192000000 10067688624 10502094848 1093391984 11.775 98 34 68 1 1143310144 43125120 6793040

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 08-05-2022 13:38:32 to 08-05-2022 13:38:48.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 111 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 08-05-2022 13:38:49 to 08-05-2022 13:38:51.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          218696
MON$OLDEST_ACTIVE               221798
MON$OLDEST_SNAPSHOT             221798
MON$NEXT_TRANSACTION            221798
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-30 07:30:08.6030 GMT
MON$PAGES                       2055392
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             781
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2763 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2763 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2763 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2763 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.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 ---
:128: 
:129: mscs-test-161.budzdorov.ru    Sun May  8 13:37:18 2022
:130:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:131: 
:132: 
:133: mscs-test-161.budzdorov.ru    Sun May  8 13:37:19 2022
:134:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:135: 
:136: 
:137: mscs-test-161.budzdorov.ru    Sun May  8 13:37:20 2022
:138:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:139: 
:140: 
:141: mscs-test-161.budzdorov.ru    Sun May  8 13:37:20 2022
:142:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:143: 
--- end of diff output ---

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

08.05.22 13:38:52. There are 3 logs in /var/tmp/logs-oltp40 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-05-08 13:38:55 No severe PSQL-related problems occured
08.05.22 13:38:56. End of report.