Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 11.12.2021 07:51
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 16641384 kB
MemAvailable 31511236 kB
Buffers 0 kB
Cached 15082280 kB
SwapCached 0 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 2021-12-11 07:50:09
DTS_END                         2021-12-11 07:50:09.1860
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/38720
TRN_ID                          221729
ATT_ID                          612
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: 8926.36 23207 1071163
customer order: creation 161.55 430 19386
customer order: refuse 14.28 312 1714
order to supplier: creation 42.22 1555 5066
order to supplier: removal 3.31 132154 397
invoice (draft): creation 42.76 3951 5131
invoice (draft): removal 51.73 1068 6208
invoice accept: apply 39.24 20871 4709
invoice accept: cancel 47.04 934 5645
customer reserve: creation 3639.19 205 436703
customer reserve: removal 4608.09 73 552971
realization accept: apply 79.11 215 9493
realization accept: cancel 78.83 142 9460
payment from customer: creation 55.59 147 6671
payment from customer: removal 4.83 88 579
payment to supplier: creation 45.01 156 5401
payment to supplier: removal 3.26 88 391
service: total inventory turnovers 3.53 4104 424
service: total monetary turnovers 5.83 741 700
service: aggregate perf. data 0.92 15095 110
service: refresh index statistics 0.03 281810 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 11-12-2021 07:52:13 to 11-12-2021 07:52:15.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 11010
TEST_TIME 2 7795
TEST_TIME 3 6964
TEST_TIME 4 6453
TEST_TIME 5 6218
TEST_TIME 6 5897
TEST_TIME 7 6219
TEST_TIME 8 6390
TEST_TIME 9 6389
TEST_TIME 10 6472
TEST_TIME 11 6450
TEST_TIME 12 6787
TEST_TIME 13 6788
TEST_TIME 14 6799
TEST_TIME 15 7020
TEST_TIME 16 7028
TEST_TIME 17 7012
TEST_TIME 18 7119
TEST_TIME 19 7870
TEST_TIME 20 7878
TEST_TIME 21 8046
TEST_TIME 22 8133
TEST_TIME 23 8122
TEST_TIME 24 8412
TEST_TIME 25 8639
TEST_TIME 26 9180
TEST_TIME 27 9772
TEST_TIME 28 9835
TEST_TIME 29 11156
TEST_TIME 30 10797
TEST_TIME 31 10727
TEST_TIME 32 10983
TEST_TIME 33 10802
TEST_TIME 34 10620
TEST_TIME 35 10483
TEST_TIME 36 10302
TEST_TIME 37 10209
TEST_TIME 38 10089
TEST_TIME 39 9913
TEST_TIME 40 9914
TEST_TIME 41 9783
TEST_TIME 42 9738
TEST_TIME 43 9754
TEST_TIME 44 9667
TEST_TIME 45 9582
TEST_TIME 46 9591
TEST_TIME 47 9555
TEST_TIME 48 9499
TEST_TIME 49 9516
TEST_TIME 50 9435
TEST_TIME 51 9283
TEST_TIME 52 9135
TEST_TIME 53 9183
TEST_TIME 54 9239
TEST_TIME 55 9160
TEST_TIME 56 9027
TEST_TIME 57 9038
TEST_TIME 58 8941
TEST_TIME 59 8854
TEST_TIME 60 8934
TEST_TIME 61 8924
TEST_TIME 62 8931
TEST_TIME 63 8930
TEST_TIME 64 9005
TEST_TIME 65 9011
TEST_TIME 66 8972
TEST_TIME 67 9096
TEST_TIME 68 9030
TEST_TIME 69 9177
TEST_TIME 70 9206
TEST_TIME 71 9187
TEST_TIME 72 9143
TEST_TIME 73 9199
TEST_TIME 74 9205
TEST_TIME 75 9086
TEST_TIME 76 9021
TEST_TIME 77 9089
TEST_TIME 78 9071
TEST_TIME 79 9009
TEST_TIME 80 8989
TEST_TIME 81 8939
TEST_TIME 82 8910
TEST_TIME 83 8870
TEST_TIME 84 8891
TEST_TIME 85 8881
TEST_TIME 86 8856
TEST_TIME 87 8881
TEST_TIME 88 8878
TEST_TIME 89 8880
TEST_TIME 90 8919
TEST_TIME 91 8935
TEST_TIME 92 8950
TEST_TIME 93 9010
TEST_TIME 94 9011
TEST_TIME 95 9085
TEST_TIME 96 9105
TEST_TIME 97 9109
TEST_TIME 98 9125
TEST_TIME 99 9056
TEST_TIME 100 8983
TEST_TIME 101 8958
TEST_TIME 102 8959
TEST_TIME 103 8939
TEST_TIME 104 8929
TEST_TIME 105 8881
TEST_TIME 106 8887
TEST_TIME 107 8845
TEST_TIME 108 8834
TEST_TIME 109 8831
TEST_TIME 110 8807
TEST_TIME 111 8876
TEST_TIME 112 8878
TEST_TIME 113 8928
TEST_TIME 114 8906
TEST_TIME 115 8885
TEST_TIME 116 8958
TEST_TIME 117 8931
TEST_TIME 118 8999
TEST_TIME 119 9003
TEST_TIME 120 8929

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 11-12-2021 07:52:17 to 11-12-2021 07:52:20.

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 1476335 1476335 0 0.00 0 24265 8 0 0
doc_list_biud 1476332 1476332 0 0.00 0 102681 35 0 0
sp_add_invoice_to_stock 4802 4709 93 1.93 36 233506 20872 0 93
sp_cancel_adding_invoice 5645 5645 0 0.00 5 103514 934 0 0
sp_cancel_client_order 1714 1714 0 0.00 4 10118 312 0 0
sp_cancel_customer_reserve 552971 552971 0 0.00 1 33314 74 0 0
sp_cancel_pay_from_customer 579 579 0 0.00 3 5045 89 0 0
sp_cancel_pay_to_supplier 391 391 0 0.00 3 4148 89 0 0
sp_cancel_supplier_invoice 6211 6208 3 0.04 1 102687 1069 0 3
sp_cancel_supplier_order 397 397 0 0.00 7 1245831 132154 0 0
sp_cancel_write_off 9460 9460 0 0.00 1 20675 143 0 0
sp_client_order 19386 19386 0 0.00 4 52781 431 0 0
sp_customer_reserve 436703 436703 0 0.00 0 80947 205 0 0
sp_fill_shopping_cart 29556 29556 0 0.00 1 19796 48 0 0
sp_fill_shopping_cart_clo_res 436692 436692 0 0.00 0 25594 17 0 0
sp_get_clo_for_invoice 4698 4698 0 0.00 0 25426 383 0 0
sp_kill_cost_storno 16075 16075 0 0.00 0 9440 66 0 0
sp_kill_qstorno_ret_qs2qd 1122007 1122007 0 0.00 0 102676 40 0 0
sp_kill_qty_storno 590586 590586 0 0.00 0 102680 86 0 0
sp_lock_selected_doc 19598 19598 0 0.00 0 8059 7 0 0
sp_make_cost_storno 26263 26263 0 0.00 0 20778 69 0 0
sp_multiply_rows_for_pdistr 26263 26263 0 0.00 0 13657 35 0 0
sp_multiply_rows_for_qdistr 19386 19386 0 0.00 0 29624 198 0 0
sp_pay_from_customer 6671 6671 0 0.00 4 24270 147 0 0
sp_pay_to_supplier 5401 5401 0 0.00 5 22648 157 0 0
sp_payment_common 12069 12069 0 0.00 1 14237 29 0 0
sp_qd_handle_on_cancel_clo 1714 1714 0 0.00 0 10103 278 0 0
sp_qd_handle_on_invoice_upd_sts 10343 10343 0 0.00 0 51528 402 0 0
sp_qd_handle_on_reserve_upd_sts 9493 9493 0 0.00 0 20033 65 0 0
sp_reserve_write_off 9493 9493 0 0.00 7 21106 215 0 0
sp_supplier_invoice 5131 5131 0 0.00 7 114724 3951 0 0
sp_supplier_order 5066 5066 0 0.00 5 107519 1556 0 0
srv_aggregate_perf_data 110 110 0 0.00 2697 163548 15096 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 922 120813 47274 0 0
srv_find_qd_qs_mism 1026958 1026958 0 0.00 0 18688 7 0 0
srv_make_invnt_saldo 424 424 0 0.00 56 113099 4104 0 0
srv_make_money_saldo 700 700 0 0.00 1 20824 742 0 0
srv_recalc_idx_stat 4 4 0 0.00 93559 565863 281810 0 0
t$perf-abend:sp_add_invoice_to_stock 93 93 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 3 3 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4709 4709 0 0.00 0 34796 228 0 0
t$perf-norm:sp_cancel_adding_invoice 405 405 0 0.00 0 14068 138 0 0
t$perf-norm:sp_cancel_client_order 1714 1714 0 0.00 0 4180 13 0 0
t$perf-norm:sp_cancel_customer_reserve 626 626 0 0.00 0 1093 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 579 579 0 0.00 0 1110 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 391 391 0 0.00 0 2618 18 0 0
t$perf-norm:sp_cancel_supplier_invoice 416 416 0 0.00 0 3572 31 0 0
t$perf-norm:sp_cancel_supplier_order 397 397 0 0.00 0 37464 2012 0 0
t$perf-norm:sp_cancel_write_off 881 881 0 0.00 0 1817 8 0 0
t$perf-norm:sp_client_order 19386 19386 0 0.00 0 23531 24 0 0
t$perf-norm:sp_customer_reserve 961 961 0 0.00 0 442 3 0 0
t$perf-norm:sp_pay_from_customer 6671 6671 0 0.00 0 4738 8 0 0
t$perf-norm:sp_pay_to_supplier 5401 5401 0 0.00 0 4037 7 0 0
t$perf-norm:sp_reserve_write_off 9493 9493 0 0.00 0 15310 12 0 0
t$perf-norm:sp_supplier_invoice 5131 5131 0 0.00 0 23743 27 0 0
t$perf-norm:sp_supplier_order 5066 5066 0 0.00 0 23348 24 0 0
t$perf-norm:srv_aggregate_perf_data 110 110 0 0.00 0 64 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 981 25 0 0
t$perf-norm:srv_make_invnt_saldo 424 424 0 0.00 0 9902 46 0 0
t$perf-norm:srv_make_money_saldo 700 700 0 0.00 0 680 9 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 3 0 0
v_add_invoice_to_stock 9580 9394 186 1.94 0 8436 19 0 186
v_all_customers 35428 35428 0 0.00 0 18 1 0 0
v_all_suppliers 28080 28080 0 0.00 0 18 1 0 0
v_all_wares 38758 38758 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 810 810 0 0.00 0 1199 7 0 0
v_cancel_client_order 3428 3428 0 0.00 0 7937 6 0 0
v_cancel_customer_prepayment 1158 1158 0 0.00 0 3772 10 0 0
v_cancel_customer_reserve 1252 1252 0 0.00 0 250 3 0 0
v_cancel_payment_to_supplier 782 782 0 0.00 0 3955 19 0 0
v_cancel_supplier_invoice 838 832 6 0.71 0 194 9 0 6
v_cancel_supplier_order 794 794 0 0.00 0 8124 15 0 0
v_cancel_write_off 1762 1762 0 0.00 0 652 12 0 0
v_max_id_clo_ord 5065 5065 0 0.00 0 703 2 0 0
v_max_id_clo_res 961 961 0 0.00 0 3523 21 0 0
v_max_id_ord_sup 5112 5112 0 0.00 0 8144 6 0 0
v_max_non_paid_invoice 1535 1535 0 0.00 0 246 2 0 0
v_max_non_paid_realizn 3432 3432 0 0.00 0 6370 7 0 0
v_min_id_clo_ord 5065 5065 0 0.00 0 12826 10 0 0
v_min_id_clo_res 961 961 0 0.00 0 3644 16 0 0
v_min_id_ord_sup 5112 5112 0 0.00 0 8216 11 0 0
v_min_non_paid_invoice 5398 5398 0 0.00 0 11268 18 0 0
v_min_non_paid_realizn 6671 6671 0 0.00 0 14234 21 0 0
v_our_firm 9808 9808 0 0.00 0 27 1 0 0
v_reserve_write_off 18984 18984 0 0.00 0 6646 6 0 0
x_lock_depdocs_on_canc_invoice 11437 11437 0 0.00 0 13943 125 0 0
x_lock_depdocs_on_canc_sup_ord 397 397 0 0.00 0 12082 373 0 0
x_make_qstorno_1000_1200 5066 5066 0 0.00 2 94657 1468 0 0
x_make_qstorno_1200_2000 5131 5131 0 0.00 3 113964 3761 0 0
x_make_qstorno_2100_3300 436703 436703 0 0.00 0 80945 186 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 11-12-2021 07:52:21 to 11-12-2021 07:52:25.

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:20:11 16 8192000000 8801617328 8814059520 5639952 0.068 3 1 2 0 5741120 65392 35776
05:21:13 32632 8192000000 9726023968 10024017920 799620576 8.892 100 88 176 0 828927872 7775248 21532048
05:22:49 26794 8192000000 9770757376 10078220288 838050752 9.280 100 88 176 0 871253488 12124480 21078256
05:24:19 24151 8192000000 9819096016 10137878528 872442976 9.624 100 82 164 0 911363824 20208272 18712576
05:25:50 13783 8192000000 9882499056 10217869312 924516464 10.141 100 85 170 0 994218736 50801120 18901152
05:27:06 8736 8192000000 9932978176 10280333312 967697568 10.564 100 78 156 0 1051825152 67950496 16177088
05:28:18 3782 8192000000 9968938848 10329493504 1000891744 10.887 100 70 140 1 1085206576 70677504 13637328
05:29:25 1680 8192000000 9989172640 10360705024 1031658944 11.184 100 65 130 0 1108640544 63789632 13191968
05:30:29 1158 8192000000 10037262992 10407751680 1051765008 11.378 100 95 192 0 1153086480 81900080 19421392
05:31:32 4131 8192000000 10020928976 10399842304 1056743344 11.425 100 81 164 2 1134284912 60808752 16732816
05:32:37 5701 8192000000 9986233456 10383458304 1062232224 11.478 100 33 66 2 1097474512 28268784 6973504
05:33:44 5597 8192000000 9988663552 10396827648 1071279888 11.564 100 32 65 0 1125073248 47494704 6298656
05:34:50 45542 8192000000 9989497728 10398683136 1077035152 11.619 100 32 65 0 1106659696 22998224 6626320
05:36:36 5976 8192000000 10005704192 10410061824 1081964000 11.666 100 45 89 0 1115929344 23720048 10245296
05:37:46 12091 8192000000 9997957488 10409504768 1089144496 11.735 100 17 34 1 1125921280 33558784 3218000
05:38:58 8230 8192000000 10014352480 10423857152 1088691840 11.730 100 23 45 1 1124047840 30563328 4792672
05:40:08 14730 8192000000 10009331152 10397945856 1090132848 11.744 100 56 115 1 1117555760 14570720 12852192
05:41:25 7055 8192000000 9994979184 10404462592 1065970224 11.514 98 39 78 0 1098960544 24213616 8776704
05:42:33 4105 8192000000 9941116304 10323705856 990889728 10.790 95 52 103 3 1016546832 14385664 11271440
05:43:41 13672 8192000000 9917297152 10296307712 966634800 10.554 95 36 71 1 988349328 13694736 8019792
05:44:56 29262 8192000000 9927818976 10320441344 999484080 10.874 97 34 68 0 1027843520 20492112 7867328
05:46:31 5366 8192000000 9971274640 10345648128 1034473408 11.212 100 59 118 2 1079591728 32898736 12219584
05:47:37 10967 8192000000 9976637456 10375421952 1051758736 11.378 100 39 76 1 1092515056 32070144 8686176
05:48:49 12157 8192000000 9984313328 10387378176 1059543840 11.452 100 31 62 1 1104998096 38805136 6649120
05:50:03 66416 8192000000 10009085040 10394484736 1079437920 11.642 100 50 100 3 1106072784 16402656 10232208
05:52:13 17294 8192000000 10063324144 10464124928 1101796320 11.855 100 49 96 2 1171668560 59937168 9935072
05:53:32 25055 8192000000 10106009616 10515988480 1118409936 12.012 100 53 106 0 1201102624 71568160 11124528
05:55:03 12233 8192000000 10121885104 10523070464 1132096160 12.141 100 53 105 0 1228694704 85180368 11418176
05:56:19 12075 8192000000 10147309712 10546475008 1136463024 12.182 100 57 113 1 1252891232 104589776 11838432
05:57:34 11425 8192000000 10185897040 10591080448 1150633264 12.315 100 62 123 0 1301509440 137545904 13330272
05:58:48 3014 8192000000 10237143200 10636951552 1166350432 12.463 100 70 139 0 1338315472 157282768 14682272
06:00:01 13173 8192000000 10251513504 10664038400 1167793008 12.476 100 58 112 0 1357550144 176883456 12873680
06:01:19 7772 8192000000 10267503376 10676338688 1174680800 12.541 100 61 121 3 1371233952 182596528 13956624
06:02:33 2387 8192000000 10314299008 10717573120 1186652352 12.652 100 78 156 1 1413874560 210395792 16826416
06:03:42 6701 8192000000 10354124752 10763374592 1182808464 12.616 100 77 155 1 1458263264 258710656 16744144
06:04:56 921 8192000000 10401314384 10806566912 1198978976 12.767 100 89 179 1 1507514080 288711888 19823216
06:06:05 1577 8192000000 10416363008 10824548352 1191136192 12.694 100 87 174 0 1516384560 305445968 19802400
06:07:11 5285 8192000000 10449407904 10858938368 1194732992 12.727 100 65 130 0 1549022464 338712080 15577392
06:08:21 929 8192000000 10467057136 10867294208 1168138928 12.479 98 88 176 0 1562129792 371798736 22192128
06:09:29 5169 8192000000 10472848080 10887114752 1180732528 12.597 100 63 126 1 1575509312 379464400 15312384
06:10:40 5897 8192000000 10451881376 10856374272 1159307616 12.397 98 76 151 1 1523622480 346853968 17460896
06:11:52 2832 8192000000 10475219232 10910871552 1164006928 12.441 100 59 118 1 1571429472 392534560 14887984
06:13:19 10724 8192000000 10510649248 10932740096 1154609808 12.353 100 48 96 0 1603071472 435624944 12836720
06:14:35 6150 8192000000 10562635824 11000950784 1166545280 12.465 100 53 106 1 1674394592 493764624 14084688
06:15:44 15048 8192000000 10535010064 11016282112 1133965904 12.159 99 39 77 0 1633629616 489156336 10507376
06:17:01 12044 8192000000 10447183024 10892824576 1128465008 12.107 100 29 58 1 1542225648 405826912 7933728
06:19:00 8339 8192000000 10279789408 10802909184 1137812528 12.195 100 41 82 0 1404149584 256614320 9722736
06:20:10 86544 8192000000 10173946256 10598100992 1121042624 12.037 99 60 120 0 1268533408 133944144 13546640
06:22:39 92592 8192000000 10051101040 10514800640 1124627504 12.071 99 7 14 0 1155552464 29551424 1373536
06:25:13 33624 8192000000 10077701968 10506076160 1139200480 12.208 100 41 81 0 1177958448 30134864 8623104
06:26:52 72610 8192000000 10088017200 10529890304 1122975920 12.055 98 24 44 1 1189660112 62078064 4606128
06:29:05 115499 8192000000 10105169152 10556395520 1102437152 11.861 97 30 60 3 1170961760 61004016 7520592
06:32:02 79136 8192000000 10139267712 10601189376 1122268544 12.048 98 31 62 1 1239791520 110301264 7221712
06:34:46 18046 8192000000 10106638656 10537844736 1137325200 12.190 100 46 92 2 1211328064 64588992 9413872
06:36:10 66612 8192000000 10091893088 10547310592 1128097088 12.103 99 26 51 0 1175422192 41584976 5740128
06:38:22 88550 8192000000 10072629728 10588815360 1104974768 11.885 96 8 16 0 1161250080 54380320 1894992
06:41:04 64463 8192000000 10103687792 10576080896 1146559584 12.277 100 12 24 1 1209010384 59810912 2639888
06:43:14 68108 8192000000 10072182800 10595811328 1136684368 12.184 99 12 24 0 1222218576 82418880 3115328
06:45:24 61744 8192000000 10088893088 10545524736 1151258304 12.321 100 14 27 1 1199870672 46046560 2565808
06:47:27 82559 8192000000 10123317936 10575896576 1158511760 12.389 100 13 26 0 1224601680 63175648 2914272
06:50:05 56085 8192000000 10077477584 10582433792 1146143504 12.273 99 13 26 0 1201314752 52848688 2322560
06:52:07 62853 8192000000 10097424464 10576814080 1153033712 12.338 100 15 30 0 1180372800 24762512 2576576
06:54:10 55330 8192000000 10099557296 10564644864 1162215296 12.424 100 18 36 0 1205582128 39894960 3471872
06:56:17 40294 8192000000 10146097472 10594942976 1146947056 12.281 98 32 64 0 1225936176 72691616 6297504
06:57:59 32796 8192000000 10124024736 10551267328 1142225232 12.236 99 38 75 0 1189612672 39266848 8120592
06:59:39 100136 8192000000 10071847648 10554523648 1070431840 11.556 94 17 34 1 1129826400 56089856 3304704
07:02:20 53599 8192000000 10119687024 10538762240 1107920336 11.913 98 44 88 0 1176047408 58745568 9381504
07:04:18 104039 8192000000 10061469760 10490417152 1096269088 11.802 97 30 60 1 1160487424 57884416 6333920
07:07:02 38149 8192000000 10095388512 10608504832 1117529056 12.004 100 10 20 0 1204957584 85376032 2052496
07:08:45 66693 8192000000 10031948368 10480209920 1069450528 11.547 95 11 23 0 1102414160 30810864 2152768
07:10:52 47726 8192000000 10068175456 10530447360 1107165904 11.906 99 20 41 0 1178065792 66499856 4400032
07:13:33 120812 8192000000 10078001312 10535952384 1126807600 12.091 100 10 20 1 1175352768 46403152 2142016
07:16:34 55037 8192000000 10076658208 10530652160 1130306832 12.124 99 19 38 0 1200936768 66968624 3661312
07:18:35 93049 8192000000 10070937792 10528550912 1082683648 11.673 96 29 58 0 1155257168 66532768 6040752
07:21:08 63618 8192000000 10072926496 10521583616 1123309552 12.058 99 26 52 1 1166764512 38592976 4861984
07:23:14 96100 8192000000 10089802544 10549432320 1110408080 11.936 97 15 30 0 1162572960 48763216 3401664
07:25:53 85543 8192000000 10066309952 10536964096 1113188800 11.963 98 16 32 0 1154682064 38411696 3081568
07:28:23 115419 8192000000 10057610352 10478100480 1040828832 11.273 91 21 42 0 1080083760 34790240 4464688
07:31:23 69511 8192000000 10072999264 10566606848 1120992048 12.036 99 25 50 1 1208995232 81993696 6009488
07:33:46 64734 8192000000 10055214848 10518614016 1125041648 12.075 99 13 26 1 1164920800 36960768 2918384
07:35:53 97284 8192000000 10050995296 10534027264 1123006656 12.055 99 11 22 2 1156415728 31041664 2367408
07:38:31 18622 8192000000 10081388304 10519236608 1140209248 12.217 100 44 88 0 1170708944 21394208 9105488
07:39:53 68325 8192000000 10161728608 10624475136 1127325248 12.096 98 28 56 0 1223259632 89619840 6314544
07:42:16 41697 8192000000 10124522192 10574897152 1136751328 12.185 99 16 31 0 1209625616 70020528 2853760
07:43:58 31683 8192000000 10087789872 10535469056 1152953952 12.337 100 21 42 2 1197264960 40116032 4194976
07:45:33 73424 8192000000 10116777888 10617806848 1146479088 12.276 100 26 52 0 1208533504 56816464 5237952
07:47:51 30349 8192000000 10149537072 10608717824 1130062304 12.122 99 46 92 1 1219355456 80036208 9256944
07:49:29 59965 8192000000 9363251776 9709449216 489641008 5.639 44 12 23 0 514097376 22243312 2213056

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 11-12-2021 07:52:27 to 11-12-2021 07:52:42.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 93 v_add_invoice_to_stock 335544517
except 3 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 11-12-2021 07:52:42 to 11-12-2021 07:52:44.

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          221240
MON$OLDEST_ACTIVE               221980
MON$OLDEST_SNAPSHOT             221980
MON$NEXT_TRANSACTION            221980
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                       2041440
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             771
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2682 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2682 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2682 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2682 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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Sat Dec 11 07:50:45 2021
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
--- end of diff output ---

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

11.12.21 07:52:45. 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
2021-12-11 07:52:48 No severe PSQL-related problems occured
11.12.21 07:52:49. End of report.