Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 17.04.2022 07: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 15852784 kB
MemAvailable 31471556 kB
Buffers 0 kB
Cached 15814496 kB
SwapCached 2688 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-04-17 07:37:14
DTS_END                         2022-04-17 07:37:14.2950
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(68:17)
IP                              ::1/51702
TRN_ID                          222168
ATT_ID                          637
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: 8565.04 35465 1027805
customer order: creation 161.68 539 19401
customer order: refuse 14.38 406 1726
order to supplier: creation 43.85 1515 5262
order to supplier: removal 3.16 173903 379
invoice (draft): creation 45.23 3680 5427
invoice (draft): removal 47.58 1045 5709
invoice accept: apply 41.21 25163 4945
invoice accept: cancel 42.90 1363 5148
customer reserve: creation 3720.05 241 446406
customer reserve: removal 4160.46 103 499255
realization accept: apply 80.09 330 9611
realization accept: cancel 76.78 181 9213
payment from customer: creation 57.90 205 6948
payment from customer: removal 5.65 134 678
payment to supplier: creation 48.57 221 5828
payment to supplier: removal 3.28 134 393
service: total inventory turnovers 3.58 4791 429
service: total monetary turnovers 7.84 371 941
service: aggregate perf. data 0.84 23150 101
service: refresh index statistics 0.04 471825 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 17-04-2022 07:38:12 to 17-04-2022 07:38:14.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 11338
TEST_TIME 2 8037
TEST_TIME 3 8168
TEST_TIME 4 7606
TEST_TIME 5 7205
TEST_TIME 6 7000
TEST_TIME 7 6782
TEST_TIME 8 6862
TEST_TIME 9 7116
TEST_TIME 10 7105
TEST_TIME 11 7481
TEST_TIME 12 7617
TEST_TIME 13 7598
TEST_TIME 14 7711
TEST_TIME 15 7711
TEST_TIME 16 7664
TEST_TIME 17 7908
TEST_TIME 18 8029
TEST_TIME 19 8050
TEST_TIME 20 8294
TEST_TIME 21 8444
TEST_TIME 22 8441
TEST_TIME 23 8574
TEST_TIME 24 8853
TEST_TIME 25 8928
TEST_TIME 26 9463
TEST_TIME 27 9562
TEST_TIME 28 9521
TEST_TIME 29 9587
TEST_TIME 30 9721
TEST_TIME 31 9732
TEST_TIME 32 9986
TEST_TIME 33 9971
TEST_TIME 34 10130
TEST_TIME 35 10355
TEST_TIME 36 10809
TEST_TIME 37 10859
TEST_TIME 38 10865
TEST_TIME 39 10734
TEST_TIME 40 10578
TEST_TIME 41 10526
TEST_TIME 42 10469
TEST_TIME 43 10339
TEST_TIME 44 10440
TEST_TIME 45 10429
TEST_TIME 46 10404
TEST_TIME 47 10284
TEST_TIME 48 10207
TEST_TIME 49 10059
TEST_TIME 50 9926
TEST_TIME 51 9928
TEST_TIME 52 9807
TEST_TIME 53 9789
TEST_TIME 54 9656
TEST_TIME 55 9660
TEST_TIME 56 9661
TEST_TIME 57 9589
TEST_TIME 58 9460
TEST_TIME 59 9360
TEST_TIME 60 9390
TEST_TIME 61 9328
TEST_TIME 62 9264
TEST_TIME 63 9286
TEST_TIME 64 9157
TEST_TIME 65 9067
TEST_TIME 66 8990
TEST_TIME 67 9081
TEST_TIME 68 9086
TEST_TIME 69 9009
TEST_TIME 70 9061
TEST_TIME 71 9060
TEST_TIME 72 8985
TEST_TIME 73 8928
TEST_TIME 74 9051
TEST_TIME 75 9046
TEST_TIME 76 8999
TEST_TIME 77 9028
TEST_TIME 78 9084
TEST_TIME 79 9135
TEST_TIME 80 9125
TEST_TIME 81 9030
TEST_TIME 82 8951
TEST_TIME 83 8918
TEST_TIME 84 8868
TEST_TIME 85 8809
TEST_TIME 86 8797
TEST_TIME 87 8781
TEST_TIME 88 8717
TEST_TIME 89 8706
TEST_TIME 90 8677
TEST_TIME 91 8672
TEST_TIME 92 8729
TEST_TIME 93 8690
TEST_TIME 94 8668
TEST_TIME 95 8677
TEST_TIME 96 8851
TEST_TIME 97 8865
TEST_TIME 98 8858
TEST_TIME 99 8861
TEST_TIME 100 8800
TEST_TIME 101 8757
TEST_TIME 102 8740
TEST_TIME 103 8733
TEST_TIME 104 8710
TEST_TIME 105 8682
TEST_TIME 106 8634
TEST_TIME 107 8614
TEST_TIME 108 8622
TEST_TIME 109 8588
TEST_TIME 110 8629
TEST_TIME 111 8633
TEST_TIME 112 8619
TEST_TIME 113 8638
TEST_TIME 114 8623
TEST_TIME 115 8633
TEST_TIME 116 8648
TEST_TIME 117 8656
TEST_TIME 118 8647
TEST_TIME 119 8643
TEST_TIME 120 8582

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 17-04-2022 07:38:16 to 17-04-2022 07:38:19.

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 1437700 1437700 0 0.00 0 24725 14 0 0
doc_list_biud 1437688 1437688 0 0.00 0 42458 43 0 0
sp_add_invoice_to_stock 5049 4945 104 2.05 34 214580 25164 0 104
sp_cancel_adding_invoice 5148 5148 0 0.00 7 115977 1364 0 0
sp_cancel_client_order 1726 1726 0 0.00 7 11675 407 0 0
sp_cancel_customer_reserve 499255 499255 0 0.00 1 30582 103 0 0
sp_cancel_pay_from_customer 678 678 0 0.00 4 4284 134 0 0
sp_cancel_pay_to_supplier 393 393 0 0.00 4 3117 134 0 0
sp_cancel_supplier_invoice 5715 5709 6 0.10 4 42494 1046 0 6
sp_cancel_supplier_order 379 379 0 0.00 21 1411645 173903 0 0
sp_cancel_write_off 9213 9213 0 0.00 2 28921 181 0 0
sp_client_order 19401 19401 0 0.00 6 44592 540 0 0
sp_customer_reserve 446406 446406 0 0.00 1 61602 242 0 0
sp_fill_shopping_cart 30073 30073 0 0.00 1 49579 111 0 0
sp_fill_shopping_cart_clo_res 446372 446372 0 0.00 0 14675 20 0 0
sp_get_clo_for_invoice 4908 4908 0 0.00 0 31395 384 0 0
sp_kill_cost_storno 15432 15432 0 0.00 0 24290 78 0 0
sp_kill_qstorno_ret_qs2qd 1013811 1013811 0 0.00 0 42446 45 0 0
sp_kill_qty_storno 535945 535945 0 0.00 0 42454 105 0 0
sp_lock_selected_doc 20131 20131 0 0.00 0 8014 7 0 0
sp_make_cost_storno 27295 27295 0 0.00 0 16647 86 0 0
sp_multiply_rows_for_pdistr 27291 27291 0 0.00 0 7660 42 0 0
sp_multiply_rows_for_qdistr 19401 19401 0 0.00 0 20526 190 0 0
sp_pay_from_customer 6948 6948 0 0.00 6 12058 205 0 0
sp_pay_to_supplier 5828 5828 0 0.00 6 13621 222 0 0
sp_payment_common 12775 12775 0 0.00 2 10216 54 0 0
sp_qd_handle_on_cancel_clo 1726 1726 0 0.00 0 11644 329 0 0
sp_qd_handle_on_invoice_upd_sts 10053 10053 0 0.00 0 42143 634 0 0
sp_qd_handle_on_reserve_upd_sts 9610 9610 0 0.00 0 29971 86 0 0
sp_reserve_write_off 9611 9611 0 0.00 8 30000 331 0 0
sp_supplier_invoice 5427 5427 0 0.00 11 88771 3681 0 0
sp_supplier_order 5262 5262 0 0.00 7 23992 1516 0 0
srv_aggregate_perf_data 101 101 0 0.00 2731 312319 23150 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 825 154154 37806 0 0
srv_find_qd_qs_mism 979912 979912 0 0.00 0 24549 10 0 0
srv_make_invnt_saldo 429 429 0 0.00 87 93142 4791 0 0
srv_make_money_saldo 941 941 0 0.00 1 7350 372 0 0
srv_recalc_idx_stat 5 5 0 0.00 360848 662756 471825 0 0
t$perf-abend:sp_add_invoice_to_stock 104 104 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 4946 4946 0 0.00 1 11458 1093 0 0
t$perf-norm:sp_cancel_adding_invoice 397 397 0 0.00 1 6925 500 0 0
t$perf-norm:sp_cancel_client_order 1726 1726 0 0.00 0 4129 34 0 0
t$perf-norm:sp_cancel_customer_reserve 661 661 0 0.00 0 2843 38 0 0
t$perf-norm:sp_cancel_pay_from_customer 678 678 0 0.00 0 2952 20 0 0
t$perf-norm:sp_cancel_pay_to_supplier 393 393 0 0.00 0 5189 37 0 0
t$perf-norm:sp_cancel_supplier_invoice 377 377 0 0.00 0 2483 41 0 0
t$perf-norm:sp_cancel_supplier_order 379 379 0 0.00 0 106058 9112 0 0
t$perf-norm:sp_cancel_write_off 1011 1011 0 0.00 0 1136 18 0 0
t$perf-norm:sp_client_order 19402 19402 0 0.00 0 6873 41 0 0
t$perf-norm:sp_customer_reserve 1019 1019 0 0.00 0 2311 19 0 0
t$perf-norm:sp_pay_from_customer 6948 6948 0 0.00 0 7167 21 0 0
t$perf-norm:sp_pay_to_supplier 5828 5828 0 0.00 0 5338 24 0 0
t$perf-norm:sp_reserve_write_off 9611 9611 0 0.00 0 4785 26 0 0
t$perf-norm:sp_supplier_invoice 5427 5427 0 0.00 0 6885 40 0 0
t$perf-norm:sp_supplier_order 5262 5262 0 0.00 0 4831 36 0 0
t$perf-norm:srv_aggregate_perf_data 101 101 0 0.00 0 608 18 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 3269 152 0 0
t$perf-norm:srv_make_invnt_saldo 429 429 0 0.00 0 631 13 0 0
t$perf-norm:srv_make_money_saldo 941 941 0 0.00 0 1801 8 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 4 366 80 0 0
v_add_invoice_to_stock 10008 9800 208 2.07 0 5954 27 0 208
v_all_customers 35440 35440 0 0.00 0 61 4 0 0
v_all_suppliers 29652 29652 0 0.00 0 123 5 0 0
v_all_wares 38791 38791 0 0.00 0 200 4 0 0
v_cancel_adding_invoice 794 794 0 0.00 0 5051 27 0 0
v_cancel_client_order 3452 3452 0 0.00 0 4914 11 0 0
v_cancel_customer_prepayment 1356 1356 0 0.00 0 4193 22 0 0
v_cancel_customer_reserve 1322 1322 0 0.00 0 3490 14 0 0
v_cancel_payment_to_supplier 786 786 0 0.00 0 2873 16 0 0
v_cancel_supplier_invoice 766 754 12 1.56 0 2895 31 0 12
v_cancel_supplier_order 758 758 0 0.00 0 3798 19 0 0
v_cancel_write_off 2022 2022 0 0.00 0 4818 33 0 0
v_max_id_clo_ord 5256 5256 0 0.00 0 154 5 0 0
v_max_id_clo_res 1019 1019 0 0.00 0 1568 10 0 0
v_max_id_ord_sup 5417 5417 0 0.00 0 5467 11 0 0
v_max_non_paid_invoice 1674 1674 0 0.00 0 522 9 0 0
v_max_non_paid_realizn 3682 3682 0 0.00 0 1276 8 0 0
v_min_id_clo_ord 5256 5256 0 0.00 0 3512 11 0 0
v_min_id_clo_res 1019 1019 0 0.00 0 2512 14 0 0
v_min_id_ord_sup 5417 5417 0 0.00 0 5844 12 0 0
v_min_non_paid_invoice 5827 5827 0 0.00 0 10183 21 0 0
v_min_non_paid_realizn 6948 6948 0 0.00 0 6439 22 0 0
v_our_firm 9878 9878 0 0.00 0 58 5 0 0
v_reserve_write_off 19216 19216 0 0.00 0 5212 15 0 0
x_lock_depdocs_on_canc_invoice 10480 10480 0 0.00 0 16908 132 0 0
x_lock_depdocs_on_canc_sup_ord 379 379 0 0.00 0 17477 401 0 0
x_make_qstorno_1000_1200 5262 5262 0 0.00 2 23933 1302 0 0
x_make_qstorno_1200_2000 5427 5427 0 0.00 6 72325 3371 0 0
x_make_qstorno_2100_3300 446406 446406 0 0.00 0 61600 207 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 17-04-2022 07:38:20 to 17-04-2022 07:38:24.

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
05:07:18 23 8192000000 8802911856 8815763456 4478816 0.054 2 1 2 0 4578208 63616 35776
05:08:19 29673 8192000000 9721848512 10021486592 799742288 8.894 100 90 180 0 829075712 7679440 21653984
05:09:51 9154 8192000000 9759912624 10065276928 829916080 9.198 100 88 176 0 862710272 11820112 20974080
05:11:02 7581 8192000000 9782541600 10099511296 857286256 9.473 100 89 176 1 895183072 17818176 20078640
05:12:23 11646 8192000000 9852977536 10182414336 906073008 9.958 100 77 153 0 957021136 34470480 16477648
05:13:38 8228 8192000000 9918828160 10263511040 961787344 10.506 100 90 178 1 1034566624 53870544 18908736
05:14:50 3855 8192000000 9980488752 10331877376 991717024 10.798 100 88 174 1 1097065136 87719792 17628320
05:15:57 2464 8192000000 10011722480 10373058560 1019175552 11.064 100 89 175 1 1125006000 87680560 18149888
05:17:01 1161 8192000000 10032515888 10404323328 1044469376 11.308 100 89 174 0 1151642304 90576272 16596656
05:18:03 1232 8192000000 10047372256 10423033856 1060957248 11.466 100 96 188 0 1169159520 89039152 19163120
05:19:06 891 8192000000 10047768080 10424307712 1069938624 11.551 100 92 176 0 1169544704 81258544 18347536
05:20:08 1614 8192000000 10052391408 10432471040 1074172656 11.592 100 88 168 1 1171727008 80860160 16694192
05:21:11 1264 8192000000 10038529936 10423578624 1075939024 11.609 100 92 181 2 1154334848 58577760 19818064
05:22:13 1242 8192000000 10056177552 10446155776 1084270704 11.688 100 76 141 0 1181524896 84046176 13208016
05:23:15 8182 8192000000 10046815264 10446184448 1091472032 11.757 100 57 112 2 1178689296 76013216 11204048
05:24:24 1178 8192000000 10056249168 10449408000 1099524624 11.833 100 94 176 1 1176891744 59010720 18356400
05:25:26 1221 8192000000 10069484400 10455859200 1103685440 11.873 100 95 191 2 1189911600 67256656 18969504
05:26:29 1363 8192000000 10080166000 10469265408 1105965312 11.894 100 97 185 1 1197483280 72706880 18811088
05:27:31 1086 8192000000 10067912704 10458808320 1110500048 11.937 100 92 172 2 1187517264 59910096 17107120
05:28:32 970 8192000000 10054934448 10448195584 1102772432 11.864 100 84 159 1 1173905568 54054240 17078896
05:29:34 3172 8192000000 10048109984 10438152192 1086354912 11.708 100 78 154 0 1178270336 76696496 15218928
05:30:38 1150 8192000000 10035994288 10413592576 1068182960 11.535 100 96 179 2 1156660432 70543984 17933488
05:31:41 3147 8192000000 10050888144 10435371008 1077139392 11.620 100 80 146 0 1171429856 80311696 13978768
05:32:45 1984 8192000000 10001955008 10378833920 1046274080 11.325 99 77 144 1 1119881792 58791632 14816080
05:33:47 2242 8192000000 9979631536 10355580928 1026813072 11.138 98 80 158 2 1101741360 57455392 17472896
05:34:50 5251 8192000000 9965066560 10344857600 1029576896 11.164 98 64 114 0 1087602368 46685920 11339552
05:35:56 7191 8192000000 9987495856 10368684032 1042149248 11.285 99 67 120 2 1102599824 46564752 13885824
05:37:04 2559 8192000000 9975094240 10355208192 1048186720 11.343 100 60 119 1 1093510384 34082640 11241024
05:38:11 19342 8192000000 9993035088 10391658496 1064971888 11.504 100 47 90 1 1123094976 49035792 9087296
05:39:38 3313 8192000000 10029062064 10415976448 1079556576 11.643 100 75 135 2 1152405392 58277152 14571664
05:40:45 983 8192000000 10070708448 10452877312 1096689344 11.806 100 84 163 1 1184041376 70843744 16508288
05:41:50 2991 8192000000 10083659472 10468130816 1110177056 11.934 100 83 156 2 1198256832 71238032 16841744
05:42:57 4558 8192000000 10127213824 10525655040 1124416352 12.069 100 67 128 0 1242107664 103747008 13944304
05:44:15 21693 8192000000 10157605520 10568404992 1130345920 12.125 100 66 130 0 1276753568 132676848 13730800
05:45:43 4123 8192000000 10174248368 10572578816 1143361840 12.247 100 81 161 3 1290486096 130289040 16835216
05:46:49 837 8192000000 10214194544 10611007488 1160278016 12.406 100 97 183 2 1327527808 147928064 19321728
05:47:55 1035 8192000000 10255591232 10664914944 1176068576 12.554 100 90 174 0 1365989440 171010128 18910736
05:48:57 1419 8192000000 10272040656 10683412480 1175281984 12.546 100 94 184 1 1378542768 182985968 20274816
05:50:02 1459 8192000000 10262036256 10661126144 1165444560 12.454 99 90 170 1 1371365408 187702448 18218400
05:51:06 910 8192000000 10281102688 10683768832 1161545280 12.418 99 92 174 1 1389735488 209686048 18504160
05:52:09 887 8192000000 10327822704 10732638208 1172414144 12.519 100 94 189 1 1438081184 244775744 20891296
05:53:16 2787 8192000000 10350298064 10755616768 1184209568 12.629 100 85 162 0 1456596432 254753840 17633024
05:54:23 916 8192000000 10370598368 10781593600 1174738976 12.541 100 97 187 1 1479813456 283331520 21742960
05:55:26 936 8192000000 10403467376 10809368576 1170284560 12.499 100 90 177 0 1509807536 318485088 21037888
05:56:29 1342 8192000000 10449771904 10858139648 1173212384 12.527 100 94 184 0 1557085600 362325344 21547872
05:57:32 1000 8192000000 10429423536 10870964224 1187926224 12.664 100 97 182 1 1539398896 330124800 21347872
05:58:37 1330 8192000000 10466664016 10878803968 1179423552 12.585 100 93 178 0 1570600480 369300992 21875936
05:59:39 930 8192000000 10484079456 10900111360 1179314848 12.584 100 96 185 0 1589077232 387242320 22520064
06:00:43 1000 8192000000 10507076560 10932097024 1183028864 12.618 100 98 194 1 1610619504 404720400 22870240
06:01:45 1219 8192000000 10478045360 10956488704 1181903360 12.608 100 98 195 0 1584837424 379904928 23029136
06:02:49 2380 8192000000 10458464496 10928545792 1181165152 12.601 100 78 154 1 1561066784 360904160 18997472
06:03:53 18302 8192000000 10401242080 10866208768 1160454896 12.408 99 37 74 0 1512177664 342166240 9556528
06:05:13 23870 8192000000 10443320800 10877562880 1167865168 12.477 100 36 72 1 1541381184 364017648 9498368
06:06:47 25819 8192000000 10449208640 10889076736 1172790864 12.523 100 30 60 1 1541398688 361091888 7515936
06:08:14 40219 8192000000 10374664400 10885074944 1145106560 12.264 98 29 58 1 1486046592 333527024 7413008
06:09:55 13989 8192000000 10360056736 10820939776 1145270880 12.265 100 29 55 0 1478958016 326953024 6734112
06:11:10 5933 8192000000 10370302560 10799763456 1147857168 12.289 100 58 116 2 1458414480 296135680 14421632
06:12:18 817 8192000000 10279416800 10824454144 1158243712 12.387 100 93 174 1 1380580832 199427440 22909680
06:13:20 3935 8192000000 10234728064 10737463296 1141294992 12.228 100 74 137 2 1334145056 175350672 17499392
06:14:25 67781 8192000000 10232048848 10689568768 1158790464 12.392 100 39 79 0 1365179152 196044944 10343744
06:16:33 29527 8192000000 10244720864 10699902976 1159257776 12.396 100 33 64 1 1351065232 184690032 7117424
06:18:04 71732 8192000000 10224575424 10705821696 1145356368 12.266 99 23 44 2 1327886816 177098928 5431520
06:20:54 30517 8192000000 10210883456 10656399360 1146384032 12.276 100 20 42 0 1312733344 161632176 4717136
06:22:25 36198 8192000000 10106101552 10561490944 1129813376 12.120 99 25 49 0 1214448032 80025744 4608912
06:24:01 60336 8192000000 10089302896 10535641088 1117332032 12.002 98 37 72 1 1173481024 48695680 7453312
06:26:05 102032 8192000000 10130659280 10576048128 1112922096 11.960 97 26 52 0 1189854864 71471280 5461488
06:29:11 154154 8192000000 10145433456 10609094656 1137370208 12.191 99 23 46 1 1275782592 133332768 5079616
06:33:47 68891 8192000000 10109014816 10644754432 1128195888 12.104 98 3 6 0 1201008576 72234928 577760
06:35:56 42941 8192000000 10101318560 10547077120 1113050224 11.961 98 23 46 1 1192975552 74723872 5201456
06:37:40 48080 8192000000 10078088320 10514173952 1101110240 11.848 97 28 55 0 1159584880 52726336 5748304
06:39:30 35396 8192000000 10100117008 10563407872 1121914896 12.045 99 34 68 0 1175529520 46051824 7562800
06:41:09 97013 8192000000 10070250800 10554363904 1117866096 12.007 99 14 27 0 1166380080 45913184 2600800
06:43:47 102230 8192000000 10057263216 10552492032 1097854768 11.817 97 16 32 1 1163743728 62685264 3203696
06:47:11 106008 8192000000 10060694000 10519269376 1091057328 11.753 96 17 34 0 1143571632 48204128 4310176
06:50:05 38692 8192000000 10133549200 10574512128 1120986288 12.036 99 40 78 0 1214034880 84787600 8260992
06:51:57 45362 8192000000 10060259024 10527436800 1102792896 11.864 98 28 57 1 1162737056 54399520 5544640
06:53:48 48136 8192000000 10131738928 10598821888 1137364304 12.191 100 38 76 0 1251935536 107349312 7221920
06:55:37 59657 8192000000 10090358304 10570235904 1110236368 11.935 97 28 54 2 1165669632 49995328 5437936
06:57:39 119351 8192000000 10073977776 10519334912 1137675744 12.194 100 22 44 2 1184979504 42702224 4601536
07:00:40 40691 8192000000 10080332624 10551656448 1138181632 12.198 99 35 70 1 1185901616 40048560 7671424
07:02:21 92034 8192000000 10039047808 10507440128 1102402864 11.860 97 15 29 0 1137948992 32727296 2818832
07:05:31 38945 8192000000 10116679296 10564014080 1117982560 12.008 98 30 60 0 1186653696 62265072 6406064
07:07:12 14968 8192000000 10125261824 10564714496 1136580544 12.183 100 29 58 0 1230388256 87888528 5919184
07:08:35 47124 8192000000 10161504640 10636247040 1157603488 12.381 100 27 54 1 1280595456 117342592 5649376
07:10:23 78088 8192000000 10193456496 10630606848 1135831744 12.176 98 40 80 3 1274126336 128566960 9727632
07:12:47 64791 8192000000 10135172352 10711846912 1096950960 11.809 97 8 16 1 1223908288 124993440 1963888
07:14:53 78534 8192000000 10096925888 10547437568 1122978192 12.055 99 35 70 0 1181571088 51013232 7579664
07:17:13 115632 8192000000 10095706096 10562859008 1077535344 11.624 95 31 60 2 1167093808 82635968 6922496
07:20:14 13864 8192000000 10060018720 10541047808 1127139696 12.094 100 25 44 0 1169953936 38599360 4214880
07:21:40 77845 8192000000 10086329952 10555277312 1135282944 12.171 100 14 28 0 1191696752 53027296 3386512
07:24:14 84167 8192000000 10091001520 10619375616 1117275856 12.001 98 8 16 0 1207932080 88723392 1932832
07:26:41 72931 8192000000 10060650448 10526232576 1073311344 11.584 94 15 30 1 1126768080 51047168 2409568
07:29:06 50807 8192000000 10077734512 10518642688 1126113200 12.085 99 24 46 1 1177227344 46822224 4291920
07:30:57 52475 8192000000 10089542112 10521333760 1101352368 11.850 96 21 42 2 1136682336 31170752 4159216
07:32:53 35097 8192000000 10123499936 10556702720 1124594800 12.070 99 30 60 1 1203121360 72622112 5904448
07:35:17 49254 8192000000 10097487040 10536271872 1134336368 12.162 99 46 90 2 1171862896 28460496 9066032
07:37:07 26616 8192000000 9066761968 9329573888 216195760 2.571 19 4 6 1 241351840 24374976 781104

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 17-04-2022 07:38:26 to 17-04-2022 07:38:42.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 104 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 17-04-2022 07:38:43 to 17-04-2022 07:38:45.

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          222625
MON$OLDEST_ACTIVE               222626
MON$OLDEST_SNAPSHOT             222626
MON$NEXT_TRANSACTION            222626
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                       2124800
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             777
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2761 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2761 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2761 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2761 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 ---
:214: 
:215: mscs-test-161.budzdorov.ru    Sun Apr 17 07:37:35 2022
:216:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:217: 
:218: 
:219: mscs-test-161.budzdorov.ru    Sun Apr 17 07:37:35 2022
:220:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:221: 
--- end of diff output ---

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

17.04.22 07:38:46. 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-04-17 07:38:49 No severe PSQL-related problems occured
17.04.22 07:38:50. End of report.