Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 26.09.2021 10:37
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 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 15790044 kB
MemAvailable 30755788 kB
Buffers 0 kB
Cached 15880376 kB
SwapCached 3220 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-09-26 10:37:08
DTS_END                         2021-09-26 10:37:08.6240
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/43316
TRN_ID                          245343
ATT_ID                          644
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: 9399.85 19658 1127982
customer order: creation 182.58 382 21910
customer order: refuse 15.17 304 1820
order to supplier: creation 48.81 1280 5857
order to supplier: removal 3.58 102410 430
invoice (draft): creation 47.79 3178 5735
invoice (draft): removal 51.03 845 6123
invoice accept: apply 44.78 17139 5373
invoice accept: cancel 46.23 846 5547
customer reserve: creation 4088.23 172 490587
customer reserve: removal 4566.43 64 547971
realization accept: apply 88.37 160 10604
realization accept: cancel 80.18 115 9621
payment from customer: creation 62.65 102 7518
payment from customer: removal 6.18 101 741
payment to supplier: creation 52.83 118 6340
payment to supplier: removal 3.69 61 443
service: total inventory turnovers 3.96 3629 475
service: total monetary turnovers 6.52 503 782
service: aggregate perf. data 0.84 19011 101
service: refresh index statistics 0.03 242734 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 26-09-2021 10:38:09 to 26-09-2021 10:38:11.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9207
TEST_TIME 2 7656
TEST_TIME 3 7058
TEST_TIME 4 6794
TEST_TIME 5 6425
TEST_TIME 6 6397
TEST_TIME 7 6553
TEST_TIME 8 6844
TEST_TIME 9 7172
TEST_TIME 10 7176
TEST_TIME 11 7203
TEST_TIME 12 7087
TEST_TIME 13 7108
TEST_TIME 14 7333
TEST_TIME 15 7409
TEST_TIME 16 7443
TEST_TIME 17 7802
TEST_TIME 18 8359
TEST_TIME 19 8475
TEST_TIME 20 9380
TEST_TIME 21 9570
TEST_TIME 22 10255
TEST_TIME 23 10591
TEST_TIME 24 11174
TEST_TIME 25 11194
TEST_TIME 26 11420
TEST_TIME 27 11179
TEST_TIME 28 11179
TEST_TIME 29 11112
TEST_TIME 30 11108
TEST_TIME 31 10990
TEST_TIME 32 10951
TEST_TIME 33 11047
TEST_TIME 34 11034
TEST_TIME 35 11015
TEST_TIME 36 11041
TEST_TIME 37 11085
TEST_TIME 38 10868
TEST_TIME 39 10680
TEST_TIME 40 10507
TEST_TIME 41 10634
TEST_TIME 42 10476
TEST_TIME 43 10455
TEST_TIME 44 10410
TEST_TIME 45 10386
TEST_TIME 46 10320
TEST_TIME 47 10395
TEST_TIME 48 10396
TEST_TIME 49 10356
TEST_TIME 50 10204
TEST_TIME 51 10318
TEST_TIME 52 10274
TEST_TIME 53 10266
TEST_TIME 54 10225
TEST_TIME 55 10236
TEST_TIME 56 10312
TEST_TIME 57 10233
TEST_TIME 58 10296
TEST_TIME 59 10414
TEST_TIME 60 10400
TEST_TIME 61 10423
TEST_TIME 62 10386
TEST_TIME 63 10380
TEST_TIME 64 10410
TEST_TIME 65 10280
TEST_TIME 66 10211
TEST_TIME 67 10114
TEST_TIME 68 10076
TEST_TIME 69 10140
TEST_TIME 70 10025
TEST_TIME 71 9944
TEST_TIME 72 9900
TEST_TIME 73 9783
TEST_TIME 74 9863
TEST_TIME 75 9900
TEST_TIME 76 9880
TEST_TIME 77 9911
TEST_TIME 78 9897
TEST_TIME 79 9887
TEST_TIME 80 9843
TEST_TIME 81 9824
TEST_TIME 82 9918
TEST_TIME 83 9912
TEST_TIME 84 9902
TEST_TIME 85 9888
TEST_TIME 86 9868
TEST_TIME 87 9795
TEST_TIME 88 9702
TEST_TIME 89 9670
TEST_TIME 90 9753
TEST_TIME 91 9700
TEST_TIME 92 9618
TEST_TIME 93 9624
TEST_TIME 94 9608
TEST_TIME 95 9581
TEST_TIME 96 9570
TEST_TIME 97 9544
TEST_TIME 98 9603
TEST_TIME 99 9611
TEST_TIME 100 9614
TEST_TIME 101 9611
TEST_TIME 102 9653
TEST_TIME 103 9625
TEST_TIME 104 9699
TEST_TIME 105 9708
TEST_TIME 106 9719
TEST_TIME 107 9726
TEST_TIME 108 9644
TEST_TIME 109 9646
TEST_TIME 110 9593
TEST_TIME 111 9536
TEST_TIME 112 9508
TEST_TIME 113 9458
TEST_TIME 114 9435
TEST_TIME 115 9446
TEST_TIME 116 9463
TEST_TIME 117 9449
TEST_TIME 118 9425
TEST_TIME 119 9480
TEST_TIME 120 9406

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 26-09-2021 10:38:12 to 26-09-2021 10:38:16.

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 1580180 1580180 0 0.00 0 18626 7 0 0
doc_list_biud 1580179 1580179 0 0.00 0 21649 28 0 0
sp_add_invoice_to_stock 5514 5373 141 2.55 30 182580 17140 0 141
sp_cancel_adding_invoice 5547 5547 0 0.00 4 83780 846 0 0
sp_cancel_client_order 1820 1820 0 0.00 5 9413 304 0 0
sp_cancel_customer_reserve 547971 547971 0 0.00 1 21656 65 0 0
sp_cancel_pay_from_customer 741 741 0 0.00 3 10898 101 0 0
sp_cancel_pay_to_supplier 443 443 0 0.00 3 2090 61 0 0
sp_cancel_supplier_invoice 6138 6123 15 0.24 2 14456 845 0 15
sp_cancel_supplier_order 430 430 0 0.00 7 987624 102411 0 0
sp_cancel_write_off 9621 9621 0 0.00 1 9532 115 0 0
sp_client_order 21910 21910 0 0.00 4 60196 382 0 0
sp_customer_reserve 490587 490587 0 0.00 0 125942 172 0 0
sp_fill_shopping_cart 33498 33498 0 0.00 1 11741 39 0 0
sp_fill_shopping_cart_clo_res 490585 490585 0 0.00 0 17260 14 0 0
sp_get_clo_for_invoice 5366 5366 0 0.00 0 24800 295 0 0
sp_kill_cost_storno 16352 16352 0 0.00 0 8824 57 0 0
sp_kill_qstorno_ret_qs2qd 1112116 1112116 0 0.00 0 21639 35 0 0
sp_kill_qty_storno 587482 587482 0 0.00 0 21648 74 0 0
sp_lock_selected_doc 22010 22010 0 0.00 0 8020 5 0 0
sp_make_cost_storno 29828 29828 0 0.00 0 18612 50 0 0
sp_multiply_rows_for_pdistr 29827 29827 0 0.00 0 9860 27 0 0
sp_multiply_rows_for_qdistr 21910 21910 0 0.00 0 30426 191 0 0
sp_pay_from_customer 7518 7518 0 0.00 4 14220 102 0 0
sp_pay_to_supplier 6340 6340 0 0.00 4 18693 119 0 0
sp_payment_common 13857 13857 0 0.00 1 10377 19 0 0
sp_qd_handle_on_cancel_clo 1820 1820 0 0.00 0 9404 269 0 0
sp_qd_handle_on_invoice_upd_sts 10913 10913 0 0.00 0 14493 315 0 0
sp_qd_handle_on_reserve_upd_sts 10604 10604 0 0.00 0 13515 47 0 0
sp_reserve_write_off 10604 10604 0 0.00 6 13646 161 0 0
sp_supplier_invoice 5735 5735 0 0.00 5 70728 3179 0 0
sp_supplier_order 5857 5857 0 0.00 5 31828 1281 0 0
srv_aggregate_perf_data 101 101 0 0.00 3016 172740 19011 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1073 162280 43053 0 0
srv_find_qd_qs_mism 1076313 1076313 0 0.00 0 16278 7 0 0
srv_make_invnt_saldo 475 475 0 0.00 43 173677 3629 0 0
srv_make_money_saldo 782 782 0 0.00 1 14597 503 0 0
srv_recalc_idx_stat 4 4 0 0.00 131724 341512 242735 0 0
t$perf-abend:sp_add_invoice_to_stock 141 141 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 5373 5373 0 0.00 0 15992 151 0 0
t$perf-norm:sp_cancel_adding_invoice 472 472 0 0.00 0 1967 83 0 0
t$perf-norm:sp_cancel_client_order 1820 1820 0 0.00 0 12031 16 0 0
t$perf-norm:sp_cancel_customer_reserve 685 685 0 0.00 0 512 6 0 0
t$perf-norm:sp_cancel_pay_from_customer 741 741 0 0.00 0 1813 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 443 443 0 0.00 0 222 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 408 408 0 0.00 0 1755 14 0 0
t$perf-norm:sp_cancel_supplier_order 430 430 0 0.00 0 16483 1323 0 0
t$perf-norm:sp_cancel_write_off 1042 1042 0 0.00 0 534 4 0 0
t$perf-norm:sp_client_order 21910 21910 0 0.00 0 12204 14 0 0
t$perf-norm:sp_customer_reserve 1108 1108 0 0.00 0 4172 9 0 0
t$perf-norm:sp_pay_from_customer 7518 7518 0 0.00 0 15406 13 0 0
t$perf-norm:sp_pay_to_supplier 6340 6340 0 0.00 0 2386 9 0 0
t$perf-norm:sp_reserve_write_off 10604 10604 0 0.00 0 8169 10 0 0
t$perf-norm:sp_supplier_invoice 5735 5735 0 0.00 0 7919 17 0 0
t$perf-norm:sp_supplier_order 5857 5857 0 0.00 0 2941 12 0 0
t$perf-norm:srv_aggregate_perf_data 101 101 0 0.00 0 3267 47 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 105 3 0 0
t$perf-norm:srv_make_invnt_saldo 475 475 0 0.00 0 2252 13 0 0
t$perf-norm:srv_make_money_saldo 782 782 0 0.00 0 1691 9 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 5 3 0 0
v_add_invoice_to_stock 11012 10730 282 2.56 0 10808 18 0 282
v_all_customers 39808 39808 0 0.00 0 16 1 0 0
v_all_suppliers 32442 32442 0 0.00 0 126 1 0 0
v_all_wares 43818 43818 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 944 944 0 0.00 0 182 6 0 0
v_cancel_client_order 3640 3640 0 0.00 0 5794 9 0 0
v_cancel_customer_prepayment 1482 1482 0 0.00 0 10856 19 0 0
v_cancel_customer_reserve 1370 1370 0 0.00 0 5791 11 0 0
v_cancel_payment_to_supplier 886 886 0 0.00 0 1720 7 0 0
v_cancel_supplier_invoice 846 816 30 3.54 0 9207 24 0 30
v_cancel_supplier_order 860 860 0 0.00 0 2375 7 0 0
v_cancel_write_off 2084 2084 0 0.00 0 3620 16 0 0
v_max_id_clo_ord 5857 5857 0 0.00 0 126 1 0 0
v_max_id_clo_res 1108 1108 0 0.00 0 4385 16 0 0
v_max_id_ord_sup 5732 5732 0 0.00 0 5686 5 0 0
v_max_non_paid_invoice 1707 1707 0 0.00 0 215 2 0 0
v_max_non_paid_realizn 4090 4090 0 0.00 0 3601 4 0 0
v_min_id_clo_ord 5857 5857 0 0.00 0 7789 8 0 0
v_min_id_clo_res 1108 1108 0 0.00 0 3827 12 0 0
v_min_id_ord_sup 5732 5732 0 0.00 0 8975 10 0 0
v_min_non_paid_invoice 6339 6339 0 0.00 0 10374 12 0 0
v_min_non_paid_realizn 7518 7518 0 0.00 0 6843 12 0 0
v_our_firm 10866 10866 0 0.00 0 20 1 0 0
v_reserve_write_off 21208 21208 0 0.00 0 10917 8 0 0
x_lock_depdocs_on_canc_invoice 11262 11262 0 0.00 0 11673 113 0 0
x_lock_depdocs_on_canc_sup_ord 430 430 0 0.00 0 9091 281 0 0
x_make_qstorno_1000_1200 5857 5857 0 0.00 2 31819 1203 0 0
x_make_qstorno_1200_2000 5735 5735 0 0.00 2 70720 3036 0 0
x_make_qstorno_2100_3300 490587 490587 0 0.00 0 125939 156 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 26-09-2021 10:38:17 to 26-09-2021 10:38:21.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
08:07:12 32 8192000000 8809562928 8824741888 7336240 0.089 3 1 2 0 7437472 65456 35776
08:08:13 21839 8192000000 9726987536 10027601920 806512128 8.962 100 83 166 0 834029072 7610480 19906464
08:09:40 29346 8192000000 9785909840 10096738304 855071648 9.451 100 89 179 0 888581728 12256720 21253360
08:11:15 17346 8192000000 9816263200 10138378240 885116720 9.751 100 87 174 0 925520880 20308960 20095200
08:12:39 16108 8192000000 9877936944 10214555648 926738800 10.163 100 82 161 2 987877456 43277520 17861136
08:14:01 10073 8192000000 9941548880 10285142016 972165696 10.608 100 86 172 0 1052004816 61931360 17907760
08:15:12 4895 8192000000 9975786288 10329890816 1003952464 10.917 100 84 167 0 1085713312 64462576 17298272
08:16:19 2099 8192000000 10004626160 10366615552 1023618320 11.107 100 86 171 0 1123737584 82930160 17189104
08:17:23 2365 8192000000 10030064528 10401316864 1054593488 11.405 100 84 167 1 1143005808 70290688 18121632
08:18:26 10065 8192000000 10051328928 10454302720 1067699968 11.530 100 74 148 0 1160364624 77416256 15248400
08:19:46 7895 8192000000 9988348848 10388107264 1068931472 11.542 100 30 60 2 1104126608 29364128 5831008
08:20:56 5442 8192000000 9985537968 10389741568 1076240080 11.612 100 20 40 3 1107809936 26759024 4810832
08:22:04 38544 8192000000 10006023392 10392973312 1081599520 11.663 100 64 127 1 1107821232 11996304 14225408
08:23:42 5327 8192000000 10012735984 10406555648 1086039632 11.705 100 49 96 0 1113056960 16825392 10191936
08:24:49 13775 8192000000 9999673488 10414374912 1078144688 11.630 99 36 74 1 1100690096 14394368 8151040
08:26:03 7582 8192000000 9992229760 10391875584 1071445696 11.566 98 29 58 1 1090576976 12629504 6501776
08:27:11 33723 8192000000 9994699552 10405834752 1046675472 11.329 96 20 40 0 1070330928 19216944 4438512
08:28:46 6614 8192000000 9972572624 10372648960 1035020656 11.217 97 43 86 0 1066094416 21789440 9284320
08:29:56 3852 8192000000 9952579120 10340323328 1020526496 11.077 98 51 101 1 1050287408 18654640 11106272
08:31:00 62037 8192000000 9924898224 10325417984 967841808 10.566 94 33 67 0 990193952 14830048 7522096
08:33:02 8406 8192000000 9957431968 10357149696 1044140496 11.304 100 45 92 1 1076788448 22697696 9950256
08:34:11 6944 8192000000 9972156848 10370330624 1055721888 11.416 100 45 92 1 1082812336 16872544 10217904
08:35:18 9611 8192000000 9993171440 10386915328 1070468080 11.557 100 33 66 3 1102056800 24441024 7147696
08:36:30 22926 8192000000 9992899056 10394128384 1073030064 11.581 100 46 91 1 1097469024 15194624 9244336
08:38:01 27239 8192000000 10052022768 10456465408 1110814096 11.940 100 52 104 2 1150427232 28328912 11284224
08:39:35 20934 8192000000 10099294048 10504867840 1132035824 12.141 100 54 108 0 1206487552 63353680 11098048
08:41:03 31830 8192000000 10143997632 10571124736 1144608944 12.259 100 36 71 1 1254358720 101291904 8457872
08:42:37 16306 8192000000 10164832896 10579230720 1151105888 12.320 100 55 109 2 1267414272 104373568 11934816
08:44:00 7220 8192000000 10170926880 10592985088 1149374576 12.304 99 68 135 1 1267668240 103841872 14451792
08:45:15 2245 8192000000 10231006304 10637520896 1176867632 12.561 100 70 140 2 1325132592 132289680 15975280
08:46:21 4607 8192000000 10242038864 10655752192 1178891040 12.580 100 64 123 2 1346648560 153739040 14018480
08:47:28 3381 8192000000 10270400080 10681962496 1180609584 12.596 100 68 133 1 1378576688 183035552 14931552
08:48:33 3534 8192000000 10287320672 10720452608 1176407440 12.557 100 60 118 2 1413837808 223575264 13855104
08:49:43 11488 8192000000 10318953312 10741633024 1173724976 12.532 99 58 116 0 1421727808 234361104 13641728
08:50:59 1071 8192000000 10366524032 10772090880 1168710000 12.485 100 88 172 1 1465307216 276276608 20320608
08:52:03 3698 8192000000 10407264144 10811318272 1182323968 12.612 100 86 170 1 1501358352 299646448 19387936
08:53:12 1640 8192000000 10440846928 10848415744 1160775152 12.411 99 83 163 1 1535929456 355939744 19214560
08:54:20 2578 8192000000 10478749104 10891493376 1168415312 12.482 100 88 175 1 1572358144 383212000 20730832
08:55:28 1801 8192000000 10429565456 10852503552 1160659856 12.409 99 83 166 0 1523829552 342873632 20296064
08:56:38 6102 8192000000 10414895760 10837311488 1139380336 12.210 98 55 110 0 1502948480 348855952 14712192
08:57:48 4590 8192000000 10356097056 10794434560 1148616032 12.297 100 45 89 1 1465738848 305539840 11582976
08:59:01 9526 8192000000 10366121552 10794864640 1149150432 12.302 100 48 96 1 1464171536 302492704 12528400
09:00:17 31540 8192000000 10252199488 10731114496 1137463424 12.192 100 25 50 2 1371832576 228414928 5954224
09:02:02 50130 8192000000 10146281344 10584338432 1116145552 11.991 99 18 36 0 1226177296 105976512 4055232
09:03:53 58831 8192000000 10068197760 10504470528 1100588384 11.843 98 33 66 0 1142534608 35379344 6566880
09:05:53 87856 8192000000 10081020432 10518200320 1097596384 11.815 97 13 26 2 1147596432 47887520 2112528
09:08:21 162280 8192000000 10082309264 10561609728 1078815552 11.636 94 7 14 0 1141997264 62397584 784128
09:12:04 82454 8192000000 10080335552 10565558272 1114333408 11.973 97 12 24 0 1181473808 64624048 2516352
09:14:34 59630 8192000000 10079212160 10503868416 1128186688 12.104 98 21 42 1 1151501328 18845056 4469584
09:16:37 103289 8192000000 10120749328 10604462080 1119234880 12.020 97 31 60 1 1216170912 90365344 6570688
09:19:28 96298 8192000000 10109454720 10559221760 1102092688 11.857 95 31 62 1 1178696336 69399744 7203904
09:22:06 26221 8192000000 10134588016 10611253248 1152331120 12.331 100 40 80 0 1251910976 90122208 9457648
09:23:44 47179 8192000000 10159847520 10647482368 1151166768 12.320 100 27 54 0 1273523760 116076752 6280240
09:25:32 55040 8192000000 10120948864 10580713472 1143132944 12.245 99 14 28 0 1199421280 53148576 3139760
09:27:31 19739 8192000000 10127869584 10586537984 1148229120 12.293 100 14 28 1 1228318464 76970928 3118416
09:28:57 27875 8192000000 10050154208 10513182720 1115151280 11.981 98 12 24 1 1147642368 29931104 2559984
09:30:25 53518 8192000000 10076475632 10529165312 1109144352 11.924 97 21 42 0 1137158624 23746368 4267904
09:32:20 46361 8192000000 10115708528 10576625664 1135614688 12.174 99 24 48 1 1202437776 61845184 4977904
09:34:19 43730 8192000000 10142539248 10605469696 1097956784 11.818 96 24 48 0 1177622352 74563504 5102064
09:36:06 90919 8192000000 10068649744 10511708160 1076771040 11.617 95 8 16 0 1136391632 58101616 1518976
09:38:42 43324 8192000000 10097908048 10543730688 1112913664 11.960 98 24 45 0 1163897984 46848656 4135664
09:40:28 50019 8192000000 10110995552 10551971840 1098950960 11.828 97 30 59 2 1182303472 76492976 6859536
09:42:21 75846 8192000000 10064252848 10496962560 1125064992 12.075 99 32 64 0 1169289520 36487408 7737120
09:44:37 27592 8192000000 10083166528 10530439168 1127005312 12.093 99 34 66 1 1189862080 55542032 7314736
09:46:07 66870 8192000000 10067010224 10581073920 1103902608 11.875 98 20 40 0 1175870304 67430720 4536976
09:48:14 55795 8192000000 10110561888 10570694656 1079702864 11.645 96 14 27 0 1177548208 94790272 3055072
09:50:11 57580 8192000000 10111442736 10561560576 1094802128 11.788 99 17 34 0 1198047712 99767616 3477968
09:52:13 45071 8192000000 10064629936 10503131136 1102686896 11.863 99 24 48 1 1154685536 47247056 4751584
09:53:58 65254 8192000000 10037705712 10466721792 1071224304 11.564 96 24 48 0 1111935072 35715488 4995280
09:56:12 38281 8192000000 10060614512 10499072000 1111359648 11.945 99 25 50 1 1160050176 43934928 4755600
09:57:55 28009 8192000000 10085983312 10516078592 1114471744 11.975 100 44 88 0 1183200064 60204016 8524304
09:59:24 40234 8192000000 10079787328 10568097792 1103757776 11.873 98 22 44 1 1208832416 100502304 4572336
10:01:10 30847 8192000000 10061070640 10517815296 1103388944 11.870 98 20 40 0 1138818752 31459568 3970240
10:02:46 80725 8192000000 10078211920 10556977152 1096880976 11.808 97 18 36 1 1144604256 43518320 4204960
10:06:05 20975 8192000000 10115010560 10565591040 1126986368 12.093 99 39 79 1 1195946672 60523792 8436512
10:07:27 54574 8192000000 10076059152 10584993792 1136913040 12.186 100 6 12 0 1200718016 62496880 1308096
10:09:23 33230 8192000000 10079346320 10560913408 1143578896 12.249 100 9 18 0 1186387136 41537424 1270816
10:10:57 39315 8192000000 10061509520 10515296256 1120766144 12.034 98 9 18 0 1159330704 37084224 1480336
10:12:50 42881 8192000000 10097149456 10538991616 1104645808 11.882 97 26 52 0 1144723552 34896608 5181136
10:14:42 60716 8192000000 10115643408 10593701888 1140463712 12.220 99 18 36 1 1210889888 66304704 4121472
10:16:43 26729 8192000000 10095378992 10569682944 1143407344 12.248 100 20 40 0 1205319296 57869152 4042800
10:18:11 51251 8192000000 10107740400 10552438784 1132556608 12.145 99 24 48 1 1175714128 38203408 4954112
10:20:02 37687 8192000000 10106411040 10549542912 1126294768 12.086 99 34 68 1 1166390672 33055696 7040208
10:21:47 79761 8192000000 10124493648 10563616768 1108520400 11.918 96 13 26 0 1184905360 73626768 2758192
10:24:07 135248 8192000000 10069875648 10545000448 1118897136 12.017 98 21 43 0 1161451680 38298448 4256096
10:27:30 54352 8192000000 10040698016 10493435904 1103493808 11.871 98 7 14 0 1121910720 17459792 957120
10:29:46 63793 8192000000 10081866592 10549673984 1104659920 11.882 98 10 20 0 1138110272 31496560 1953792
10:31:50 68049 8192000000 10096203696 10563088384 1116876736 11.997 99 21 42 0 1200109328 78643328 4589264
10:34:00 33206 8192000000 10044830336 10486661120 1092924096 11.770 97 15 30 0 1127957376 31727840 3305440
10:35:38 31580 8192000000 10092421568 10529472512 1095811232 11.798 98 36 72 0 1175181104 72172704 7197168

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 26-09-2021 10:38:23 to 26-09-2021 10:38:38.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 141 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 26-09-2021 10:38:39 to 26-09-2021 10:38:41.

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          244893
MON$OLDEST_ACTIVE               245752
MON$OLDEST_SNAPSHOT             245752
MON$NEXT_TRANSACTION            245752
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                       2060512
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             822
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2618 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2618 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2618 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2618 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 ---
:107: 
:108: mscs-test-161.budzdorov.ru    Sun Sep 26 10:37:16 2021
:109:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:110: 
--- end of diff output ---

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

26.09.21 10:38:42. 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-09-26 10:38:45 No severe PSQL-related problems occured
26.09.21 10:38:46. End of report.