Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 13.08.2022 19:39
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 15901428 kB
MemAvailable 30895920 kB
Buffers 0 kB
Cached 15794856 kB
SwapCached 2548 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 3.0.11
DB_NAME                         /home/bases/oltp30-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/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/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=3333
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/oltp30-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-oltp30
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-08-13 19:38:26
DTS_END                         2022-08-13 19:38:26.8330
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/51408
TRN_ID                          240550
ATT_ID                          636
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: 9568.58 20610 1148230
customer order: creation 172.93 399 20751
customer order: refuse 14.54 346 1745
order to supplier: creation 45.79 1287 5495
order to supplier: removal 3.54 95045 425
invoice (draft): creation 46.18 3202 5541
invoice (draft): removal 54.28 751 6513
invoice accept: apply 42.03 17150 5044
invoice accept: cancel 49.43 705 5932
customer reserve: creation 3925.91 168 471109
customer reserve: removal 4914.58 54 589749
realization accept: apply 83.89 158 10067
realization accept: cancel 84.57 122 10148
payment from customer: creation 60.68 91 7282
payment from customer: removal 5.94 66 713
payment to supplier: creation 49.48 102 5937
payment to supplier: removal 3.46 56 415
service: total inventory turnovers 4.38 1042 526
service: total monetary turnovers 6.14 22 737
service: aggregate perf. data 0.81 21776 97
service: refresh index statistics 0.03 269662 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 13-08-2022 19:39:34 to 13-08-2022 19:39:36.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4654
TEST_TIME 2 5787
TEST_TIME 3 5718
TEST_TIME 4 5890
TEST_TIME 5 6103
TEST_TIME 6 6719
TEST_TIME 7 6985
TEST_TIME 8 6402
TEST_TIME 9 6254
TEST_TIME 10 6180
TEST_TIME 11 6358
TEST_TIME 12 6348
TEST_TIME 13 6357
TEST_TIME 14 6414
TEST_TIME 15 6640
TEST_TIME 16 7040
TEST_TIME 17 7209
TEST_TIME 18 7431
TEST_TIME 19 8068
TEST_TIME 20 8305
TEST_TIME 21 9011
TEST_TIME 22 9086
TEST_TIME 23 8938
TEST_TIME 24 9033
TEST_TIME 25 9103
TEST_TIME 26 9074
TEST_TIME 27 10102
TEST_TIME 28 10532
TEST_TIME 29 10352
TEST_TIME 30 10484
TEST_TIME 31 10327
TEST_TIME 32 10677
TEST_TIME 33 10483
TEST_TIME 34 10217
TEST_TIME 35 10152
TEST_TIME 36 10079
TEST_TIME 37 9957
TEST_TIME 38 9929
TEST_TIME 39 9910
TEST_TIME 40 9780
TEST_TIME 41 9772
TEST_TIME 42 9938
TEST_TIME 43 9815
TEST_TIME 44 9767
TEST_TIME 45 9692
TEST_TIME 46 9793
TEST_TIME 47 9847
TEST_TIME 48 9827
TEST_TIME 49 9913
TEST_TIME 50 9880
TEST_TIME 51 9964
TEST_TIME 52 10139
TEST_TIME 53 10132
TEST_TIME 54 10143
TEST_TIME 55 10090
TEST_TIME 56 10051
TEST_TIME 57 10111
TEST_TIME 58 10132
TEST_TIME 59 10127
TEST_TIME 60 10037
TEST_TIME 61 10001
TEST_TIME 62 9939
TEST_TIME 63 9874
TEST_TIME 64 9915
TEST_TIME 65 9834
TEST_TIME 66 9770
TEST_TIME 67 9721
TEST_TIME 68 9691
TEST_TIME 69 9712
TEST_TIME 70 9750
TEST_TIME 71 9751
TEST_TIME 72 9711
TEST_TIME 73 9707
TEST_TIME 74 9786
TEST_TIME 75 9821
TEST_TIME 76 9759
TEST_TIME 77 9730
TEST_TIME 78 9799
TEST_TIME 79 9836
TEST_TIME 80 9839
TEST_TIME 81 9784
TEST_TIME 82 9705
TEST_TIME 83 9697
TEST_TIME 84 9694
TEST_TIME 85 9656
TEST_TIME 86 9594
TEST_TIME 87 9627
TEST_TIME 88 9622
TEST_TIME 89 9618
TEST_TIME 90 9626
TEST_TIME 91 9626
TEST_TIME 92 9616
TEST_TIME 93 9616
TEST_TIME 94 9643
TEST_TIME 95 9617
TEST_TIME 96 9677
TEST_TIME 97 9706
TEST_TIME 98 9714
TEST_TIME 99 9700
TEST_TIME 100 9668
TEST_TIME 101 9673
TEST_TIME 102 9714
TEST_TIME 103 9807
TEST_TIME 104 9821
TEST_TIME 105 9834
TEST_TIME 106 9753
TEST_TIME 107 9768
TEST_TIME 108 9714
TEST_TIME 109 9753
TEST_TIME 110 9714
TEST_TIME 111 9660
TEST_TIME 112 9643
TEST_TIME 113 9602
TEST_TIME 114 9616
TEST_TIME 115 9591
TEST_TIME 116 9618
TEST_TIME 117 9609
TEST_TIME 118 9632
TEST_TIME 119 9623
TEST_TIME 120 9578

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 13-08-2022 19:39:38 to 13-08-2022 19:39:41.

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 1583882 1583882 0 0.00 0 19870 6 0 0
doc_list_biud 1583874 1583874 0 0.00 0 31297 26 0 0
sp_add_invoice_to_stock 5194 5044 150 2.88 54 178772 17151 0 150
sp_cancel_adding_invoice 5932 5932 0 0.00 3 78944 706 0 0
sp_cancel_client_order 1745 1745 0 0.00 4 20004 347 0 0
sp_cancel_customer_reserve 589749 589749 0 0.00 0 29870 54 0 0
sp_cancel_pay_from_customer 713 713 0 0.00 3 8991 67 0 0
sp_cancel_pay_to_supplier 415 415 0 0.00 2 845 56 0 0
sp_cancel_supplier_invoice 6526 6513 13 0.19 2 29064 751 0 13
sp_cancel_supplier_order 425 425 0 0.00 7 923150 95045 0 0
sp_cancel_write_off 10148 10148 0 0.00 1 11546 122 0 0
sp_client_order 20751 20751 0 0.00 3 27878 400 0 0
sp_customer_reserve 471109 471109 0 0.00 0 33243 169 0 0
sp_fill_shopping_cart 31783 31783 0 0.00 1 14319 36 0 0
sp_fill_shopping_cart_clo_res 471093 471093 0 0.00 0 12784 12 0 0
sp_get_clo_for_invoice 5022 5022 0 0.00 0 11750 262 0 0
sp_kill_cost_storno 17208 17208 0 0.00 0 8931 54 0 0
sp_kill_qstorno_ret_qs2qd 1196584 1196584 0 0.00 0 29862 30 0 0
sp_kill_qty_storno 629601 629601 0 0.00 0 31297 64 0 0
sp_lock_selected_doc 20834 20834 0 0.00 0 9882 5 0 0
sp_make_cost_storno 28308 28308 0 0.00 0 14690 41 0 0
sp_multiply_rows_for_pdistr 28308 28308 0 0.00 0 7962 24 0 0
sp_multiply_rows_for_qdistr 20751 20751 0 0.00 0 16273 168 0 0
sp_pay_from_customer 7282 7282 0 0.00 3 14826 92 0 0
sp_pay_to_supplier 5937 5937 0 0.00 5 7775 103 0 0
sp_payment_common 13219 13219 0 0.00 1 7060 19 0 0
sp_qd_handle_on_cancel_clo 1745 1745 0 0.00 0 11688 310 0 0
sp_qd_handle_on_invoice_upd_sts 10954 10954 0 0.00 0 31295 307 0 0
sp_qd_handle_on_reserve_upd_sts 10067 10067 0 0.00 0 14652 61 0 0
sp_reserve_write_off 10067 10067 0 0.00 6 14667 158 0 0
sp_supplier_invoice 5541 5541 0 0.00 5 85965 3202 0 0
sp_supplier_order 5495 5495 0 0.00 4 36763 1287 0 0
srv_aggregate_perf_data 97 97 0 0.00 2432 279638 21776 0 0
srv_fill_mon_cache_memory 68 68 0 0.00 799 134377 41458 0 0
srv_find_qd_qs_mism 1099574 1099574 0 0.00 0 19869 5 0 0
srv_make_invnt_saldo 526 526 0 0.00 28 28391 1042 0 0
srv_make_money_saldo 737 737 0 0.00 0 519 22 0 0
srv_recalc_idx_stat 4 4 0 0.00 212645 308160 269662 0 0
t$perf-abend:sp_add_invoice_to_stock 150 150 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5044 5044 0 0.00 0 15122 168 0 0
t$perf-norm:sp_cancel_adding_invoice 415 415 0 0.00 0 5313 97 0 0
t$perf-norm:sp_cancel_client_order 1745 1745 0 0.00 0 4744 15 0 0
t$perf-norm:sp_cancel_customer_reserve 691 691 0 0.00 0 5943 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 713 713 0 0.00 0 1188 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 415 415 0 0.00 0 133 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 425 425 0 0.00 0 483 8 0 0
t$perf-norm:sp_cancel_supplier_order 425 425 0 0.00 0 23185 1596 0 0
t$perf-norm:sp_cancel_write_off 917 917 0 0.00 0 5599 20 0 0
t$perf-norm:sp_client_order 20751 20751 0 0.00 0 12761 17 0 0
t$perf-norm:sp_customer_reserve 1047 1047 0 0.00 0 1455 5 0 0
t$perf-norm:sp_pay_from_customer 7282 7282 0 0.00 0 7235 11 0 0
t$perf-norm:sp_pay_to_supplier 5937 5937 0 0.00 0 7785 13 0 0
t$perf-norm:sp_reserve_write_off 10067 10067 0 0.00 0 6474 10 0 0
t$perf-norm:sp_supplier_invoice 5541 5541 0 0.00 0 10209 21 0 0
t$perf-norm:sp_supplier_order 5495 5495 0 0.00 0 6146 13 0 0
t$perf-norm:srv_aggregate_perf_data 97 97 0 0.00 0 725 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 68 68 0 0.00 0 656 14 0 0
t$perf-norm:srv_make_invnt_saldo 526 526 0 0.00 0 2027 21 0 0
t$perf-norm:srv_make_money_saldo 737 737 0 0.00 0 1902 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 6 3 0 0
v_add_invoice_to_stock 10344 10044 300 2.90 0 6894 17 0 300
v_all_customers 38308 38308 0 0.00 0 17 0 0 0
v_all_suppliers 31036 31036 0 0.00 0 24 1 0 0
v_all_wares 41502 41502 0 0.00 0 109 1 0 0
v_cancel_adding_invoice 830 830 0 0.00 0 3224 12 0 0
v_cancel_client_order 3490 3490 0 0.00 0 925 3 0 0
v_cancel_customer_prepayment 1426 1426 0 0.00 0 2322 5 0 0
v_cancel_customer_reserve 1382 1382 0 0.00 0 2440 6 0 0
v_cancel_payment_to_supplier 830 830 0 0.00 0 556 5 0 0
v_cancel_supplier_invoice 876 850 26 2.96 0 2107 17 0 26
v_cancel_supplier_order 850 850 0 0.00 0 1902 5 0 0
v_cancel_write_off 1834 1834 0 0.00 0 4186 20 0 0
v_max_id_clo_ord 5494 5494 0 0.00 0 375 1 0 0
v_max_id_clo_res 1047 1047 0 0.00 0 9215 25 0 0
v_max_id_ord_sup 5538 5538 0 0.00 0 6855 5 0 0
v_max_non_paid_invoice 1451 1451 0 0.00 0 2079 4 0 0
v_max_non_paid_realizn 3700 3700 0 0.00 0 1355 4 0 0
v_min_id_clo_ord 5494 5494 0 0.00 0 2733 6 0 0
v_min_id_clo_res 1047 1047 0 0.00 0 4343 10 0 0
v_min_id_ord_sup 5538 5538 0 0.00 0 6787 8 0 0
v_min_non_paid_invoice 5937 5937 0 0.00 0 7052 13 0 0
v_min_non_paid_realizn 7282 7282 0 0.00 0 6455 13 0 0
v_our_firm 10358 10358 0 0.00 0 72 1 0 0
v_reserve_write_off 20132 20132 0 0.00 0 6204 5 0 0
x_lock_depdocs_on_canc_invoice 12020 12020 0 0.00 0 15601 103 0 0
x_lock_depdocs_on_canc_sup_ord 425 425 0 0.00 0 14758 378 0 0
x_make_qstorno_1000_1200 5495 5495 0 0.00 1 36754 1222 0 0
x_make_qstorno_1200_2000 5541 5541 0 0.00 2 85926 3069 0 0
x_make_qstorno_2100_3300 471109 471109 0 0.00 0 33242 155 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 13-08-2022 19:39:42 to 13-08-2022 19:39:46.

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
17:08:30 2 8192000000 8799193664 8810627072 5590192 0.068 2 1 2 0 5689824 56848 42784
17:09:31 18241 8192000000 9912761872 10223448064 993710848 10.818 100 90 180 0 1023811008 6583152 23517008
17:10:53 18711 8192000000 9952090944 10275123200 1033836544 11.205 100 91 182 1 1065315248 8911248 22567456
17:12:22 16387 8192000000 9983060368 10319863808 1070508496 11.557 100 82 164 1 1101029408 10206464 20314448
17:13:42 8314 8192000000 10025468928 10372075520 1111274000 11.944 100 83 166 0 1142673664 11347840 20051824
17:14:53 8874 8192000000 10080473824 10434961408 1157914256 12.384 100 83 166 0 1189965328 12447296 19603776
17:16:06 4376 8192000000 10107268480 10477580288 1200161104 12.778 100 75 148 0 1228152096 11469008 16521984
17:17:13 2628 8192000000 10148793936 10523344896 1235254672 13.103 100 86 172 0 1267371024 12981088 19135264
17:18:17 1189 8192000000 10171991904 10553315328 1260910192 13.338 100 79 158 0 1291962640 13425600 17626848
17:19:20 1073 8192000000 10201879376 10586251264 1288328768 13.589 100 79 155 0 1318508832 13007840 17172224
17:20:23 14240 8192000000 10193992832 10597621760 1298260112 13.679 100 40 80 1 1317141712 9543200 9338400
17:21:39 6527 8192000000 10193269088 10604859392 1308275424 13.770 100 23 44 0 1319616912 6395920 4945568
17:22:46 7076 8192000000 10203299040 10617004032 1317106128 13.850 100 24 47 0 1330176048 7791504 5278416
17:23:56 60827 8192000000 10230866064 10643152896 1326165376 13.932 100 46 91 0 1347094448 9513648 11415424
17:25:58 5973 8192000000 10231560128 10647859200 1334717648 14.010 100 33 64 0 1349229648 6967728 7544272
17:27:05 8097 8192000000 10229164560 10656342016 1342936544 14.084 100 27 53 1 1354821728 6119152 5766032
17:28:15 64436 8192000000 10217856016 10630139904 1281675568 13.528 96 17 34 0 1291364352 5531632 4157152
17:30:21 7182 8192000000 10193405664 10602487808 1294437872 13.645 100 30 60 0 1312109824 10789792 6882160
17:31:28 5324 8192000000 10138210144 10530775040 1232037376 13.073 99 33 67 0 1246019632 6085776 7896480
17:32:38 49289 8192000000 10153422720 10551779328 1246495200 13.206 99 19 37 0 1256106960 5167024 4444736
17:34:29 5284 8192000000 10175849440 10578235392 1276689328 13.483 100 30 60 0 1288699904 5891120 6119456
17:35:35 8980 8192000000 10183915184 10597015552 1291005952 13.613 100 28 55 0 1311403648 14296416 6101280
17:36:46 11919 8192000000 10191543008 10610851840 1307838160 13.766 100 24 48 0 1318206240 5255840 5112240
17:37:59 35573 8192000000 10213266384 10627477504 1319882384 13.876 100 43 87 1 1336059776 6048544 10128848
17:39:46 23963 8192000000 10260869408 10672443392 1348945456 14.138 100 55 110 0 1371060096 10156064 11958576
17:41:16 14449 8192000000 10260582080 10685517824 1363376880 14.268 100 34 64 1 1379122352 8744960 7000512
17:42:35 12284 8192000000 10279167312 10705186816 1374808320 14.370 100 37 72 2 1399743072 17154592 7780160
17:43:51 17445 8192000000 10307721360 10730688512 1385801296 14.468 100 45 89 0 1416243904 19324592 11118016
17:45:13 24551 8192000000 10304305728 10744193024 1399094416 14.587 100 30 60 0 1421489808 14117680 8277712
17:46:40 33762 8192000000 10327059376 10758217728 1413285392 14.713 100 41 82 1 1441535392 18761216 9488784
17:48:20 6371 8192000000 10342921680 10776182784 1426656720 14.832 100 41 82 0 1456464320 19896384 9911216
17:49:34 8735 8192000000 10359253984 10790092800 1433003616 14.888 100 52 102 0 1471816736 26205408 12607712
17:50:47 2622 8192000000 10362081216 10788225024 1429875200 14.860 100 58 115 1 1472533056 28370864 14286992
17:51:55 3268 8192000000 10372681696 10791735296 1416753920 14.744 99 71 140 0 1470208704 36465648 16989136
17:53:03 3329 8192000000 10354236944 10765017088 1383790992 14.450 99 86 170 3 1444201680 40315456 20095232
17:54:08 4221 8192000000 10345873552 10762964992 1396417200 14.563 100 62 123 0 1451658576 39942256 15299120
17:55:18 6844 8192000000 10368870352 10784362496 1400246128 14.597 99 67 135 0 1459290928 42807072 16237728
17:56:29 2182 8192000000 10367694848 10775588864 1394901408 14.550 100 84 168 0 1467895104 51363648 21630048
17:57:38 797 8192000000 10350586912 10761170944 1372380336 14.348 100 72 145 2 1451956464 61609536 17966592
17:58:42 6178 8192000000 10343135328 10761662464 1372645808 14.351 99 54 106 0 1433921712 47996400 13279504
17:59:52 37165 8192000000 10314896432 10746093568 1357139792 14.212 98 33 63 0 1402880576 37654096 8086688
18:01:38 21597 8192000000 10330137456 10764705792 1381221856 14.427 100 28 56 0 1430608480 41787232 7599392
18:03:05 30655 8192000000 10334538272 10774495232 1390307136 14.509 100 21 42 0 1436319104 40317280 5694688
18:04:38 25250 8192000000 10321317888 10761211904 1359180928 14.230 99 25 50 0 1411843824 45664144 6998752
18:06:05 48507 8192000000 10248518272 10689089536 1293441520 13.636 95 18 36 0 1312043904 14434256 4168128
18:08:10 56334 8192000000 10272159904 10707632128 1339427392 14.052 99 13 26 0 1354982384 12880544 2674448
18:10:07 70959 8192000000 10275290512 10728521728 1352972320 14.174 99 7 14 0 1372011888 17585536 1454032
18:12:20 69669 8192000000 10290829408 10734133248 1378670192 14.405 100 38 75 0 1396087088 9102208 8314688
18:14:53 48365 8192000000 10291290720 10748669952 1386870192 14.478 100 13 26 0 1401570736 11745568 2954976
18:16:46 29005 8192000000 10302129776 10736009216 1373614048 14.359 99 33 63 2 1397081424 15533984 7933392
18:18:17 23064 8192000000 10315701072 10751275008 1365681136 14.288 98 56 110 0 1396522320 17909456 12931728
18:20:03 27649 8192000000 10282085456 10748796928 1368586528 14.314 99 12 24 0 1393111216 21494752 3029936
18:21:41 53804 8192000000 10292330304 10739949568 1371990512 14.345 99 15 30 0 1390489280 14973008 3525760
18:23:36 48736 8192000000 10288919680 10741211136 1371514864 14.341 99 14 27 0 1397196240 22723680 2957696
18:25:26 51427 8192000000 10293534720 10752356352 1386600592 14.476 100 11 22 0 1404105264 14679344 2825328
18:27:18 65293 8192000000 10293814768 10754162688 1374501968 14.367 99 11 22 0 1394433824 17461728 2470128
18:29:24 62531 8192000000 10305780608 10768228352 1392313280 14.527 100 11 22 0 1417556880 22493312 2750288
18:31:29 134377 8192000000 10284956288 10735296512 1297256192 13.670 93 11 22 0 1314326016 15010912 2058912
18:34:44 38493 8192000000 10305799488 10755899392 1390221520 14.508 100 22 44 0 1406171008 10988528 4960960
18:36:34 49196 8192000000 10310823696 10767142912 1374179888 14.364 98 27 54 1 1396719984 16279488 6260608
18:38:41 44327 8192000000 10270826960 10733133824 1358018416 14.220 98 31 57 1 1373355616 8115232 7221968
18:40:27 7527 8192000000 10296991840 10739253248 1382937808 14.443 100 41 81 0 1399339568 7952384 8449376
18:41:37 40383 8192000000 10275032336 10739154944 1371175632 14.338 99 42 80 2 1388857472 9304320 8377520
18:43:27 56888 8192000000 10280829440 10737131520 1363647664 14.270 99 22 41 0 1377174096 8533712 4992720
18:46:15 58080 8192000000 10285648832 10746482688 1377368752 14.393 100 12 24 0 1395027888 14356320 3302816
18:48:14 56646 8192000000 10267216352 10732810240 1340722576 14.064 97 19 38 0 1357321824 11324240 5275008
18:50:17 42932 8192000000 10266327088 10717593600 1327617616 13.946 97 28 54 0 1347198448 13647488 5933344
18:52:15 36810 8192000000 10255757408 10711945216 1326929120 13.939 98 23 47 0 1348198480 15904912 5364448
18:54:01 57619 8192000000 10268633968 10708021248 1335892480 14.020 98 15 30 0 1348693632 9705024 3096128
18:56:02 65107 8192000000 10277255680 10733862912 1350420816 14.151 99 18 34 0 1375252512 21139664 3692032
18:58:07 15422 8192000000 10269989344 10715500544 1342231360 14.078 98 28 56 0 1356408160 8068112 6108688
18:59:23 30837 8192000000 10242007952 10686738432 1319387088 13.871 97 38 76 0 1337809424 10091696 8330640
19:00:54 70730 8192000000 10267805200 10702962688 1321422528 13.890 97 29 58 1 1340826592 12945728 6458336
19:03:06 61517 8192000000 10272775072 10726154240 1353815504 14.182 99 30 60 0 1378391952 17843600 6732848
19:05:10 67461 8192000000 10271014400 10743762944 1369972176 14.327 100 16 32 0 1392631584 18618256 4041152
19:07:25 53891 8192000000 10278250960 10746822656 1378742512 14.405 100 6 12 1 1390539136 10860320 936304
19:09:23 47715 8192000000 10277321008 10740817920 1360934576 14.246 98 13 26 0 1374380016 10608400 2837040
19:11:11 77150 8192000000 10297160832 10763141120 1358945216 14.228 97 9 18 0 1371216768 10302416 1969136
19:13:29 73257 8192000000 10307611248 10767044608 1383364528 14.447 99 23 46 0 1402702096 14073824 5263744
19:15:47 29225 8192000000 10309136976 10758430720 1387368208 14.482 99 40 78 2 1404784160 8727696 8688256
19:17:19 49483 8192000000 10299192912 10750128128 1378932112 14.407 99 29 58 0 1399045296 13843904 6269280
19:19:15 61869 8192000000 10297447792 10761859072 1369725376 14.325 98 32 64 0 1394375056 17486480 7163200
19:21:34 53071 8192000000 10303360816 10755014656 1364094128 14.274 98 14 27 0 1379886560 13089440 2702992
19:23:27 123807 8192000000 10284993872 10739572736 1324848592 13.921 95 11 22 0 1337429616 10429632 2151392
19:26:31 38639 8192000000 10297253776 10772963328 1383281136 14.446 100 11 22 0 1411208592 25609584 2317872
19:28:17 54091 8192000000 10286293312 10756018176 1370943936 14.336 98 4 8 0 1382509408 10705536 859936
19:30:12 53575 8192000000 10293331824 10753220608 1383226848 14.445 99 10 20 0 1393987200 8055232 2705120
19:32:06 46832 8192000000 10289258000 10758696960 1382214320 14.436 99 12 24 1 1394345984 9046352 3085312
19:33:57 48862 8192000000 10278824816 10740211712 1351769392 14.163 97 19 39 0 1367823680 11834560 4219728
19:35:57 65079 8192000000 10286294096 10741256192 1349418432 14.142 97 17 34 0 1360927616 8043488 3465696
19:38:02 31577 8192000000 9158060032 9407152128 315451056 3.707 23 7 14 0 326741856 9797920 1492880

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 13-08-2022 19:39:48 to 13-08-2022 19:40:03.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 150 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 13-08-2022 19:40:04 to 13-08-2022 19:40:06.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          240549
MON$OLDEST_ACTIVE               240837
MON$OLDEST_SNAPSHOT             240837
MON$NEXT_TRANSACTION            240837
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-03 16:41:55.3470
MON$PAGES                       2028384
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33616 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33616 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33616 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33616 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.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 ---
:195: 
:196: mscs-test-161.budzdorov.ru    Sat Aug 13 19:38:44 2022
:197:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:198: 
:199: 
:200: mscs-test-161.budzdorov.ru    Sat Aug 13 19:38:44 2022
:201:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:202: 
--- end of diff output ---

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

13.08.22 19:40:07. There are 3 logs in /var/tmp/logs-oltp30 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2022-08-13 19:40:09 No severe PSQL-related problems occured
13.08.22 19:40:11. End of report.