Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 31.07.2022 13:38
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 15770240 kB
MemAvailable 31032668 kB
Buffers 0 kB
Cached 15968156 kB
SwapCached 2512 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-07-31 13:37:18
DTS_END                         2022-07-31 13:37:18.6570
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/48294
TRN_ID                          225096
ATT_ID                          568
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: 9207.32 22215 1104878
customer order: creation 176.66 420 21199
customer order: refuse 14.38 390 1725
order to supplier: creation 46.93 1313 5632
order to supplier: removal 3.34 160581 401
invoice (draft): creation 46.62 3050 5594
invoice (draft): removal 50.88 921 6106
invoice accept: apply 42.51 24174 5101
invoice accept: cancel 46.28 946 5554
customer reserve: creation 3960.11 229 475213
customer reserve: removal 4520.11 92 542413
realization accept: apply 86.76 271 10411
realization accept: cancel 81.54 171 9785
payment from customer: creation 60.98 165 7318
payment from customer: removal 5.82 102 698
payment to supplier: creation 48.97 196 5876
payment to supplier: removal 3.27 112 392
service: total inventory turnovers 3.68 4012 441
service: total monetary turnovers 7.45 333 894
service: aggregate perf. data 1.01 16479 121
service: refresh index statistics 0.03 230334 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 31-07-2022 13:39:04 to 31-07-2022 13:39:06.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7204
TEST_TIME 2 6426
TEST_TIME 3 6298
TEST_TIME 4 7031
TEST_TIME 5 6565
TEST_TIME 6 6705
TEST_TIME 7 7375
TEST_TIME 8 7427
TEST_TIME 9 7073
TEST_TIME 10 7020
TEST_TIME 11 7042
TEST_TIME 12 7191
TEST_TIME 13 7132
TEST_TIME 14 7072
TEST_TIME 15 7578
TEST_TIME 16 7566
TEST_TIME 17 7517
TEST_TIME 18 7657
TEST_TIME 19 7743
TEST_TIME 20 7856
TEST_TIME 21 8044
TEST_TIME 22 8451
TEST_TIME 23 8747
TEST_TIME 24 8983
TEST_TIME 25 9115
TEST_TIME 26 9101
TEST_TIME 27 9063
TEST_TIME 28 9610
TEST_TIME 29 9813
TEST_TIME 30 10163
TEST_TIME 31 10256
TEST_TIME 32 10650
TEST_TIME 33 11397
TEST_TIME 34 11195
TEST_TIME 35 11229
TEST_TIME 36 11002
TEST_TIME 37 10909
TEST_TIME 38 10923
TEST_TIME 39 11022
TEST_TIME 40 10913
TEST_TIME 41 10834
TEST_TIME 42 10618
TEST_TIME 43 10648
TEST_TIME 44 10606
TEST_TIME 45 10401
TEST_TIME 46 10336
TEST_TIME 47 10224
TEST_TIME 48 10109
TEST_TIME 49 10108
TEST_TIME 50 10114
TEST_TIME 51 10029
TEST_TIME 52 9937
TEST_TIME 53 10105
TEST_TIME 54 10091
TEST_TIME 55 10117
TEST_TIME 56 10113
TEST_TIME 57 10063
TEST_TIME 58 10043
TEST_TIME 59 10104
TEST_TIME 60 10031
TEST_TIME 61 10116
TEST_TIME 62 10120
TEST_TIME 63 10147
TEST_TIME 64 10104
TEST_TIME 65 10074
TEST_TIME 66 10141
TEST_TIME 67 10103
TEST_TIME 68 10096
TEST_TIME 69 10085
TEST_TIME 70 10017
TEST_TIME 71 9989
TEST_TIME 72 9925
TEST_TIME 73 9862
TEST_TIME 74 9845
TEST_TIME 75 9795
TEST_TIME 76 9761
TEST_TIME 77 9782
TEST_TIME 78 9719
TEST_TIME 79 9650
TEST_TIME 80 9594
TEST_TIME 81 9632
TEST_TIME 82 9639
TEST_TIME 83 9595
TEST_TIME 84 9583
TEST_TIME 85 9559
TEST_TIME 86 9651
TEST_TIME 87 9666
TEST_TIME 88 9662
TEST_TIME 89 9648
TEST_TIME 90 9676
TEST_TIME 91 9769
TEST_TIME 92 9735
TEST_TIME 93 9843
TEST_TIME 94 9851
TEST_TIME 95 9767
TEST_TIME 96 9696
TEST_TIME 97 9649
TEST_TIME 98 9604
TEST_TIME 99 9551
TEST_TIME 100 9533
TEST_TIME 101 9518
TEST_TIME 102 9536
TEST_TIME 103 9506
TEST_TIME 104 9466
TEST_TIME 105 9480
TEST_TIME 106 9530
TEST_TIME 107 9504
TEST_TIME 108 9509
TEST_TIME 109 9482
TEST_TIME 110 9480
TEST_TIME 111 9460
TEST_TIME 112 9503
TEST_TIME 113 9539
TEST_TIME 114 9481
TEST_TIME 115 9440
TEST_TIME 116 9413
TEST_TIME 117 9357
TEST_TIME 118 9315
TEST_TIME 119 9270
TEST_TIME 120 9241

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 31-07-2022 13:39:07 to 31-07-2022 13:39:11.

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 1543808 1543808 0 0.00 0 21449 13 0 0
doc_list_biud 1543807 1543807 0 0.00 0 67543 38 0 0
sp_add_invoice_to_stock 5212 5101 111 2.12 38 233247 24175 0 111
sp_cancel_adding_invoice 5554 5554 0 0.00 5 83651 946 0 0
sp_cancel_client_order 1725 1725 0 0.00 6 12848 391 0 0
sp_cancel_customer_reserve 542413 542413 0 0.00 1 27309 92 0 0
sp_cancel_pay_from_customer 698 698 0 0.00 4 6364 102 0 0
sp_cancel_pay_to_supplier 392 392 0 0.00 3 3512 112 0 0
sp_cancel_supplier_invoice 6121 6106 15 0.24 2 67606 921 0 15
sp_cancel_supplier_order 401 401 0 0.00 10 1377892 160581 0 0
sp_cancel_write_off 9785 9785 0 0.00 2 12449 171 0 0
sp_client_order 21199 21199 0 0.00 5 21537 420 0 0
sp_customer_reserve 475213 475213 0 0.00 0 167931 229 0 0
sp_fill_shopping_cart 32421 32421 0 0.00 1 12826 81 0 0
sp_fill_shopping_cart_clo_res 475169 475169 0 0.00 0 32718 17 0 0
sp_get_clo_for_invoice 5052 5052 0 0.00 0 22839 276 0 0
sp_kill_cost_storno 16429 16429 0 0.00 0 5771 68 0 0
sp_kill_qstorno_ret_qs2qd 1101118 1101118 0 0.00 0 67542 40 0 0
sp_kill_qty_storno 581444 581444 0 0.00 0 67543 93 0 0
sp_lock_selected_doc 21148 21148 0 0.00 0 9119 8 0 0
sp_make_cost_storno 28654 28654 0 0.00 0 18291 82 0 0
sp_multiply_rows_for_pdistr 28654 28654 0 0.00 0 9969 38 0 0
sp_multiply_rows_for_qdistr 21199 21199 0 0.00 0 21446 151 0 0
sp_pay_from_customer 7318 7318 0 0.00 5 20973 166 0 0
sp_pay_to_supplier 5876 5876 0 0.00 7 17766 196 0 0
sp_payment_common 13194 13194 0 0.00 2 5788 40 0 0
sp_qd_handle_on_cancel_clo 1725 1725 0 0.00 0 12840 326 0 0
sp_qd_handle_on_invoice_upd_sts 10603 10603 0 0.00 0 29413 486 0 0
sp_qd_handle_on_reserve_upd_sts 10411 10411 0 0.00 0 21598 66 0 0
sp_reserve_write_off 10411 10411 0 0.00 8 22377 272 0 0
sp_supplier_invoice 5594 5594 0 0.00 9 98297 3050 0 0
sp_supplier_order 5632 5632 0 0.00 6 35598 1314 0 0
srv_aggregate_perf_data 121 121 0 0.00 2121 209642 16479 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 725 159459 35685 0 0
srv_find_qd_qs_mism 1055487 1055487 0 0.00 0 16918 10 0 0
srv_make_invnt_saldo 441 441 0 0.00 40 54467 4012 0 0
srv_make_money_saldo 894 894 0 0.00 1 16409 334 0 0
srv_recalc_idx_stat 4 4 0 0.00 70601 382311 230335 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 15 15 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5108 5108 0 0.00 1 12797 896 0 0
t$perf-norm:sp_cancel_adding_invoice 397 397 0 0.00 1 4235 301 0 0
t$perf-norm:sp_cancel_client_order 1725 1725 0 0.00 0 6106 28 0 0
t$perf-norm:sp_cancel_customer_reserve 719 719 0 0.00 0 2470 30 0 0
t$perf-norm:sp_cancel_pay_from_customer 698 698 0 0.00 0 585 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 392 392 0 0.00 0 5851 39 0 0
t$perf-norm:sp_cancel_supplier_invoice 357 357 0 0.00 0 1387 38 0 0
t$perf-norm:sp_cancel_supplier_order 401 401 0 0.00 0 114611 6759 0 0
t$perf-norm:sp_cancel_write_off 999 999 0 0.00 0 8055 33 0 0
t$perf-norm:sp_client_order 21199 21199 0 0.00 0 7369 35 0 0
t$perf-norm:sp_customer_reserve 928 928 0 0.00 0 6673 23 0 0
t$perf-norm:sp_pay_from_customer 7318 7318 0 0.00 0 7965 21 0 0
t$perf-norm:sp_pay_to_supplier 5876 5876 0 0.00 0 9191 19 0 0
t$perf-norm:sp_reserve_write_off 10411 10411 0 0.00 0 4407 22 0 0
t$perf-norm:sp_supplier_invoice 5594 5594 0 0.00 0 8782 29 0 0
t$perf-norm:sp_supplier_order 5632 5632 0 0.00 0 12907 31 0 0
t$perf-norm:srv_aggregate_perf_data 121 121 0 0.00 0 467 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 2292 70 0 0
t$perf-norm:srv_make_invnt_saldo 441 441 0 0.00 0 502 13 0 0
t$perf-norm:srv_make_money_saldo 894 894 0 0.00 0 9281 19 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 5 272 82 0 0
v_add_invoice_to_stock 10320 10098 222 2.15 0 8067 25 0 222
v_all_customers 38550 38550 0 0.00 0 106 4 0 0
v_all_suppliers 31444 31444 0 0.00 0 87 4 0 0
v_all_wares 42396 42396 0 0.00 0 104 4 0 0
v_cancel_adding_invoice 794 794 0 0.00 0 667 11 0 0
v_cancel_client_order 3450 3450 0 0.00 0 1520 9 0 0
v_cancel_customer_prepayment 1396 1396 0 0.00 0 479 10 0 0
v_cancel_customer_reserve 1438 1438 0 0.00 1 788 10 0 0
v_cancel_payment_to_supplier 784 784 0 0.00 0 2388 14 0 0
v_cancel_supplier_invoice 744 714 30 4.03 0 473 23 0 30
v_cancel_supplier_order 802 802 0 0.00 0 935 13 0 0
v_cancel_write_off 1998 1998 0 0.00 0 5079 23 0 0
v_max_id_clo_ord 5632 5632 0 0.00 0 1655 5 0 0
v_max_id_clo_res 928 928 0 0.00 0 1805 15 0 0
v_max_id_ord_sup 5591 5591 0 0.00 0 4149 8 0 0
v_max_non_paid_invoice 1377 1377 0 0.00 0 211 8 0 0
v_max_non_paid_realizn 3872 3872 0 0.00 0 1181 7 0 0
v_min_id_clo_ord 5632 5632 0 0.00 0 1408 7 0 0
v_min_id_clo_res 928 928 0 0.00 0 423 7 0 0
v_min_id_ord_sup 5591 5591 0 0.00 0 8344 14 0 0
v_min_non_paid_invoice 5876 5876 0 0.00 0 3514 12 0 0
v_min_non_paid_realizn 7318 7318 0 0.00 0 5785 15 0 0
v_our_firm 10738 10738 0 0.00 0 66 5 0 0
v_reserve_write_off 20822 20822 0 0.00 0 2797 9 0 0
x_lock_depdocs_on_canc_invoice 11303 11303 0 0.00 0 12669 112 0 0
x_lock_depdocs_on_canc_sup_ord 401 401 0 0.00 0 2769 313 0 0
x_make_qstorno_1000_1200 5632 5632 0 0.00 2 35587 1158 0 0
x_make_qstorno_1200_2000 5594 5594 0 0.00 4 98214 2825 0 0
x_make_qstorno_2100_3300 475213 475213 0 0.00 0 167919 201 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 31-07-2022 13:39:12 to 31-07-2022 13:39:16.

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:22 2 8192000000 8796669104 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:23 24044 8192000000 9720880320 10018824192 796832464 8.864 100 89 178 0 826424208 7750384 21841360
11:09:51 12345 8192000000 9765594528 10071552000 839344704 9.293 100 90 180 0 871673024 11366272 20962048
11:11:17 15245 8192000000 9797928704 10118340608 864676144 9.547 100 82 162 0 903648560 20326064 18646352
11:12:41 9037 8192000000 9866142128 10203074560 915966560 10.056 100 78 156 0 974558832 41537024 17055248
11:13:56 4498 8192000000 9925705584 10268192768 958301280 10.472 100 90 176 0 1040932608 64352752 18278576
11:15:04 2038 8192000000 9977401552 10333966336 999914976 10.878 100 92 177 2 1091156096 73226912 18014208
11:16:09 1080 8192000000 10013977856 10376470528 1027163984 11.141 100 96 185 0 1128332224 83009440 18158800
11:17:12 3463 8192000000 9991733712 10357186560 1043349328 11.297 100 82 159 0 1111586480 53233552 15003600
11:18:17 1347 8192000000 10029120416 10405916672 1064477440 11.499 100 98 193 0 1151710496 67452256 19780800
11:19:20 709 8192000000 10036365296 10416119808 1072161456 11.573 100 95 182 2 1154617840 63236256 19220128
11:20:22 986 8192000000 10063505632 10453200896 1079593888 11.644 100 80 161 1 1187925184 91746208 16585088
11:21:24 988 8192000000 10062932704 10451329024 1088925632 11.732 100 90 169 1 1185336800 79803248 16607920
11:22:26 1123 8192000000 10066380944 10453225472 1092882400 11.770 100 91 172 0 1183832576 75021728 15928448
11:23:28 1499 8192000000 10063113232 10450792448 1095847216 11.798 100 96 181 3 1190423968 75348400 19228352
11:24:30 1202 8192000000 10069617344 10459631616 1096669072 11.806 100 92 177 0 1190726816 76657024 17400720
11:25:32 1104 8192000000 10071345152 10462232576 1101391616 11.851 100 98 181 0 1196478384 76666256 18420512
11:26:34 1522 8192000000 10066989168 10458439680 1106924656 11.903 100 85 167 2 1185487840 62764848 15798336
11:27:37 969 8192000000 10053826288 10441375744 1098735664 11.826 100 92 183 0 1173578272 55742048 19100560
11:28:40 799 8192000000 10057936432 10448457728 1097836784 11.817 100 93 172 2 1180346384 64203824 18305776
11:29:41 1259 8192000000 10056819744 10442911744 1094061616 11.781 100 96 182 1 1182934832 70353728 18519488
11:30:44 1110 8192000000 10057640080 10441478144 1097185776 11.811 100 90 170 2 1173742000 59565456 16990768
11:31:46 1266 8192000000 10021214048 10403000320 1066079536 11.515 100 97 186 3 1139048624 54520832 18448256
11:32:48 1010 8192000000 10005074768 10378383360 1045068912 11.313 100 93 173 1 1125807424 62230528 18507984
11:33:50 15206 8192000000 9985068128 10368536576 1026862528 11.138 99 64 127 1 1094476336 55112512 12501296
11:35:06 2464 8192000000 9960796208 10332692480 1025874192 11.129 100 72 134 0 1083769648 44261200 13634256
11:36:10 918 8192000000 9993670512 10369110016 1043878496 11.302 100 92 173 4 1114033424 49833760 20321168
11:37:14 887 8192000000 10024175728 10401476608 1056821504 11.426 100 92 180 1 1147871664 72324768 18725392
11:38:23 1179 8192000000 10014385216 10389929984 1063089824 11.486 100 95 183 2 1140891664 59190432 18611408
11:39:28 1304 8192000000 10022341472 10403700736 1079185792 11.640 100 84 162 2 1144200560 47019376 17995392
11:40:33 913 8192000000 10075159904 10459127808 1088651328 11.730 100 84 153 2 1194051488 90385376 15014784
11:41:37 874 8192000000 10086160048 10468102144 1103087792 11.867 100 93 169 2 1203124832 83186304 16850736
11:42:41 11275 8192000000 10117269824 10513682432 1113306688 11.964 100 55 106 1 1232549696 108275008 10968000
11:44:03 1092 8192000000 10182554176 10579136512 1136255200 12.180 100 93 174 1 1297705568 143060528 18389840
11:45:07 1072 8192000000 10194826384 10594418688 1147417360 12.285 100 96 184 1 1310709904 143204608 20087936
11:46:14 2177 8192000000 10235246992 10635907072 1156881344 12.374 100 88 172 1 1353496048 178944928 17669776
11:47:23 6130 8192000000 10282763472 10685603840 1171832464 12.514 100 72 140 0 1397600688 210500048 15268176
11:48:32 761 8192000000 10317857632 10725339136 1179225424 12.583 100 94 177 1 1428237840 229625664 19386752
11:49:37 1719 8192000000 10342923600 10761560064 1180688016 12.597 100 87 170 0 1457283488 256748032 19847440
11:50:50 2208 8192000000 10389521840 10798669824 1184193328 12.629 100 79 155 1 1494600912 292621184 17786400
11:51:56 1344 8192000000 10413143392 10848350208 1184510832 12.632 100 93 181 2 1524612144 319832896 20268416
11:53:00 956 8192000000 10432441104 10842132480 1193600416 12.717 100 99 190 2 1541211376 325234016 22376944
11:54:05 4590 8192000000 10432640192 10862010368 1181052944 12.600 100 74 147 0 1545406448 346427280 17926224
11:55:16 753 8192000000 10476658176 10899767296 1178035808 12.572 100 98 195 0 1583776992 382174176 23567008
11:56:22 821 8192000000 10477155072 10902970368 1187130640 12.657 100 97 190 3 1582832480 372141328 23560512
11:57:27 988 8192000000 10487952128 10919161856 1183221648 12.620 100 99 199 0 1596116496 389310960 23583888
11:58:30 716 8192000000 10498388624 10942959616 1179886240 12.589 100 96 189 0 1604914144 402659664 22368240
11:59:34 972 8192000000 10461407200 10929332224 1184847104 12.635 100 95 188 2 1563639920 355756080 23036736
12:00:38 1298 8192000000 10489553760 10920710144 1195423360 12.734 100 81 158 0 1592617360 378309120 18884880
12:01:46 14640 8192000000 10470369072 10930991104 1190047680 12.684 100 43 87 0 1575619104 374467840 11103584
12:03:01 5390 8192000000 10472346704 10900525056 1153958496 12.347 99 38 74 1 1572215920 408530064 9727360
12:04:10 27429 8192000000 10500149760 10957942784 1159877376 12.402 100 31 62 1 1593082608 424747632 8457600
12:05:43 8792 8192000000 10377981872 10932420608 1143462896 12.248 100 37 73 0 1491213472 338281872 9468704
12:06:54 15180 8192000000 10347781440 10846355456 1135347824 12.172 100 36 72 0 1457400112 313165008 8887280
12:08:23 28587 8192000000 10295537808 10751045632 1127255664 12.095 99 26 52 0 1392829040 258856800 6716576
12:09:52 41042 8192000000 10081278400 10676510720 1101263952 11.850 98 9 18 0 1279746128 176280960 2201216
12:11:58 11190 8192000000 10084289280 10512728064 1127693936 12.100 100 40 78 0 1167894000 32049680 8150384
12:13:22 34567 8192000000 10095763968 10525294592 1124979520 12.074 100 35 69 0 1187049472 54206480 7863472
12:14:57 38124 8192000000 10090862256 10535989248 1118844448 12.016 100 26 52 0 1190591536 66679632 5067456
12:16:40 80764 8192000000 10094430880 10540699648 1089649296 11.739 96 20 39 0 1165092944 71525376 3918272
12:19:04 86052 8192000000 10083228480 10543788032 1116124272 11.990 99 42 77 1 1159303056 34639520 8539264
12:21:51 159458 8192000000 10132401648 10569711616 1139762176 12.213 98 19 38 0 1215267264 71454368 4050720
12:25:36 15746 8192000000 10196386912 10622160896 1139341648 12.209 99 62 111 2 1284762880 133463232 11958000
12:26:53 37511 8192000000 10192856432 10676752384 1144880576 12.261 99 23 46 0 1293897648 143233584 5783488
12:28:45 63460 8192000000 10208910976 10678439936 1151215696 12.321 100 18 35 0 1302273072 146867200 4190176
12:30:50 61282 8192000000 10092567728 10567553024 1139363920 12.210 100 16 29 1 1217528672 74797168 3367584
12:33:55 49867 8192000000 10069086064 10509086720 1137629552 12.193 99 27 48 0 1161817488 19506688 4681248
12:35:45 99817 8192000000 10111867376 10555514880 1132995760 12.150 98 18 36 0 1204943984 68150560 3797664
12:38:27 82730 8192000000 10103103984 10576547840 1139882064 12.214 99 14 28 0 1200046112 57649200 2514848
12:40:51 67976 8192000000 10124101968 10586775552 1126840128 12.092 97 11 22 0 1166266224 37513808 1912288
12:43:08 56019 8192000000 10068862896 10511192064 1091948208 11.761 95 17 32 0 1138350736 43054272 3348256
12:45:08 26987 8192000000 10122102304 10574233600 1122324096 12.049 98 27 54 0 1176300976 48626112 5350768
12:46:43 75940 8192000000 10141388096 10615508992 1149187456 12.302 100 22 44 0 1234760560 80996416 4576688
12:49:46 59669 8192000000 10080625200 10535911424 1113134640 11.962 97 27 50 1 1184287936 65453168 5700128
12:51:47 68006 8192000000 10077339776 10524667904 1124772128 12.072 98 21 43 0 1178171600 49397184 4002288
12:53:55 40681 8192000000 10078495056 10529976320 1124606608 12.070 99 14 28 0 1183272352 55561120 3104624
12:55:39 71737 8192000000 10107787248 10561261568 1125140352 12.076 99 18 37 0 1191420288 62279680 4000256
12:58:06 59366 8192000000 10063517056 10551472128 1090070208 11.743 98 15 31 0 1156622160 63061472 3490480
13:00:06 29612 8192000000 10105295744 10554642432 1126376480 12.087 100 38 69 2 1202504320 67949360 8178480
13:01:39 51583 8192000000 10142522624 10584530944 1114278784 11.973 99 32 64 0 1229045072 107425600 7340688
13:03:33 46485 8192000000 10120055840 10558459904 1104967808 11.885 98 22 44 0 1203269376 93652272 4649296
13:05:20 23839 8192000000 10109610240 10547486720 1093853376 11.779 97 36 72 0 1183086720 81658752 7574592
13:06:48 23755 8192000000 10140528176 10565861376 1103614384 11.872 98 48 94 2 1216531376 104116688 8800304
13:08:12 51379 8192000000 10099895280 10583650304 1094180768 11.782 97 25 46 0 1195006912 96169968 4656176
13:10:13 89071 8192000000 10111453760 10559201280 1102904224 11.865 98 13 26 0 1161765920 56409712 2451984
13:12:43 64146 8192000000 10060229392 10495541248 1116535168 11.994 99 43 87 1 1157732496 31046448 10150880
13:14:47 78959 8192000000 10076998336 10556063744 1123147408 12.057 99 10 20 0 1174927184 49611984 2167792
13:17:06 59475 8192000000 10098890688 10562658304 1129986880 12.121 99 15 30 0 1174542640 41553552 3002208
13:19:09 42296 8192000000 10059014704 10519678976 1122178048 12.048 99 27 51 0 1161844336 34044928 5621360
13:20:54 41247 8192000000 10121603792 10562662400 1136617504 12.184 99 30 58 0 1191959200 49454640 5887056
13:22:38 69455 8192000000 10125105760 10573402112 1114200992 11.972 97 19 39 0 1204119136 85646672 4271472
13:24:53 11137 8192000000 10099998976 10530512896 1136210320 12.180 99 55 101 1 1189255088 42744288 10300480
13:26:07 29988 8192000000 10096624704 10536198144 1135529632 12.173 99 42 84 0 1192027872 48185904 8312336
13:27:38 37651 8192000000 10145749040 10576502784 1132781040 12.148 99 44 86 0 1197058144 55639984 8637120
13:29:17 64536 8192000000 10100336608 10616164352 1116509232 11.994 97 16 32 0 1197960768 78018688 3432848
13:32:43 60330 8192000000 10068092848 10543751168 1144262096 12.256 100 17 34 0 1186678496 38774288 3642112
13:34:43 78397 8192000000 10078129872 10562834432 1147150704 12.283 100 10 20 0 1194832128 45410432 2270992
13:37:15 13249 8192000000 9130829104 9433477120 285264912 3.365 25 2 4 0 300898128 15388032 245184

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 31-07-2022 13:39:18 to 31-07-2022 13:39:34.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 31-07-2022 13:39:35 to 31-07-2022 13:39:37.

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          225375
MON$OLDEST_ACTIVE               225376
MON$OLDEST_SNAPSHOT             225376
MON$NEXT_TRANSACTION            225376
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                       2062560
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             774
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2811 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2811 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2811 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2811 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 ---
:364: 
:365: mscs-test-161.budzdorov.ru    Sun Jul 31 13:37:34 2022
:366:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:367: 
--- end of diff output ---

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

31.07.22 13:39:38. 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-07-31 13:39:40 No severe PSQL-related problems occured
31.07.22 13:39:41. End of report.