Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 03.01.2022 10: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 16348532 kB
MemAvailable 31319436 kB
Buffers 0 kB
Cached 15312452 kB
SwapCached 1196 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.1
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-01-03 10:37:11
DTS_END                         2022-01-03 10:37:11.7540
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/33666
TRN_ID                          238662
ATT_ID                          648
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: 9082.68 22515 1089922
customer order: creation 181.31 413 21757
customer order: refuse 15.55 374 1866
order to supplier: creation 48.30 1332 5796
order to supplier: removal 3.37 108065 404
invoice (draft): creation 48.18 3460 5782
invoice (draft): removal 49.60 871 5952
invoice accept: apply 44.47 20001 5336
invoice accept: cancel 45.20 870 5424
customer reserve: creation 3978.38 205 477405
customer reserve: removal 4368.17 66 524180
realization accept: apply 87.00 190 10440
realization accept: cancel 80.09 131 9611
payment from customer: creation 60.74 114 7289
payment from customer: removal 5.71 105 685
payment to supplier: creation 52.03 127 6244
payment to supplier: removal 3.68 89 442
service: total inventory turnovers 3.77 3671 452
service: total monetary turnovers 6.23 573 748
service: aggregate perf. data 0.88 16170 105
service: refresh index statistics 0.03 293473 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 03-01-2022 10:37:55 to 03-01-2022 10:37:57.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3594
TEST_TIME 2 4350
TEST_TIME 3 4151
TEST_TIME 4 4797
TEST_TIME 5 5392
TEST_TIME 6 6480
TEST_TIME 7 6505
TEST_TIME 8 6385
TEST_TIME 9 6715
TEST_TIME 10 6637
TEST_TIME 11 6709
TEST_TIME 12 6497
TEST_TIME 13 6959
TEST_TIME 14 7204
TEST_TIME 15 7615
TEST_TIME 16 7899
TEST_TIME 17 7943
TEST_TIME 18 8181
TEST_TIME 19 8225
TEST_TIME 20 8771
TEST_TIME 21 8694
TEST_TIME 22 9020
TEST_TIME 23 9358
TEST_TIME 24 9461
TEST_TIME 25 9586
TEST_TIME 26 9669
TEST_TIME 27 10120
TEST_TIME 28 10890
TEST_TIME 29 11006
TEST_TIME 30 10975
TEST_TIME 31 10816
TEST_TIME 32 10707
TEST_TIME 33 10703
TEST_TIME 34 10513
TEST_TIME 35 10284
TEST_TIME 36 10146
TEST_TIME 37 10166
TEST_TIME 38 10025
TEST_TIME 39 9935
TEST_TIME 40 9895
TEST_TIME 41 9952
TEST_TIME 42 9834
TEST_TIME 43 9750
TEST_TIME 44 9784
TEST_TIME 45 9864
TEST_TIME 46 9766
TEST_TIME 47 9768
TEST_TIME 48 9714
TEST_TIME 49 9699
TEST_TIME 50 9661
TEST_TIME 51 9601
TEST_TIME 52 9642
TEST_TIME 53 9724
TEST_TIME 54 9719
TEST_TIME 55 9657
TEST_TIME 56 9859
TEST_TIME 57 9826
TEST_TIME 58 9846
TEST_TIME 59 9863
TEST_TIME 60 9732
TEST_TIME 61 9622
TEST_TIME 62 9657
TEST_TIME 63 9565
TEST_TIME 64 9542
TEST_TIME 65 9542
TEST_TIME 66 9465
TEST_TIME 67 9413
TEST_TIME 68 9426
TEST_TIME 69 9457
TEST_TIME 70 9492
TEST_TIME 71 9488
TEST_TIME 72 9481
TEST_TIME 73 9577
TEST_TIME 74 9522
TEST_TIME 75 9513
TEST_TIME 76 9504
TEST_TIME 77 9597
TEST_TIME 78 9665
TEST_TIME 79 9631
TEST_TIME 80 9587
TEST_TIME 81 9510
TEST_TIME 82 9463
TEST_TIME 83 9471
TEST_TIME 84 9420
TEST_TIME 85 9364
TEST_TIME 86 9349
TEST_TIME 87 9317
TEST_TIME 88 9280
TEST_TIME 89 9247
TEST_TIME 90 9221
TEST_TIME 91 9186
TEST_TIME 92 9307
TEST_TIME 93 9309
TEST_TIME 94 9325
TEST_TIME 95 9293
TEST_TIME 96 9318
TEST_TIME 97 9368
TEST_TIME 98 9366
TEST_TIME 99 9407
TEST_TIME 100 9425
TEST_TIME 101 9397
TEST_TIME 102 9386
TEST_TIME 103 9328
TEST_TIME 104 9274
TEST_TIME 105 9248
TEST_TIME 106 9193
TEST_TIME 107 9166
TEST_TIME 108 9115
TEST_TIME 109 9088
TEST_TIME 110 9102
TEST_TIME 111 9153
TEST_TIME 112 9135
TEST_TIME 113 9140
TEST_TIME 114 9141
TEST_TIME 115 9139
TEST_TIME 116 9155
TEST_TIME 117 9145
TEST_TIME 118 9135
TEST_TIME 119 9144
TEST_TIME 120 9086

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 03-01-2022 10:37:59 to 03-01-2022 10:38:02.

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 1530937 1530937 0 0.00 0 21598 7 0 0
doc_list_biud 1530937 1530937 0 0.00 0 28336 29 0 0
sp_add_invoice_to_stock 5423 5336 87 1.60 33 218062 20001 0 87
sp_cancel_adding_invoice 5424 5424 0 0.00 6 67567 870 0 0
sp_cancel_client_order 1866 1866 0 0.00 5 16748 375 0 0
sp_cancel_customer_reserve 524180 524180 0 0.00 1 26621 66 0 0
sp_cancel_pay_from_customer 685 685 0 0.00 3 9325 105 0 0
sp_cancel_pay_to_supplier 442 442 0 0.00 3 9059 90 0 0
sp_cancel_supplier_invoice 5958 5952 6 0.10 2 28346 872 0 6
sp_cancel_supplier_order 404 404 0 0.00 6 1190190 108065 0 0
sp_cancel_write_off 9611 9611 0 0.00 1 12515 132 0 0
sp_client_order 21757 21757 0 0.00 4 36153 414 0 0
sp_customer_reserve 477405 477405 0 0.00 0 55167 205 0 0
sp_fill_shopping_cart 33322 33322 0 0.00 1 37872 44 0 0
sp_fill_shopping_cart_clo_res 477388 477388 0 0.00 0 11688 15 0 0
sp_get_clo_for_invoice 5319 5319 0 0.00 0 17886 284 0 0
sp_kill_cost_storno 16162 16162 0 0.00 0 13833 63 0 0
sp_kill_qstorno_ret_qs2qd 1064327 1064327 0 0.00 0 28332 36 0 0
sp_kill_qty_storno 563196 563196 0 0.00 0 28336 78 0 0
sp_lock_selected_doc 21654 21654 0 0.00 0 9054 6 0 0
sp_make_cost_storno 29292 29292 0 0.00 0 15066 50 0 0
sp_multiply_rows_for_pdistr 29292 29292 0 0.00 0 7582 33 0 0
sp_multiply_rows_for_qdistr 21757 21757 0 0.00 0 17344 204 0 0
sp_pay_from_customer 7289 7289 0 0.00 4 15278 114 0 0
sp_pay_to_supplier 6244 6244 0 0.00 5 14761 128 0 0
sp_payment_common 13533 13533 0 0.00 1 11390 24 0 0
sp_qd_handle_on_cancel_clo 1866 1866 0 0.00 0 16740 343 0 0
sp_qd_handle_on_invoice_upd_sts 10743 10743 0 0.00 0 21132 350 0 0
sp_qd_handle_on_reserve_upd_sts 10440 10440 0 0.00 0 21986 66 0 0
sp_reserve_write_off 10440 10440 0 0.00 6 22046 190 0 0
sp_supplier_invoice 5782 5782 0 0.00 6 78798 3460 0 0
sp_supplier_order 5796 5796 0 0.00 6 55312 1333 0 0
srv_aggregate_perf_data 105 105 0 0.00 2406 277015 16171 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 1789 137977 43653 0 0
srv_find_qd_qs_mism 1039743 1039743 0 0.00 0 21597 7 0 0
srv_make_invnt_saldo 452 452 0 0.00 52 100570 3671 0 0
srv_make_money_saldo 748 748 0 0.00 1 15453 574 0 0
srv_recalc_idx_stat 4 4 0 0.00 216298 475764 293473 0 0
t$perf-abend:sp_add_invoice_to_stock 87 87 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5337 5337 0 0.00 0 20318 179 0 0
t$perf-norm:sp_cancel_adding_invoice 429 429 0 0.00 0 31070 239 0 0
t$perf-norm:sp_cancel_client_order 1866 1866 0 0.00 0 8262 17 0 0
t$perf-norm:sp_cancel_customer_reserve 708 708 0 0.00 0 1324 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 685 685 0 0.00 0 725 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 442 442 0 0.00 0 97 1 0 0
t$perf-norm:sp_cancel_supplier_invoice 373 373 0 0.00 0 1343 25 0 0
t$perf-norm:sp_cancel_supplier_order 404 404 0 0.00 0 37658 1624 0 0
t$perf-norm:sp_cancel_write_off 988 988 0 0.00 0 3071 13 0 0
t$perf-norm:sp_client_order 21757 21757 0 0.00 0 20781 20 0 0
t$perf-norm:sp_customer_reserve 1080 1080 0 0.00 0 16756 22 0 0
t$perf-norm:sp_pay_from_customer 7289 7289 0 0.00 0 25487 14 0 0
t$perf-norm:sp_pay_to_supplier 6244 6244 0 0.00 0 3972 9 0 0
t$perf-norm:sp_reserve_write_off 10441 10441 0 0.00 0 19621 12 0 0
t$perf-norm:sp_supplier_invoice 5782 5782 0 0.00 0 12589 23 0 0
t$perf-norm:sp_supplier_order 5796 5796 0 0.00 0 9538 19 0 0
t$perf-norm:srv_aggregate_perf_data 105 105 0 0.00 0 326 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 96 3 0 0
t$perf-norm:srv_make_invnt_saldo 452 452 0 0.00 0 4875 30 0 0
t$perf-norm:srv_make_money_saldo 748 748 0 0.00 0 1336 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 3 0 0
v_add_invoice_to_stock 10812 10638 174 1.60 0 10196 20 0 174
v_all_customers 39090 39090 0 0.00 0 52 1 0 0
v_all_suppliers 31916 31916 0 0.00 0 55 1 0 0
v_all_wares 43514 43514 0 0.00 0 39 1 0 0
v_cancel_adding_invoice 858 858 0 0.00 0 455 6 0 0
v_cancel_client_order 3732 3732 0 0.00 0 6041 7 0 0
v_cancel_customer_prepayment 1370 1370 0 0.00 0 5054 10 0 0
v_cancel_customer_reserve 1416 1416 0 0.00 0 1504 4 0 0
v_cancel_payment_to_supplier 884 884 0 0.00 0 371 3 0 0
v_cancel_supplier_invoice 758 746 12 1.58 0 412 11 0 12
v_cancel_supplier_order 808 808 0 0.00 0 2528 11 0 0
v_cancel_write_off 1976 1976 0 0.00 0 5485 18 0 0
v_max_id_clo_ord 5796 5796 0 0.00 0 200 1 0 0
v_max_id_clo_res 1080 1080 0 0.00 0 2307 17 0 0
v_max_id_ord_sup 5769 5769 0 0.00 0 13723 7 0 0
v_max_non_paid_invoice 1851 1851 0 0.00 0 282 2 0 0
v_max_non_paid_realizn 4093 4093 0 0.00 0 2043 4 0 0
v_min_id_clo_ord 5796 5796 0 0.00 0 2774 6 0 0
v_min_id_clo_res 1080 1080 0 0.00 0 3213 11 0 0
v_min_id_ord_sup 5769 5769 0 0.00 0 9149 16 0 0
v_min_non_paid_invoice 6244 6244 0 0.00 0 7253 16 0 0
v_min_non_paid_realizn 7289 7289 0 0.00 0 5938 14 0 0
v_our_firm 10816 10816 0 0.00 0 20 1 0 0
v_reserve_write_off 20880 20880 0 0.00 0 3807 6 0 0
x_lock_depdocs_on_canc_invoice 11003 11003 0 0.00 0 17457 114 0 0
x_lock_depdocs_on_canc_sup_ord 404 404 0 0.00 0 23527 404 0 0
x_make_qstorno_1000_1200 5796 5796 0 0.00 2 32606 1240 0 0
x_make_qstorno_1200_2000 5782 5782 0 0.00 3 78595 3288 0 0
x_make_qstorno_2100_3300 477405 477405 0 0.00 0 55160 187 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 03-01-2022 10:38:03 to 03-01-2022 10:38:07.

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
08:07:15 1 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:16 27256 8192000000 9731386032 10030157824 814349216 9.041 100 88 176 0 842935216 7496864 21089136
08:09:47 27489 8192000000 9764904176 10072412160 838328304 9.283 100 90 180 0 872527360 12455232 21743824
08:11:24 17891 8192000000 9800558832 10117570560 868764336 9.588 100 77 152 0 907314576 20999296 17550944
08:12:44 9540 8192000000 9868681952 10202476544 919655472 10.093 100 82 164 1 982441616 44853424 17932720
08:13:58 7826 8192000000 9928744432 10268323840 955665392 10.447 100 85 167 0 1035829808 62821968 17342448
08:15:13 5593 8192000000 9945239136 10300620800 992663392 10.807 100 71 140 1 1058603232 51270800 14669040
08:16:20 3271 8192000000 9975995792 10335125504 1017504656 11.048 100 75 151 2 1088928576 56453488 14970432
08:17:25 1936 8192000000 10000436784 10364395520 1042961792 11.293 100 93 185 0 1117768256 56154048 18652416
08:18:29 1246 8192000000 10035298288 10400120832 1054895760 11.408 100 88 172 0 1142771856 70450176 17425920
08:19:32 26369 8192000000 9985746288 10369794048 1070319648 11.555 100 59 116 0 1123070096 40537104 12213344
08:21:00 5391 8192000000 10014874128 10402254848 1077160928 11.620 100 54 105 0 1129746560 41260208 11325424
08:22:07 59799 8192000000 9996486384 10404208640 1082142096 11.668 100 36 73 0 1105458336 15382656 7933584
08:24:07 6016 8192000000 10009395680 10399338496 1089685216 11.740 100 42 85 2 1121509408 22482384 9341808
08:25:14 6117 8192000000 10002101328 10405314560 1083408208 11.680 99 37 74 3 1118502736 27269184 7825344
08:26:20 4348 8192000000 10005786208 10401525760 1094051760 11.781 100 35 70 1 1133634368 30351040 9231568
08:27:27 26787 8192000000 10008730896 10404265984 1071980672 11.571 98 37 74 1 1106336032 26219712 8135648
08:28:54 4736 8192000000 10013543760 10397212672 1049935120 11.360 97 50 98 3 1094802080 34519856 10347104
08:30:01 8444 8192000000 9971454960 10371043328 1027534624 11.145 97 25 50 2 1059319696 26700928 5084144
08:31:14 5502 8192000000 9932374384 10299457536 1008458704 10.960 98 36 71 2 1028402752 11890816 8053232
08:32:25 25438 8192000000 9927177152 10309988352 990665200 10.788 97 45 88 1 1020144928 19016672 10463056
08:33:52 11131 8192000000 9954080608 10341683200 1026735856 11.137 99 26 52 1 1056824496 24875536 5213104
08:35:06 4837 8192000000 9948319760 10339934208 1045844448 11.321 100 27 52 1 1077053680 25384448 5824784
08:36:12 7325 8192000000 9980201184 10368700416 1056297200 11.421 100 37 74 1 1077268848 11988304 8983344
08:37:22 81175 8192000000 10018121824 10411356160 1078277936 11.631 100 51 102 1 1113746432 24879136 10589360
08:39:53 9735 8192000000 10052366640 10448875520 1102728640 11.864 100 41 81 0 1153492528 42530944 8232944
08:41:13 10919 8192000000 10094161232 10495528960 1114224512 11.972 100 43 82 2 1196299024 73177376 8897136
08:42:32 3780 8192000000 10117057504 10518175744 1126548768 12.089 100 67 136 1 1229431904 88390912 14492224
08:43:40 7055 8192000000 10117016048 10530951168 1131745488 12.138 100 53 107 1 1219214944 76682160 10787296
08:44:50 6064 8192000000 10167357552 10569072640 1139809600 12.214 100 74 148 1 1275945936 119940336 16196000
08:45:59 4312 8192000000 10214059696 10617012224 1144244112 12.255 100 73 146 0 1314152400 154947552 14960736
08:47:11 4231 8192000000 10235945408 10642268160 1156334928 12.369 100 67 132 0 1338233696 167276576 14622192
08:48:19 14546 8192000000 10239911408 10656739328 1168370592 12.482 100 57 114 0 1357378352 176394240 12613520
08:49:39 13887 8192000000 10291721168 10701742080 1173419904 12.529 100 71 142 2 1385046176 195311200 16315072
08:50:58 3889 8192000000 10289168432 10706817024 1160068416 12.404 99 70 140 0 1385495360 208608544 16818400
08:52:08 1787 8192000000 10295262544 10733236224 1163322544 12.434 100 84 168 0 1399022336 216019488 19680304
08:53:17 3186 8192000000 10306736944 10705813504 1156483056 12.370 100 81 162 0 1404928816 230399504 18046256
08:54:23 3951 8192000000 10305984928 10716020736 1162516800 12.427 100 65 129 0 1412860992 234663808 15680384
08:55:33 1900 8192000000 10363384000 10776817664 1162301968 12.425 100 92 184 1 1455957072 272125968 21529136
08:56:38 2148 8192000000 10389392032 10787999744 1147651632 12.287 99 90 176 1 1481495696 313842576 20001488
08:57:45 7896 8192000000 10330040432 10761732096 1145419792 12.266 99 50 100 0 1432472896 274343648 12709456
08:59:01 6011 8192000000 10346241456 10781769728 1140202672 12.217 100 42 82 0 1445122656 294539648 10380336
09:00:11 9539 8192000000 10324133136 10768338944 1144347408 12.256 99 31 63 1 1417880992 266115088 7418496
09:01:24 5613 8192000000 10328423456 10750771200 1145397312 12.266 100 41 81 1 1425931520 271084736 9449472
09:02:33 35855 8192000000 10281389040 10774630400 1115463552 11.984 98 25 50 3 1415767744 293513472 6790720
09:04:12 29047 8192000000 10236550320 10724741120 1106273552 11.897 99 22 42 2 1377455584 265876224 5305808
09:05:44 28539 8192000000 10052243440 10518106112 1108901712 11.922 100 16 32 1 1240739616 128007232 3830672
09:07:14 85350 8192000000 10075730512 10498969600 1101914384 11.856 98 22 44 0 1144504128 37809200 4780544
09:09:56 14629 8192000000 10082694032 10518622208 1117052304 11.999 99 16 30 1 1175916240 55571248 3292688
09:11:11 91356 8192000000 10064533216 10502852608 1118766128 12.015 98 30 60 1 1158219952 32951952 6501872
09:13:52 61468 8192000000 10080166800 10526244864 1138367920 12.200 100 37 74 1 1193863808 47797040 7698848
09:15:58 11436 8192000000 10109837776 10550124544 1151878096 12.327 100 40 76 2 1207675760 47189696 8607968
09:17:13 27395 8192000000 10141224560 10572763136 1125916400 12.083 97 30 60 0 1233144256 100580400 6647456
09:18:53 40542 8192000000 10147088064 10598219776 1141452112 12.229 99 19 38 0 1237048208 91600128 3995968
09:20:40 36903 8192000000 10157672176 10608869376 1140325616 12.219 100 12 24 0 1268212368 125163056 2723696
09:22:21 31466 8192000000 10074514768 10513977344 1129131504 12.113 99 29 58 0 1211413904 74413072 7869328
09:24:03 66265 8192000000 10065733568 10548236288 1122343248 12.049 99 15 30 0 1172821232 47243408 3234576
09:26:19 25351 8192000000 10061174352 10494218240 1131045648 12.131 100 26 52 2 1181103008 45144496 4912864
09:27:46 49156 8192000000 10100745728 10548797440 1132884080 12.149 99 25 49 0 1192310144 54356112 5069952
09:29:38 85967 8192000000 10086561152 10538807296 1096990176 11.809 96 12 24 0 1126622304 27099744 2532384
09:32:06 112821 8192000000 10055800976 10609680384 1085074736 11.696 96 10 20 0 1212704512 125168032 2461744
09:34:59 75872 8192000000 10093169520 10529067008 1120887632 12.035 99 19 38 0 1176745728 52074304 3783792
09:37:16 137977 8192000000 10091956944 10582757376 1112884720 11.960 98 27 54 1 1178267152 59469120 5913312
09:40:54 18659 8192000000 10098648928 10557116416 1119971040 12.027 99 12 24 0 1193873968 71321760 2581168
09:42:15 64314 8192000000 10088187904 10553176064 1139646944 12.212 100 16 32 0 1207882672 64872624 3363104
09:44:19 46153 8192000000 10125295328 10578219008 1109135168 11.924 97 17 34 2 1189656448 76920224 3601056
09:46:07 70948 8192000000 10066652480 10535452672 1080777296 11.655 96 19 38 1 1136876288 52135504 3963488
09:48:18 70092 8192000000 10106240800 10557640704 1066891312 11.522 95 42 84 0 1143484864 67403984 9189568
09:50:28 54382 8192000000 10042368112 10491150336 1102115184 11.858 99 15 28 1 1143284880 37968560 3201136
09:52:23 32205 8192000000 10079369728 10508947456 1085974064 11.704 97 34 68 1 1126596944 33810592 6812288
09:53:57 134283 8192000000 10082344528 10549411840 1111546352 11.947 99 11 22 0 1159575936 45994256 2035328
09:57:12 115593 8192000000 10102818976 10542555136 1125759968 12.081 99 21 42 0 1170539840 40219664 4560208
10:00:08 7027 8192000000 10084760608 10513088512 1118927984 12.017 99 32 64 1 1178201040 52859168 6413888
10:01:16 26302 8192000000 10058431568 10501197824 1128453072 12.107 100 12 24 0 1169052976 37904304 2695600
10:02:53 71364 8192000000 10076856656 10540912640 1122749072 12.053 99 16 30 2 1171482752 45631792 3101888
10:05:04 77346 8192000000 10069005856 10519900160 1101069808 11.848 97 14 27 1 1147092352 42413040 3609504
10:07:22 42609 8192000000 10122229216 10558455808 1122642320 12.052 98 34 68 0 1213029504 82694912 7692272
10:09:06 80048 8192000000 10084460096 10538651648 1125053216 12.075 98 16 32 0 1228038656 99072464 3912976
10:11:31 77243 8192000000 10114859440 10538201088 1112252912 11.954 97 16 32 1 1168117312 52442752 3421648
10:13:54 35350 8192000000 10130007664 10599325696 1132675680 12.147 100 23 46 1 1235232288 98117408 4439200
10:15:29 48192 8192000000 10112633152 10541223936 1139826720 12.214 100 26 52 0 1178698128 33730720 5140688
10:17:23 41774 8192000000 10093470368 10564272128 1106024848 11.895 97 26 52 0 1162640192 51594688 5020656
10:19:06 18071 8192000000 10071396720 10493329408 1097690880 11.816 98 43 86 0 1139779008 33560400 8527728
10:20:26 109939 8192000000 10042350384 10464780288 1086845072 11.713 97 51 100 0 1137155792 39976128 10334592
10:23:17 71554 8192000000 10042612224 10503430144 1114376208 11.974 100 15 30 0 1159802144 42320432 3105504
10:25:28 49199 8192000000 10090002960 10539696128 1102046048 11.857 98 16 32 2 1165137504 59098256 3993200
10:27:57 123899 8192000000 10063216304 10534973440 1066349632 11.517 95 10 20 0 1121789184 53364976 2074576
10:31:01 66351 8192000000 10053828480 10508451840 1112909408 11.960 99 17 33 1 1149380704 32838176 3633120
10:33:17 49455 8192000000 10049087072 10503196672 1067561152 11.529 96 24 48 0 1119933696 47524992 4847552
10:35:07 26284 8192000000 10068580080 10490187776 1118980064 12.017 100 55 108 1 1163743152 33762640 11000448
10:36:37 46998 8192000000 9357804480 9683865600 471009376 5.437 43 5 10 0 494080752 22253264 818112

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 03-01-2022 10:38:09 to 03-01-2022 10:38:24.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 87 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 03-01-2022 10:38:25 to 03-01-2022 10:38:27.

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          238948
MON$OLDEST_ACTIVE               238949
MON$OLDEST_SNAPSHOT             238949
MON$NEXT_TRANSACTION            238949
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                       2052128
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             806
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2700 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2700 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2700 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2700 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 ---
:35: 
:36: mscs-test-161.budzdorov.ru    Mon Jan  3 10:37:28 2022
:37:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:38: 
--- end of diff output ---

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

03.01.22 10:38:28. 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-01-03 10:38:31 No severe PSQL-related problems occured
03.01.22 10:38:32. End of report.