Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 17.10.2022 22:40
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 15397728 kB
MemAvailable 30515056 kB
Buffers 0 kB
Cached 16338220 kB
SwapCached 2052 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-10-17 22:38:34
DTS_END                         2022-10-17 22:38:34.9090
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(57:17)
IP                              ::1/34530
TRN_ID                          240973
ATT_ID                          677
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: 9467.09 19716 1136051
customer order: creation 171.98 369 20637
customer order: refuse 14.79 355 1775
order to supplier: creation 45.44 1271 5453
order to supplier: removal 3.55 103333 426
invoice (draft): creation 44.93 3186 5392
invoice (draft): removal 54.24 784 6509
invoice accept: apply 41.91 17691 5029
invoice accept: cancel 48.94 752 5873
customer reserve: creation 3937.75 171 472530
customer reserve: removal 4805.35 60 576642
realization accept: apply 83.67 196 10040
realization accept: cancel 86.03 114 10324
payment from customer: creation 58.44 104 7013
payment from customer: removal 5.49 73 659
payment to supplier: creation 49.19 132 5903
payment to supplier: removal 3.32 84 398
service: total inventory turnovers 5.01 989 601
service: total monetary turnovers 6.21 27 745
service: aggregate perf. data 0.82 21504 98
service: refresh index statistics 0.03 243131 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 17-10-2022 22:40:42 to 17-10-2022 22:40:44.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8183
TEST_TIME 2 6473
TEST_TIME 3 5957
TEST_TIME 4 5563
TEST_TIME 5 5234
TEST_TIME 6 5096
TEST_TIME 7 5377
TEST_TIME 8 5165
TEST_TIME 9 5139
TEST_TIME 10 5026
TEST_TIME 11 5205
TEST_TIME 12 5350
TEST_TIME 13 6285
TEST_TIME 14 6423
TEST_TIME 15 6572
TEST_TIME 16 6856
TEST_TIME 17 7082
TEST_TIME 18 7337
TEST_TIME 19 7557
TEST_TIME 20 7693
TEST_TIME 21 8066
TEST_TIME 22 8055
TEST_TIME 23 8310
TEST_TIME 24 8745
TEST_TIME 25 8898
TEST_TIME 26 9686
TEST_TIME 27 9949
TEST_TIME 28 10340
TEST_TIME 29 11050
TEST_TIME 30 10885
TEST_TIME 31 11015
TEST_TIME 32 10882
TEST_TIME 33 10675
TEST_TIME 34 10558
TEST_TIME 35 10267
TEST_TIME 36 10279
TEST_TIME 37 10354
TEST_TIME 38 10201
TEST_TIME 39 10114
TEST_TIME 40 10100
TEST_TIME 41 10068
TEST_TIME 42 9990
TEST_TIME 43 10017
TEST_TIME 44 9979
TEST_TIME 45 9930
TEST_TIME 46 9871
TEST_TIME 47 9777
TEST_TIME 48 9770
TEST_TIME 49 9792
TEST_TIME 50 9703
TEST_TIME 51 9712
TEST_TIME 52 9589
TEST_TIME 53 9540
TEST_TIME 54 9543
TEST_TIME 55 9600
TEST_TIME 56 9610
TEST_TIME 57 9663
TEST_TIME 58 9728
TEST_TIME 59 9804
TEST_TIME 60 9845
TEST_TIME 61 9864
TEST_TIME 62 9852
TEST_TIME 63 9857
TEST_TIME 64 9916
TEST_TIME 65 9978
TEST_TIME 66 9994
TEST_TIME 67 9917
TEST_TIME 68 9793
TEST_TIME 69 9792
TEST_TIME 70 9841
TEST_TIME 71 9780
TEST_TIME 72 9734
TEST_TIME 73 9752
TEST_TIME 74 9773
TEST_TIME 75 9751
TEST_TIME 76 9669
TEST_TIME 77 9635
TEST_TIME 78 9621
TEST_TIME 79 9644
TEST_TIME 80 9657
TEST_TIME 81 9640
TEST_TIME 82 9617
TEST_TIME 83 9680
TEST_TIME 84 9725
TEST_TIME 85 9701
TEST_TIME 86 9728
TEST_TIME 87 9706
TEST_TIME 88 9692
TEST_TIME 89 9783
TEST_TIME 90 9795
TEST_TIME 91 9851
TEST_TIME 92 9786
TEST_TIME 93 9704
TEST_TIME 94 9673
TEST_TIME 95 9648
TEST_TIME 96 9624
TEST_TIME 97 9588
TEST_TIME 98 9551
TEST_TIME 99 9593
TEST_TIME 100 9539
TEST_TIME 101 9520
TEST_TIME 102 9504
TEST_TIME 103 9486
TEST_TIME 104 9504
TEST_TIME 105 9517
TEST_TIME 106 9509
TEST_TIME 107 9472
TEST_TIME 108 9552
TEST_TIME 109 9500
TEST_TIME 110 9501
TEST_TIME 111 9604
TEST_TIME 112 9586
TEST_TIME 113 9690
TEST_TIME 114 9688
TEST_TIME 115 9645
TEST_TIME 116 9587
TEST_TIME 117 9575
TEST_TIME 118 9543
TEST_TIME 119 9522
TEST_TIME 120 9469

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 17-10-2022 22:40:46 to 17-10-2022 22:40:49.

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 1572241 1572241 0 0.00 0 19967 6 0 0
doc_list_biud 1572238 1572238 0 0.00 0 103879 28 0 0
sp_add_invoice_to_stock 5216 5029 187 3.58 32 184011 17691 0 187
sp_cancel_adding_invoice 5873 5873 0 0.00 5 74547 753 0 0
sp_cancel_client_order 1775 1775 0 0.00 4 10058 356 0 0
sp_cancel_customer_reserve 576642 576642 0 0.00 0 60626 60 0 0
sp_cancel_pay_from_customer 659 659 0 0.00 2 4339 73 0 0
sp_cancel_pay_to_supplier 398 398 0 0.00 3 2560 84 0 0
sp_cancel_supplier_invoice 6518 6509 9 0.13 1 103990 784 0 9
sp_cancel_supplier_order 426 426 0 0.00 7 1172556 103333 0 0
sp_cancel_write_off 10324 10324 0 0.00 1 13188 115 0 0
sp_client_order 20637 20637 0 0.00 3 56438 370 0 0
sp_customer_reserve 472530 472530 0 0.00 0 65806 172 0 0
sp_fill_shopping_cart 31474 31474 0 0.00 1 15715 35 0 0
sp_fill_shopping_cart_clo_res 472527 472527 0 0.00 0 22519 14 0 0
sp_get_clo_for_invoice 5024 5024 0 0.00 0 34979 277 0 0
sp_kill_cost_storno 17254 17254 0 0.00 0 6536 51 0 0
sp_kill_qstorno_ret_qs2qd 1170543 1170543 0 0.00 0 103876 33 0 0
sp_kill_qty_storno 616611 616611 0 0.00 0 103878 70 0 0
sp_lock_selected_doc 20812 20812 0 0.00 0 9885 7 0 0
sp_make_cost_storno 27980 27980 0 0.00 0 11200 55 0 0
sp_multiply_rows_for_pdistr 27979 27979 0 0.00 0 9552 27 0 0
sp_multiply_rows_for_qdistr 20637 20637 0 0.00 0 56427 169 0 0
sp_pay_from_customer 7013 7013 0 0.00 4 13174 104 0 0
sp_pay_to_supplier 5903 5903 0 0.00 5 15218 133 0 0
sp_payment_common 12916 12916 0 0.00 1 9343 29 0 0
sp_qd_handle_on_cancel_clo 1775 1775 0 0.00 0 10053 317 0 0
sp_qd_handle_on_invoice_upd_sts 10897 10897 0 0.00 0 24580 328 0 0
sp_qd_handle_on_reserve_upd_sts 10038 10038 0 0.00 0 19015 60 0 0
sp_reserve_write_off 10040 10040 0 0.00 6 19050 197 0 0
sp_supplier_invoice 5392 5392 0 0.00 6 99759 3186 0 0
sp_supplier_order 5453 5453 0 0.00 5 31040 1272 0 0
srv_aggregate_perf_data 98 98 0 0.00 2703 241188 21505 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 2422 123375 40035 0 0
srv_find_qd_qs_mism 1087443 1087443 0 0.00 0 19966 5 0 0
srv_make_invnt_saldo 601 601 0 0.00 28 22657 989 0 0
srv_make_money_saldo 745 745 0 0.00 1 528 27 0 0
srv_recalc_idx_stat 4 4 0 0.00 66418 399408 243132 0 0
t$perf-abend:sp_add_invoice_to_stock 187 187 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5029 5029 0 0.00 0 16923 175 0 0
t$perf-norm:sp_cancel_adding_invoice 410 410 0 0.00 0 2784 69 0 0
t$perf-norm:sp_cancel_client_order 1775 1775 0 0.00 0 8289 26 0 0
t$perf-norm:sp_cancel_customer_reserve 668 668 0 0.00 0 1289 16 0 0
t$perf-norm:sp_cancel_pay_from_customer 659 659 0 0.00 0 2656 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 398 398 0 0.00 0 1865 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 382 382 0 0.00 0 1248 11 0 0
t$perf-norm:sp_cancel_supplier_order 426 426 0 0.00 0 28388 1610 0 0
t$perf-norm:sp_cancel_write_off 1032 1032 0 0.00 0 3597 13 0 0
t$perf-norm:sp_client_order 20637 20637 0 0.00 0 16198 17 0 0
t$perf-norm:sp_customer_reserve 958 958 0 0.00 0 3028 9 0 0
t$perf-norm:sp_pay_from_customer 7013 7013 0 0.00 0 5228 8 0 0
t$perf-norm:sp_pay_to_supplier 5903 5903 0 0.00 0 6670 12 0 0
t$perf-norm:sp_reserve_write_off 10040 10040 0 0.00 0 14286 14 0 0
t$perf-norm:sp_supplier_invoice 5392 5392 0 0.00 0 6442 14 0 0
t$perf-norm:sp_supplier_order 5453 5453 0 0.00 0 7465 15 0 0
t$perf-norm:srv_aggregate_perf_data 98 98 0 0.00 0 89 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 376 6 0 0
t$perf-norm:srv_make_invnt_saldo 601 601 0 0.00 0 491 15 0 0
t$perf-norm:srv_make_money_saldo 745 745 0 0.00 0 802 16 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 3 0 0
v_add_invoice_to_stock 10422 10048 374 3.58 0 6700 23 0 374
v_all_customers 37678 37678 0 0.00 0 210 1 0 0
v_all_suppliers 30284 30284 0 0.00 0 68 1 0 0
v_all_wares 41272 41272 0 0.00 0 210 1 0 0
v_cancel_adding_invoice 820 820 0 0.00 0 5312 19 0 0
v_cancel_client_order 3550 3550 0 0.00 0 4596 9 0 0
v_cancel_customer_prepayment 1318 1318 0 0.00 0 2814 7 0 0
v_cancel_customer_reserve 1336 1336 0 0.00 0 2519 10 0 0
v_cancel_payment_to_supplier 796 796 0 0.00 0 2551 7 0 0
v_cancel_supplier_invoice 782 764 18 2.30 0 3735 22 0 18
v_cancel_supplier_order 852 852 0 0.00 0 2643 6 0 0
v_cancel_write_off 2064 2064 0 0.00 0 5999 19 0 0
v_max_id_clo_ord 5447 5447 0 0.00 0 658 1 0 0
v_max_id_clo_res 958 958 0 0.00 0 1978 11 0 0
v_max_id_ord_sup 5390 5390 0 0.00 0 15712 8 0 0
v_max_non_paid_invoice 1598 1598 0 0.00 0 1134 3 0 0
v_max_non_paid_realizn 3710 3710 0 0.00 0 3307 5 0 0
v_min_id_clo_ord 5447 5447 0 0.00 0 2838 7 0 0
v_min_id_clo_res 958 958 0 0.00 0 6672 16 0 0
v_min_id_ord_sup 5390 5390 0 0.00 0 4726 9 0 0
v_min_non_paid_invoice 5903 5903 0 0.00 0 6973 19 0 0
v_min_non_paid_realizn 7013 7013 0 0.00 0 6226 21 0 0
v_our_firm 10200 10200 0 0.00 0 17 1 0 0
v_reserve_write_off 20076 20076 0 0.00 0 8939 8 0 0
x_lock_depdocs_on_canc_invoice 12000 12000 0 0.00 0 21088 115 0 0
x_lock_depdocs_on_canc_sup_ord 426 426 0 0.00 0 3451 311 0 0
x_make_qstorno_1000_1200 5453 5453 0 0.00 1 31031 1196 0 0
x_make_qstorno_1200_2000 5392 5392 0 0.00 3 99748 3063 0 0
x_make_qstorno_2100_3300 472530 472530 0 0.00 0 65802 156 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 17-10-2022 22:40:50 to 17-10-2022 22:40:54.

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
20:08:38 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:39 34460 8192000000 9898281936 10206703616 978776736 10.672 100 94 188 1 1010435872 6106736 25552400
20:11:18 27375 8192000000 9943823904 10265931776 1024560464 11.116 100 87 174 0 1055723920 8137696 23025760
20:12:55 13157 8192000000 9991231760 10325676032 1069393024 11.546 100 75 150 0 1096950416 9203824 18353568
20:14:13 11229 8192000000 10049140816 10398904320 1129305024 12.115 100 73 144 1 1156500464 10292912 16902528
20:15:26 6886 8192000000 10109826112 10468343808 1188242400 12.667 100 86 172 0 1220572128 12522672 19807056
20:16:36 2868 8192000000 10135752512 10505826304 1220654992 12.968 100 82 164 1 1252958784 14054032 18249760
20:17:41 1531 8192000000 10163605072 10540744704 1250678288 13.244 100 76 148 0 1280373648 13368784 16326576
20:18:44 1599 8192000000 10199529104 10581630976 1280582032 13.518 100 86 173 2 1313658928 14324976 18751920
20:19:48 1821 8192000000 10205019904 10597081088 1296125392 13.660 100 80 160 1 1325111600 11124176 17862032
20:20:51 25319 8192000000 10201405392 10602676224 1298750384 13.684 100 37 74 0 1313319920 5384128 9185408
20:22:18 4189 8192000000 10201662272 10612576256 1308023616 13.768 100 34 66 1 1323198896 7330176 7845104
20:23:26 9509 8192000000 10210582096 10629619712 1323818192 13.911 100 28 56 0 1336208176 5877776 6512208
20:24:40 10584 8192000000 10213616896 10636406784 1328501952 13.954 100 29 56 1 1345924320 11055248 6367120
20:25:53 55773 8192000000 10248294720 10655420416 1335509696 14.017 100 45 90 0 1356934864 10351136 11074032
20:27:50 4960 8192000000 10214897696 10633510912 1315542912 13.836 99 26 50 0 1327482480 6258848 5680720
20:28:56 8204 8192000000 10186676048 10596077568 1276087824 13.477 98 35 72 0 1290970848 6334512 8548512
20:30:11 46166 8192000000 10148603296 10556055552 1235158208 13.102 97 19 38 0 1244666880 5464864 4043808
20:32:09 6487 8192000000 10127395120 10525970432 1233118960 13.083 99 21 42 0 1243712048 5686336 4906752
20:33:16 8706 8192000000 10158270864 10559991808 1233202464 13.084 97 18 33 1 1242334688 5250480 3881744
20:34:27 12561 8192000000 10170761776 10584702976 1281950512 13.531 100 14 28 0 1290326448 5066096 3309840
20:35:40 42848 8192000000 10178416976 10595516416 1283178592 13.542 99 36 71 0 1297799584 5725472 8895520
20:37:25 8749 8192000000 10197379040 10609025024 1303241600 13.725 100 18 35 0 1312590000 5358720 3989680
20:38:36 2962 8192000000 10237135584 10644111360 1331020976 13.976 100 37 75 0 1345889280 5270224 9598080
20:39:44 60132 8192000000 10258152592 10678538240 1353804816 14.182 100 34 68 1 1368766928 6853936 8108176
20:41:51 23210 8192000000 10275739216 10699575296 1363238224 14.266 99 40 81 0 1383208416 11141712 8828480
20:43:17 10840 8192000000 10281435632 10704859136 1381107824 14.426 100 41 81 1 1402483456 12302656 9072976
20:44:31 10181 8192000000 10291901760 10715131904 1375817856 14.379 99 41 79 0 1399139024 13225312 10095856
20:45:49 18002 8192000000 10312562160 10736947200 1397233392 14.570 100 43 86 0 1424893920 16785728 10874800
20:47:10 19320 8192000000 10315703136 10739281920 1391221008 14.517 99 39 77 1 1420233808 19756096 9256704
20:48:43 5407 8192000000 10344064368 10763788288 1416356528 14.740 100 57 114 0 1456331136 26390400 13584208
20:49:55 2494 8192000000 10353475760 10773331968 1421685872 14.788 100 77 152 2 1474914208 33911376 19316960
20:51:01 2419 8192000000 10356089504 10763767808 1403751072 14.628 100 83 165 1 1462987120 39290224 19945824
20:52:08 4747 8192000000 10341723984 10759790592 1406514464 14.653 100 62 122 0 1454788640 33096656 15177520
20:53:20 7705 8192000000 10359118784 10783014912 1413568368 14.716 100 50 99 0 1464311440 37678448 13064624
20:54:38 4972 8192000000 10344860608 10757844992 1398938752 14.586 100 61 121 1 1448749520 34938176 14872592
20:55:46 2851 8192000000 10352038384 10758291456 1390988512 14.515 100 79 156 1 1453795472 43212208 19594752
20:56:56 3996 8192000000 10335835104 10745933824 1380569568 14.422 100 62 121 0 1440057600 43678544 15809488
20:58:04 4640 8192000000 10374729536 10784579584 1413523776 14.715 100 75 147 0 1477937184 45932464 18480944
20:59:14 8460 8192000000 10364053760 10785480704 1404005280 14.631 100 52 103 1 1473157024 54984160 14167584
21:00:33 14275 8192000000 10363514464 10791264256 1412193488 14.703 100 39 78 0 1471461152 48682672 10584992
21:01:50 11064 8192000000 10347410016 10769313792 1382316896 14.437 99 43 82 0 1443998288 50304288 11377104
21:03:11 21276 8192000000 10333459120 10756079616 1384450624 14.456 100 35 70 0 1442467120 48302448 9714048
21:04:53 25702 8192000000 10316258960 10755497984 1357029168 14.211 99 27 54 0 1406318944 42143264 7146512
21:06:30 16899 8192000000 10296850832 10737192960 1361656000 14.252 100 23 46 0 1411711952 43731216 6324736
21:08:34 42671 8192000000 10291122368 10740895744 1380021824 14.417 100 15 30 0 1398896048 15973376 2900848
21:10:34 42511 8192000000 10294489120 10740011008 1374588768 14.368 99 29 57 0 1387391584 6771408 6031408
21:12:20 123374 8192000000 10309858912 10754170880 1369616944 14.324 98 27 54 1 1387525648 11636768 6271936
21:15:41 47486 8192000000 10310719792 10774908928 1397013328 14.568 100 21 42 1 1418002544 15818992 5170224
21:17:31 48768 8192000000 10325004416 10763268096 1394545920 14.546 100 32 64 0 1411410192 9013552 7850720
21:19:28 55878 8192000000 10315942192 10769874944 1397611744 14.574 100 23 46 0 1422256576 19721024 4923808
21:21:24 17362 8192000000 10308585120 10777333760 1403409792 14.625 100 12 24 0 1425420752 19182912 2828048
21:23:01 20073 8192000000 10298659024 10753941504 1350592480 14.153 96 19 38 1 1367931136 13182768 4155888
21:24:22 46166 8192000000 10312401152 10770341888 1403426736 14.625 100 18 36 1 1419269568 11504944 4337888
21:26:23 68692 8192000000 10308556240 10768060416 1389357552 14.500 99 26 52 0 1408387936 13293216 5737168
21:28:33 32205 8192000000 10286950784 10744983552 1376369600 14.384 98 20 40 0 1388765424 7931280 4464544
21:30:10 33506 8192000000 10294080944 10758959104 1387333968 14.482 99 11 20 1 1396421040 6784944 2302128
21:31:50 37371 8192000000 10316298912 10769850368 1400197360 14.597 100 28 55 1 1419845936 13116992 6531584
21:33:27 31959 8192000000 10317128352 10779279360 1401324752 14.607 100 22 44 0 1423489392 17224480 4940160
21:35:01 34969 8192000000 10314371808 10768920576 1374266160 14.365 98 22 44 0 1397139248 17782640 5090448
21:37:01 23282 8192000000 10306766464 10760056832 1389373200 14.500 100 37 74 0 1410094352 12561696 8159456
21:38:58 27783 8192000000 10280435536 10716897280 1317277472 13.852 96 37 70 0 1332015232 7315776 7421984
21:40:26 58185 8192000000 10252784560 10700853248 1267715760 13.401 93 15 28 0 1277806240 7402512 2687968
21:42:29 72977 8192000000 10261174272 10724671488 1293700528 13.638 95 16 32 0 1312822128 15625616 3495984
21:44:42 46929 8192000000 10253337424 10708185088 1341309200 14.069 99 13 26 0 1354487488 10504288 2674000
21:47:27 45892 8192000000 10285377216 10720825344 1323648992 13.910 97 35 70 1 1345417616 13461808 8306816
21:49:13 22786 8192000000 10261312320 10701017088 1316907152 13.849 97 36 72 2 1338739824 14295760 7536912
21:50:42 62779 8192000000 10255315424 10715865088 1343636032 14.090 99 13 25 0 1363421504 16671600 3113872
21:52:59 59997 8192000000 10257420592 10715680768 1347129312 14.122 99 13 26 0 1359861312 9810096 2921904
21:55:25 95969 8192000000 10255272000 10711064576 1305272384 13.743 96 9 18 0 1318250928 11004832 1973712
21:58:01 53449 8192000000 10263787312 10723991552 1352483056 14.170 99 14 28 0 1364560240 9063360 3013824
22:00:03 34950 8192000000 10288724576 10730901504 1370163120 14.329 100 31 61 0 1386229904 9303568 6763216
22:01:40 85379 8192000000 10254134704 10701783040 1281973568 13.531 94 13 26 0 1295648608 10566656 3108384
22:04:11 91219 8192000000 10271148688 10719121408 1326535280 13.936 97 16 32 0 1338909184 9174416 3199488
22:06:47 62275 8192000000 10299412416 10758524928 1374967440 14.372 100 30 60 0 1395978720 14583648 6427632
22:08:50 38671 8192000000 10308954128 10763309056 1391427296 14.519 100 24 48 1 1405904944 9715184 4762464
22:12:17 66116 8192000000 10280971856 10745606144 1364768864 14.280 98 19 36 0 1379699776 10635744 4295168
22:14:32 58822 8192000000 10270224464 10736648192 1332788560 13.992 96 10 21 0 1345117424 10336544 1992320
22:16:33 73581 8192000000 10284937376 10751926272 1369912752 14.326 99 17 34 0 1394245904 20127200 4205952
22:18:59 78137 8192000000 10278329792 10751201280 1355245744 14.195 98 16 32 0 1371299552 13076112 2977696
22:21:20 57388 8192000000 10278067616 10740076544 1357848208 14.218 98 13 24 0 1371528880 10784432 2896240
22:23:22 50560 8192000000 10272691936 10733531136 1352746016 14.172 98 17 34 0 1369155072 12807376 3601680
22:25:14 61664 8192000000 10279050288 10739122176 1342178240 14.077 97 18 37 0 1360214128 13887024 4148864
22:28:02 44741 8192000000 10289476880 10734911488 1347529744 14.125 98 27 53 0 1372272720 18454224 6288752
22:29:56 32749 8192000000 10274859264 10722312192 1349736656 14.145 99 25 50 0 1364845312 9842752 5265904
22:31:31 103996 8192000000 10273531728 10732154880 1319796416 13.875 97 14 28 1 1347473872 24543584 3133872
22:34:15 83727 8192000000 10256865136 10709016576 1350547280 14.152 99 42 79 1 1369907936 10202752 9157904
22:36:51 43698 8192000000 10244872768 10702651392 1314221040 13.824 97 14 28 0 1323306576 6212000 2873536

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 17-10-2022 22:40:56 to 17-10-2022 22:41:11.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 187 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 17-10-2022 22:41:12 to 17-10-2022 22:41:14.

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          240401
MON$OLDEST_ACTIVE               241499
MON$OLDEST_SNAPSHOT             241499
MON$NEXT_TRANSACTION            241499
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                       2037920
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.33635 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33635 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33635 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33635 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 ---
:36: 
:37: mscs-test-161.budzdorov.ru    Mon Oct 17 22:38:41 2022
:38:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:39: 
:40: 
:41: mscs-test-161.budzdorov.ru    Mon Oct 17 22:38:58 2022
:42:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:43: 
:44: 
:45: mscs-test-161.budzdorov.ru    Mon Oct 17 22:38:58 2022
:46:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:47: 
:48: 
:49: mscs-test-161.budzdorov.ru    Mon Oct 17 22:38:58 2022
:50:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:51: 
:52: 
:53: mscs-test-161.budzdorov.ru    Mon Oct 17 22:38:58 2022
:54:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:55: 
--- end of diff output ---

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

17.10.22 22:41:15. 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-10-17 22:41:18 No severe PSQL-related problems occured
17.10.22 22:41:19. End of report.