Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 11.08.2021 04: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 58e1ddde69c44dad93d327777e5becec
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-05-09 08:45
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 4793.92
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 15796816 kB
MemAvailable 31002448 kB
Buffers 20 kB
Cached 15825052 kB
SwapCached 2580 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-08-11 04:37:05
DTS_END                         2021-08-11 04:37:05.7350
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/50222
TRN_ID                          240728
ATT_ID                          642
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: 9540.55 23627 1144866
customer order: creation 175.58 361 21069
customer order: refuse 14.93 310 1791
order to supplier: creation 47.29 1304 5675
order to supplier: removal 3.42 115584 410
invoice (draft): creation 46.60 3255 5592
invoice (draft): removal 54.51 888 6541
invoice accept: apply 41.55 17637 4986
invoice accept: cancel 49.41 838 5929
customer reserve: creation 3919.78 170 470373
customer reserve: removal 4882.77 64 585932
realization accept: apply 85.16 183 10219
realization accept: cancel 87.15 102 10458
payment from customer: creation 61.67 127 7400
payment from customer: removal 5.93 87 712
payment to supplier: creation 50.24 131 6029
payment to supplier: removal 3.51 70 421
service: total inventory turnovers 3.63 3888 435
service: total monetary turnovers 6.44 510 773
service: aggregate perf. data 0.98 15165 117
service: refresh index statistics 0.03 311868 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 11-08-2021 04:38:26 to 11-08-2021 04:38:28.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6381
TEST_TIME 2 6601
TEST_TIME 3 6224
TEST_TIME 4 6430
TEST_TIME 5 6247
TEST_TIME 6 6125
TEST_TIME 7 5947
TEST_TIME 8 6057
TEST_TIME 9 6792
TEST_TIME 10 7240
TEST_TIME 11 7474
TEST_TIME 12 7631
TEST_TIME 13 7722
TEST_TIME 14 7684
TEST_TIME 15 7683
TEST_TIME 16 8337
TEST_TIME 17 8618
TEST_TIME 18 8958
TEST_TIME 19 8758
TEST_TIME 20 8973
TEST_TIME 21 9290
TEST_TIME 22 9331
TEST_TIME 23 9776
TEST_TIME 24 9794
TEST_TIME 25 10058
TEST_TIME 26 10443
TEST_TIME 27 10876
TEST_TIME 28 11484
TEST_TIME 29 11253
TEST_TIME 30 11184
TEST_TIME 31 11032
TEST_TIME 32 11190
TEST_TIME 33 11133
TEST_TIME 34 11225
TEST_TIME 35 11143
TEST_TIME 36 11045
TEST_TIME 37 11113
TEST_TIME 38 10999
TEST_TIME 39 10918
TEST_TIME 40 10739
TEST_TIME 41 10701
TEST_TIME 42 10666
TEST_TIME 43 10543
TEST_TIME 44 10461
TEST_TIME 45 10367
TEST_TIME 46 10313
TEST_TIME 47 10394
TEST_TIME 48 10333
TEST_TIME 49 10377
TEST_TIME 50 10268
TEST_TIME 51 10210
TEST_TIME 52 10215
TEST_TIME 53 10164
TEST_TIME 54 10228
TEST_TIME 55 10187
TEST_TIME 56 10170
TEST_TIME 57 10318
TEST_TIME 58 10291
TEST_TIME 59 10421
TEST_TIME 60 10388
TEST_TIME 61 10360
TEST_TIME 62 10358
TEST_TIME 63 10451
TEST_TIME 64 10602
TEST_TIME 65 10549
TEST_TIME 66 10503
TEST_TIME 67 10403
TEST_TIME 68 10304
TEST_TIME 69 10233
TEST_TIME 70 10173
TEST_TIME 71 10119
TEST_TIME 72 10103
TEST_TIME 73 10065
TEST_TIME 74 10014
TEST_TIME 75 9950
TEST_TIME 76 9911
TEST_TIME 77 9841
TEST_TIME 78 9780
TEST_TIME 79 9845
TEST_TIME 80 9852
TEST_TIME 81 9853
TEST_TIME 82 9818
TEST_TIME 83 9822
TEST_TIME 84 9807
TEST_TIME 85 9894
TEST_TIME 86 9922
TEST_TIME 87 9915
TEST_TIME 88 9995
TEST_TIME 89 10010
TEST_TIME 90 9966
TEST_TIME 91 9950
TEST_TIME 92 9888
TEST_TIME 93 9855
TEST_TIME 94 9853
TEST_TIME 95 9812
TEST_TIME 96 9776
TEST_TIME 97 9785
TEST_TIME 98 9748
TEST_TIME 99 9726
TEST_TIME 100 9727
TEST_TIME 101 9836
TEST_TIME 102 9827
TEST_TIME 103 9810
TEST_TIME 104 9815
TEST_TIME 105 9871
TEST_TIME 106 9837
TEST_TIME 107 9864
TEST_TIME 108 9876
TEST_TIME 109 9862
TEST_TIME 110 9781
TEST_TIME 111 9770
TEST_TIME 112 9754
TEST_TIME 113 9766
TEST_TIME 114 9721
TEST_TIME 115 9690
TEST_TIME 116 9655
TEST_TIME 117 9594
TEST_TIME 118 9618
TEST_TIME 119 9619
TEST_TIME 120 9543

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 11-08-2021 04:38:30 to 11-08-2021 04:38:33.

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 1579167 1579167 0 0.00 0 18677 6 0 0
doc_list_biud 1579166 1579166 0 0.00 0 108930 30 0 0
sp_add_invoice_to_stock 5110 4986 124 2.42 32 186133 17638 0 124
sp_cancel_adding_invoice 5929 5929 0 0.00 6 69149 838 0 0
sp_cancel_client_order 1791 1791 0 0.00 4 16682 311 0 0
sp_cancel_customer_reserve 585932 585932 0 0.00 1 24809 64 0 0
sp_cancel_pay_from_customer 712 712 0 0.00 3 8443 88 0 0
sp_cancel_pay_to_supplier 421 421 0 0.00 3 3545 70 0 0
sp_cancel_supplier_invoice 6557 6541 16 0.24 2 108933 888 0 16
sp_cancel_supplier_order 410 410 0 0.00 7 1061840 115585 0 0
sp_cancel_write_off 10458 10458 0 0.00 1 12008 103 0 0
sp_client_order 21069 21069 0 0.00 4 34572 361 0 0
sp_customer_reserve 470373 470373 0 0.00 0 46960 171 0 0
sp_fill_shopping_cart 32332 32332 0 0.00 1 16587 40 0 0
sp_fill_shopping_cart_clo_res 470365 470365 0 0.00 0 20286 14 0 0
sp_get_clo_for_invoice 4978 4978 0 0.00 0 39372 320 0 0
sp_kill_cost_storno 17520 17520 0 0.00 0 16999 56 0 0
sp_kill_qstorno_ret_qs2qd 1189273 1189273 0 0.00 0 108929 35 0 0
sp_kill_qty_storno 626258 626258 0 0.00 0 108930 75 0 0
sp_lock_selected_doc 21169 21169 0 0.00 0 8075 6 0 0
sp_make_cost_storno 28626 28626 0 0.00 0 14806 55 0 0
sp_multiply_rows_for_pdistr 28626 28626 0 0.00 0 9833 32 0 0
sp_multiply_rows_for_qdistr 21069 21069 0 0.00 0 15784 172 0 0
sp_pay_from_customer 7400 7400 0 0.00 4 19107 127 0 0
sp_pay_to_supplier 6029 6029 0 0.00 5 17694 131 0 0
sp_payment_common 13429 13429 0 0.00 1 12591 22 0 0
sp_qd_handle_on_cancel_clo 1791 1791 0 0.00 0 12118 278 0 0
sp_qd_handle_on_invoice_upd_sts 10907 10907 0 0.00 0 23489 357 0 0
sp_qd_handle_on_reserve_upd_sts 10219 10219 0 0.00 0 22753 68 0 0
sp_reserve_write_off 10219 10219 0 0.00 7 23993 184 0 0
sp_supplier_invoice 5592 5592 0 0.00 7 115220 3255 0 0
sp_supplier_order 5675 5675 0 0.00 5 31886 1305 0 0
srv_aggregate_perf_data 117 117 0 0.00 2940 165248 15166 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1484 114953 44138 0 0
srv_find_qd_qs_mism 1095435 1095435 0 0.00 0 16392 5 0 0
srv_make_invnt_saldo 435 435 0 0.00 45 92828 3889 0 0
srv_make_money_saldo 773 773 0 0.00 1 11375 510 0 0
srv_recalc_idx_stat 4 4 0 0.00 153169 423905 311869 0 0
t$perf-abend:sp_add_invoice_to_stock 124 124 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 16 16 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4986 4986 0 0.00 0 11953 161 0 0
t$perf-norm:sp_cancel_adding_invoice 419 419 0 0.00 0 4010 81 0 0
t$perf-norm:sp_cancel_client_order 1791 1791 0 0.00 0 4564 13 0 0
t$perf-norm:sp_cancel_customer_reserve 771 771 0 0.00 0 1636 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 712 712 0 0.00 0 767 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 421 421 0 0.00 0 678 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 425 425 0 0.00 0 1512 16 0 0
t$perf-norm:sp_cancel_supplier_order 410 410 0 0.00 0 22493 1744 0 0
t$perf-norm:sp_cancel_write_off 1023 1023 0 0.00 0 546 6 0 0
t$perf-norm:sp_client_order 21069 21069 0 0.00 0 8730 18 0 0
t$perf-norm:sp_customer_reserve 1046 1046 0 0.00 0 2691 10 0 0
t$perf-norm:sp_pay_from_customer 7400 7400 0 0.00 0 7446 11 0 0
t$perf-norm:sp_pay_to_supplier 6029 6029 0 0.00 0 5344 7 0 0
t$perf-norm:sp_reserve_write_off 10219 10219 0 0.00 0 4936 9 0 0
t$perf-norm:sp_supplier_invoice 5592 5592 0 0.00 0 5432 17 0 0
t$perf-norm:sp_supplier_order 5675 5675 0 0.00 0 4397 16 0 0
t$perf-norm:srv_aggregate_perf_data 117 117 0 0.00 0 2158 24 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 6722 129 0 0
t$perf-norm:srv_make_invnt_saldo 435 435 0 0.00 0 555 3 0 0
t$perf-norm:srv_make_money_saldo 773 773 0 0.00 0 3792 11 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 45 14 0 0
v_add_invoice_to_stock 10204 9956 248 2.43 0 11152 19 0 248
v_all_customers 38580 38580 0 0.00 0 19 1 0 0
v_all_suppliers 31548 31548 0 0.00 0 19 1 0 0
v_all_wares 42138 42138 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 838 838 0 0.00 0 2073 8 0 0
v_cancel_client_order 3582 3582 0 0.00 0 3487 5 0 0
v_cancel_customer_prepayment 1424 1424 0 0.00 0 313 3 0 0
v_cancel_customer_reserve 1542 1542 0 0.00 0 2336 4 0 0
v_cancel_payment_to_supplier 842 842 0 0.00 0 3490 7 0 0
v_cancel_supplier_invoice 882 850 32 3.62 0 244 10 0 32
v_cancel_supplier_order 820 820 0 0.00 0 2378 5 0 0
v_cancel_write_off 2046 2046 0 0.00 0 4875 14 0 0
v_max_id_clo_ord 5674 5674 0 0.00 0 350 1 0 0
v_max_id_clo_res 1046 1046 0 0.00 0 1146 13 0 0
v_max_id_ord_sup 5589 5589 0 0.00 0 6062 5 0 0
v_max_non_paid_invoice 1518 1518 0 0.00 0 469 2 0 0
v_max_non_paid_realizn 3897 3897 0 0.00 0 4022 5 0 0
v_min_id_clo_ord 5674 5674 0 0.00 0 5058 8 0 0
v_min_id_clo_res 1046 1046 0 0.00 0 1336 5 0 0
v_min_id_ord_sup 5589 5589 0 0.00 0 7729 12 0 0
v_min_non_paid_invoice 6029 6029 0 0.00 0 12587 11 0 0
v_min_non_paid_realizn 7400 7400 0 0.00 0 8830 16 0 0
v_our_firm 10564 10564 0 0.00 0 32 1 0 0
v_reserve_write_off 20438 20438 0 0.00 0 5075 5 0 0
x_lock_depdocs_on_canc_invoice 12045 12045 0 0.00 0 11857 101 0 0
x_lock_depdocs_on_canc_sup_ord 410 410 0 0.00 0 2255 249 0 0
x_make_qstorno_1000_1200 5675 5675 0 0.00 1 31881 1228 0 0
x_make_qstorno_1200_2000 5592 5592 0 0.00 2 109511 3108 0 0
x_make_qstorno_2100_3300 470373 470373 0 0.00 0 40407 154 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 11-08-2021 04:38:34 to 11-08-2021 04:38:38.

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
02:07:09 1 8192000000 8796668944 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:11 16182 8192000000 9709029664 10005499904 789992560 8.795 100 82 164 0 817169296 7248640 19928096
02:09:38 17087 8192000000 9753963744 10059714560 833323696 9.233 100 85 170 0 864425344 11104832 19996816
02:11:02 15479 8192000000 9807787552 10124222464 876963968 9.669 100 89 178 0 914443680 17066848 20412864
02:12:23 14796 8192000000 9866606032 10198044672 913197904 10.029 100 77 155 0 970827408 40952016 16677488
02:13:47 8768 8192000000 9923931216 10266947584 956110816 10.451 100 76 152 1 1029272608 57562624 15599168
02:14:59 6503 8192000000 9962475232 10315571200 999143856 10.870 100 84 165 1 1074116144 57688272 17284016
02:16:08 2358 8192000000 10004395632 10365726720 1021257200 11.084 100 81 160 0 1114531904 77845040 15429664
02:17:12 4641 8192000000 10010219264 10377560064 1044775248 11.311 100 88 175 2 1124401712 62138048 17488416
02:18:18 1069 8192000000 10064740544 10436751360 1069013760 11.543 100 96 195 0 1174130000 85484208 19632032
02:19:20 13156 8192000000 9980921408 10372861952 1071586656 11.567 100 41 83 3 1109449824 27378240 10484928
02:20:34 10309 8192000000 10001777904 10409480192 1080542768 11.653 100 26 52 0 1110519600 24978544 4998288
02:21:45 11084 8192000000 9988892992 10397462528 1083215712 11.678 100 18 36 0 1107003728 20079344 3708672
02:22:57 29511 8192000000 10020961040 10411339776 1085324160 11.698 100 61 120 2 1119567840 20969808 13273872
02:24:27 5911 8192000000 10036588432 10427699200 1091601088 11.758 100 39 78 0 1135846592 36451088 7794416
02:25:34 4611 8192000000 10029520816 10424205312 1100413920 11.842 100 44 90 1 1136153520 26039632 9699968
02:26:42 65721 8192000000 10010860416 10432647168 1094017744 11.781 99 38 76 0 1125980432 23455184 8507504
02:28:49 2296 8192000000 9971451056 10357305344 1051601568 11.376 99 52 105 2 1086301248 21233296 13466384
02:29:52 5867 8192000000 9936765984 10320171008 1001658992 10.895 97 39 80 0 1029854992 18818176 9377824
02:30:59 10180 8192000000 9925238208 10312708096 1002905120 10.907 98 33 65 1 1041153184 30472560 7775504
02:32:09 27053 8192000000 9943801632 10342465536 1022708976 11.098 98 12 24 0 1035466736 10093424 2664336
02:33:39 3162 8192000000 9957328944 10347909120 1048445472 11.346 100 58 115 2 1070766608 9935328 12385808
02:34:43 7803 8192000000 9982161440 10378764288 1064385184 11.498 100 41 82 0 1087341248 12973264 9982800
02:35:54 63618 8192000000 9986643264 10399322112 1073171440 11.582 100 26 52 3 1114003248 35064288 5767520
02:38:02 24105 8192000000 10047546896 10447347712 1097698976 11.816 100 49 98 0 1150039120 43007440 9332704
02:39:28 17955 8192000000 10080275888 10480734208 1115005824 11.980 100 54 108 0 1182440144 56947664 10486656
02:40:52 23607 8192000000 10138140464 10558222336 1134669952 12.165 100 47 92 0 1247838784 103502240 9666592
02:42:41 5272 8192000000 10164021168 10576977920 1154927088 12.356 100 58 115 0 1283224016 115195472 13101456
02:43:52 9297 8192000000 10190777824 10609782784 1159758400 12.401 100 42 84 0 1302095136 132227920 10108816
02:45:23 13107 8192000000 10248562608 10670387200 1170865200 12.505 100 55 110 0 1354286304 171251904 12169200
02:46:41 9744 8192000000 10264942928 10709667840 1174106736 12.535 100 61 122 0 1367660032 179808592 13744704
02:47:56 6443 8192000000 10258288272 10674696192 1167891552 12.477 100 54 107 1 1359924256 179428112 12604592
02:49:06 1482 8192000000 10301712320 10711068672 1171319232 12.509 100 87 174 1 1403459376 213243856 18896288
02:50:13 3119 8192000000 10315374208 10727174144 1153938096 12.346 99 84 167 0 1417120064 244317552 18864416
02:51:21 11409 8192000000 10297170912 10709524480 1145955072 12.272 99 62 124 1 1397357696 236871840 14530784
02:52:37 5121 8192000000 10361488528 10767720448 1153257168 12.340 100 72 145 0 1457862208 287845520 16759520
02:53:46 2629 8192000000 10322130176 10721759232 1145149744 12.264 99 76 151 0 1402457664 240250336 17057584
02:54:57 6975 8192000000 10324439024 10748203008 1155899904 12.365 100 41 82 2 1422166576 255537040 10729632
02:56:11 6793 8192000000 10321668016 10741649408 1157745744 12.382 100 44 88 1 1423675744 254673744 11256256
02:57:38 8813 8192000000 10418678592 10839961600 1170171520 12.498 100 54 104 1 1513270672 330403920 12695232
02:58:50 7178 8192000000 10334167664 10762706944 1146495472 12.277 100 43 84 0 1434005776 276964256 10546048
03:00:01 19571 8192000000 10354739808 10796265472 1141968384 12.234 100 30 59 0 1468474048 318578352 7927312
03:01:24 11414 8192000000 10377757808 10807873536 1131997648 12.140 99 34 68 1 1457869200 317213984 8657568
03:02:39 19124 8192000000 10311026400 10762272768 1126392176 12.087 100 25 50 3 1426590096 293493280 6704640
03:04:08 40776 8192000000 10217599008 10671616000 1124560144 12.070 100 22 44 0 1366872368 236528624 5783600
03:05:57 44883 8192000000 10060403808 10484588544 1113127312 11.962 99 42 81 1 1152303152 30617376 8558464
03:08:06 53862 8192000000 10110703664 10557247488 1129259744 12.114 99 22 45 2 1160077600 26600496 4217360
03:10:00 111142 8192000000 10051319200 10559361024 1045675776 11.319 92 10 19 2 1098675936 50999696 2000464
03:12:55 52011 8192000000 10089521008 10508427264 1134375904 12.163 100 29 55 1 1179435760 38986464 6073392
03:14:48 58462 8192000000 10093465568 10545426432 1120785280 12.034 99 10 20 2 1195425632 72246256 2394096
03:17:05 93425 8192000000 10100079872 10547990528 1129005840 12.112 99 36 72 1 1165111632 28442960 7662832
03:19:40 42416 8192000000 10082994272 10519298048 1131188576 12.133 99 28 54 3 1163967840 27106992 5672272
03:21:25 38111 8192000000 10148301360 10602479616 1153164752 12.339 100 21 44 0 1226597872 68094288 5338832
03:23:06 18465 8192000000 10176517936 10604879872 1144263184 12.256 99 51 100 2 1265375440 109538608 11573648
03:24:32 68337 8192000000 10184985152 10703859712 1160801808 12.411 100 15 30 1 1277605840 113087712 3716320
03:26:42 37241 8192000000 10131882944 10614136832 1158927472 12.393 100 11 22 1 1232205616 70707664 2570480
03:28:27 56765 8192000000 10085219248 10577362944 1141538608 12.230 99 22 44 0 1177005248 30206016 5260624
03:31:50 70123 8192000000 10115511984 10575351808 1131937744 12.140 99 17 35 0 1207531104 71755328 3838032
03:34:00 50347 8192000000 10123050560 10648764416 1132866800 12.148 99 20 40 0 1248203648 110416576 4920272
03:35:51 84004 8192000000 10084974864 10549051392 1074919104 11.599 94 16 32 0 1137739360 59576480 3243776
03:38:17 49392 8192000000 10127458464 10564861952 1121305008 12.039 98 34 69 3 1170655664 42498272 6852384
03:40:07 114211 8192000000 10071631376 10535297024 1084855312 11.694 95 9 18 0 1131117568 44820000 1442256
03:43:12 107506 8192000000 10077058768 10570698752 1096822624 11.807 97 30 60 1 1167129280 63296848 7009808
03:46:40 81417 8192000000 10043953104 10479882240 1095614288 11.796 97 32 63 1 1120290704 17973056 6703360
03:49:02 43655 8192000000 10026434240 10482470912 1101628576 11.853 99 10 20 0 1140259424 36952624 1678224
03:50:46 69100 8192000000 10026561872 10464534528 1078310960 11.631 97 25 50 1 1106914384 24002096 4601328
03:53:22 56445 8192000000 10091204848 10553397248 1086772448 11.712 97 20 40 3 1172885232 81452624 4660160
03:55:18 104275 8192000000 10099369040 10632347648 1124359488 12.068 100 20 40 2 1219724128 90717232 4647408
03:58:36 28593 8192000000 10033328272 10464030720 1075430992 11.604 96 36 70 3 1103921888 20450512 8040384
04:00:07 29422 8192000000 10108547488 10563395584 1116940704 11.998 100 47 94 0 1206504512 80367568 9196240
04:01:38 58302 8192000000 10095424560 10572222464 1086360784 11.708 96 31 62 0 1173407344 80670912 6375648
04:03:40 46179 8192000000 10099379216 10548367360 1122729664 12.053 100 37 74 1 1189741632 59694992 7316976
04:05:28 23257 8192000000 10104918560 10567376896 1122046064 12.046 100 41 82 0 1218702384 88369984 8286336
04:06:56 36644 8192000000 10100831296 10538635264 1125033856 12.075 99 18 36 0 1179459792 50365616 4060320
04:08:34 114953 8192000000 10059869328 10540302336 1136398512 12.182 100 13 26 0 1228319824 89429488 2491824
04:11:39 50554 8192000000 10073216704 10559066112 1146280864 12.275 100 6 12 0 1179605984 32311792 1013328
04:13:29 64366 8192000000 10117923824 10566180864 1138548208 12.202 99 22 45 1 1181275200 38435648 4291344
04:15:36 48475 8192000000 10125773456 10571272192 1124017872 12.065 97 26 52 0 1204402096 74812160 5572064
04:17:25 49504 8192000000 10146718032 10607710208 1102222864 11.859 95 23 46 0 1209867904 102277008 5368032
04:19:21 79298 8192000000 10093297344 10551631872 1120992752 12.036 98 27 54 1 1164004560 37752208 5259600
04:22:00 53763 8192000000 10108279936 10647330816 1145196432 12.264 99 24 48 1 1236020416 85750864 5073120
04:24:00 40732 8192000000 10135814272 10588901376 1132471488 12.145 98 24 48 2 1193144352 55780784 4892080
04:25:45 89279 8192000000 10087825360 10555432960 1130244176 12.124 99 12 24 1 1185589248 52060720 3284352
04:28:49 72884 8192000000 10056919264 10513944576 1081523456 11.662 95 19 38 0 1117179824 32203424 3452944
04:31:03 30148 8192000000 10055215248 10513051648 1109921360 11.932 98 16 34 0 1142716096 29909072 2885664
04:32:34 62648 8192000000 10097251168 10569359360 1108954560 11.923 98 15 30 0 1160651200 48524528 3172112
04:34:38 68928 8192000000 10039885552 10538835968 1088300960 11.727 97 17 34 0 1174333008 82115504 3916544
04:36:53 17220 8192000000 9053681040 9289998336 197558064 2.354 18 2 4 0 208167584 10367392 242128

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-08-2021 04:38:40 to 11-08-2021 04:38:55.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 124 v_add_invoice_to_stock 335544517
except 16 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 11-08-2021 04:38:56 to 11-08-2021 04:38:58.

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          241091
MON$OLDEST_ACTIVE               241092
MON$OLDEST_SNAPSHOT             241092
MON$NEXT_TRANSACTION            241092
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                       2084640
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             811
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2567 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2567 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2567 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2567 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P16: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 ---
:61: 
:62: mscs-test-161.budzdorov.ru    Wed Aug 11 04:37:13 2021
:63:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:64: 
--- end of diff output ---

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

11.08.21 04:38:59. 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-08-11 04:39:02 No severe PSQL-related problems occured
11.08.21 04:39:03. End of report.