Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 13.12.2022 16: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 14990028 kB
MemAvailable 30091852 kB
Buffers 0 kB
Cached 16723496 kB
SwapCached 3152 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-12-13 16:38:29
DTS_END                         2022-12-13 16:38:29.5470
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/36040
TRN_ID                          245656
ATT_ID                          720
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: 9388.63 19634 1126636
customer order: creation 181.34 333 21761
customer order: refuse 15.12 419 1814
order to supplier: creation 48.27 1258 5792
order to supplier: removal 3.48 87787 418
invoice (draft): creation 48.32 3242 5798
invoice (draft): removal 52.69 730 6323
invoice accept: apply 42.93 18337 5152
invoice accept: cancel 47.58 783 5709
customer reserve: creation 3977.41 180 477289
customer reserve: removal 4664.42 51 559730
realization accept: apply 86.49 179 10379
realization accept: cancel 82.64 106 9917
payment from customer: creation 62.97 95 7556
payment from customer: removal 6.24 88 749
payment to supplier: creation 52.17 106 6260
payment to supplier: removal 3.69 102 443
service: total inventory turnovers 5.08 918 609
service: total monetary turnovers 6.93 32 831
service: aggregate perf. data 0.85 20190 102
service: refresh index statistics 0.03 257739 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 13-12-2022 16:39:49 to 13-12-2022 16:39:52.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4559
TEST_TIME 2 5473
TEST_TIME 3 5616
TEST_TIME 4 5408
TEST_TIME 5 5750
TEST_TIME 6 5721
TEST_TIME 7 5830
TEST_TIME 8 6167
TEST_TIME 9 6191
TEST_TIME 10 7104
TEST_TIME 11 7267
TEST_TIME 12 7870
TEST_TIME 13 8001
TEST_TIME 14 8182
TEST_TIME 15 8192
TEST_TIME 16 8131
TEST_TIME 17 8552
TEST_TIME 18 8910
TEST_TIME 19 9157
TEST_TIME 20 9597
TEST_TIME 21 9905
TEST_TIME 22 9785
TEST_TIME 23 9757
TEST_TIME 24 10009
TEST_TIME 25 9989
TEST_TIME 26 10380
TEST_TIME 27 10552
TEST_TIME 28 11409
TEST_TIME 29 11321
TEST_TIME 30 11127
TEST_TIME 31 10905
TEST_TIME 32 10725
TEST_TIME 33 10668
TEST_TIME 34 10657
TEST_TIME 35 10495
TEST_TIME 36 10431
TEST_TIME 37 10333
TEST_TIME 38 10365
TEST_TIME 39 10321
TEST_TIME 40 10446
TEST_TIME 41 10305
TEST_TIME 42 10168
TEST_TIME 43 10165
TEST_TIME 44 10371
TEST_TIME 45 10212
TEST_TIME 46 10199
TEST_TIME 47 10012
TEST_TIME 48 9933
TEST_TIME 49 10036
TEST_TIME 50 9929
TEST_TIME 51 9893
TEST_TIME 52 9735
TEST_TIME 53 9891
TEST_TIME 54 9966
TEST_TIME 55 10094
TEST_TIME 56 10070
TEST_TIME 57 10015
TEST_TIME 58 10058
TEST_TIME 59 10162
TEST_TIME 60 10222
TEST_TIME 61 10232
TEST_TIME 62 10284
TEST_TIME 63 10168
TEST_TIME 64 10073
TEST_TIME 65 9981
TEST_TIME 66 9946
TEST_TIME 67 9877
TEST_TIME 68 9823
TEST_TIME 69 9800
TEST_TIME 70 9692
TEST_TIME 71 9609
TEST_TIME 72 9744
TEST_TIME 73 9711
TEST_TIME 74 9681
TEST_TIME 75 9672
TEST_TIME 76 9735
TEST_TIME 77 9703
TEST_TIME 78 9705
TEST_TIME 79 9756
TEST_TIME 80 9779
TEST_TIME 81 9893
TEST_TIME 82 9911
TEST_TIME 83 9934
TEST_TIME 84 9939
TEST_TIME 85 9883
TEST_TIME 86 9805
TEST_TIME 87 9811
TEST_TIME 88 9755
TEST_TIME 89 9765
TEST_TIME 90 9698
TEST_TIME 91 9620
TEST_TIME 92 9609
TEST_TIME 93 9593
TEST_TIME 94 9579
TEST_TIME 95 9611
TEST_TIME 96 9579
TEST_TIME 97 9625
TEST_TIME 98 9585
TEST_TIME 99 9636
TEST_TIME 100 9632
TEST_TIME 101 9637
TEST_TIME 102 9647
TEST_TIME 103 9628
TEST_TIME 104 9624
TEST_TIME 105 9741
TEST_TIME 106 9740
TEST_TIME 107 9707
TEST_TIME 108 9762
TEST_TIME 109 9686
TEST_TIME 110 9642
TEST_TIME 111 9610
TEST_TIME 112 9602
TEST_TIME 113 9583
TEST_TIME 114 9550
TEST_TIME 115 9536
TEST_TIME 116 9484
TEST_TIME 117 9462
TEST_TIME 118 9450
TEST_TIME 119 9448
TEST_TIME 120 9390

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 13-12-2022 16:39:53 to 13-12-2022 16:39:57.

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 1569090 1569090 0 0.00 0 16150 6 0 0
doc_list_biud 1569090 1569090 0 0.00 0 22697 24 0 0
sp_add_invoice_to_stock 5245 5152 93 1.77 34 202576 18338 0 93
sp_cancel_adding_invoice 5709 5709 0 0.00 4 52885 783 0 0
sp_cancel_client_order 1814 1814 0 0.00 4 15489 419 0 0
sp_cancel_customer_reserve 559730 559730 0 0.00 0 22700 52 0 0
sp_cancel_pay_from_customer 749 749 0 0.00 2 8600 88 0 0
sp_cancel_pay_to_supplier 443 443 0 0.00 3 7315 102 0 0
sp_cancel_supplier_invoice 6329 6323 6 0.09 1 20586 731 0 6
sp_cancel_supplier_order 418 418 0 0.00 13 823665 87787 0 0
sp_cancel_write_off 9917 9917 0 0.00 1 13995 107 0 0
sp_client_order 21761 21761 0 0.00 3 53269 334 0 0
sp_customer_reserve 477289 477289 0 0.00 0 89451 181 0 0
sp_fill_shopping_cart 33333 33333 0 0.00 1 24472 38 0 0
sp_fill_shopping_cart_clo_res 477285 477285 0 0.00 0 29552 13 0 0
sp_get_clo_for_invoice 5141 5141 0 0.00 0 25093 304 0 0
sp_kill_cost_storno 16818 16818 0 0.00 0 7517 51 0 0
sp_kill_qstorno_ret_qs2qd 1136118 1136118 0 0.00 0 22697 28 0 0
sp_kill_qty_storno 599429 599429 0 0.00 0 22697 62 0 0
sp_lock_selected_doc 21628 21628 0 0.00 0 7633 8 0 0
sp_make_cost_storno 29334 29334 0 0.00 0 8408 43 0 0
sp_multiply_rows_for_pdistr 29334 29334 0 0.00 0 5898 25 0 0
sp_multiply_rows_for_qdistr 21761 21761 0 0.00 0 17519 154 0 0
sp_pay_from_customer 7556 7556 0 0.00 4 10594 96 0 0
sp_pay_to_supplier 6260 6260 0 0.00 4 9752 106 0 0
sp_payment_common 13816 13816 0 0.00 1 9696 22 0 0
sp_qd_handle_on_cancel_clo 1814 1814 0 0.00 0 15482 382 0 0
sp_qd_handle_on_invoice_upd_sts 10848 10848 0 0.00 0 21849 333 0 0
sp_qd_handle_on_reserve_upd_sts 10379 10379 0 0.00 0 14130 60 0 0
sp_reserve_write_off 10379 10379 0 0.00 5 14149 180 0 0
sp_supplier_invoice 5798 5798 0 0.00 6 99707 3242 0 0
sp_supplier_order 5792 5792 0 0.00 5 27423 1258 0 0
srv_aggregate_perf_data 102 102 0 0.00 2487 403171 20190 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 1739 131960 39402 0 0
srv_find_qd_qs_mism 1076766 1076766 0 0.00 0 16149 5 0 0
srv_make_invnt_saldo 609 609 0 0.00 49 17425 919 0 0
srv_make_money_saldo 831 831 0 0.00 1 2202 32 0 0
srv_recalc_idx_stat 4 4 0 0.00 162074 387891 257740 0 0
t$perf-abend:sp_add_invoice_to_stock 93 93 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5152 5152 0 0.00 0 12516 170 0 0
t$perf-norm:sp_cancel_adding_invoice 436 436 0 0.00 0 3444 97 0 0
t$perf-norm:sp_cancel_client_order 1814 1814 0 0.00 0 1030 9 0 0
t$perf-norm:sp_cancel_customer_reserve 832 832 0 0.00 0 3524 18 0 0
t$perf-norm:sp_cancel_pay_from_customer 749 749 0 0.00 0 2115 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 443 443 0 0.00 0 282 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 427 427 0 0.00 0 5569 25 0 0
t$perf-norm:sp_cancel_supplier_order 418 418 0 0.00 0 27126 1530 0 0
t$perf-norm:sp_cancel_write_off 993 993 0 0.00 0 756 3 0 0
t$perf-norm:sp_client_order 21761 21761 0 0.00 0 9939 17 0 0
t$perf-norm:sp_customer_reserve 1083 1083 0 0.00 0 708 3 0 0
t$perf-norm:sp_pay_from_customer 7556 7556 0 0.00 0 7631 10 0 0
t$perf-norm:sp_pay_to_supplier 6260 6260 0 0.00 0 5226 9 0 0
t$perf-norm:sp_reserve_write_off 10379 10379 0 0.00 0 8497 12 0 0
t$perf-norm:sp_supplier_invoice 5798 5798 0 0.00 0 10189 20 0 0
t$perf-norm:sp_supplier_order 5792 5792 0 0.00 0 9039 18 0 0
t$perf-norm:srv_aggregate_perf_data 102 102 0 0.00 0 703 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 840 16 0 0
t$perf-norm:srv_make_invnt_saldo 609 609 0 0.00 0 5265 29 0 0
t$perf-norm:srv_make_money_saldo 831 831 0 0.00 0 1218 19 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 7 3 0 0
v_add_invoice_to_stock 10460 10274 186 1.77 0 3159 17 0 186
v_all_customers 40004 40004 0 0.00 0 61 1 0 0
v_all_suppliers 32042 32042 0 0.00 0 23 1 0 0
v_all_wares 43520 43520 0 0.00 0 53 1 0 0
v_cancel_adding_invoice 872 872 0 0.00 0 119 6 0 0
v_cancel_client_order 3628 3628 0 0.00 0 4106 6 0 0
v_cancel_customer_prepayment 1498 1498 0 0.00 0 1918 8 0 0
v_cancel_customer_reserve 1664 1664 0 0.00 0 2622 7 0 0
v_cancel_payment_to_supplier 886 886 0 0.00 0 3132 9 0 0
v_cancel_supplier_invoice 866 854 12 1.38 0 2114 18 0 12
v_cancel_supplier_order 836 836 0 0.00 0 4254 9 0 0
v_cancel_write_off 1986 1986 0 0.00 0 2637 17 0 0
v_max_id_clo_ord 5792 5792 0 0.00 0 1682 1 0 0
v_max_id_clo_res 1083 1083 0 0.00 0 6893 21 0 0
v_max_id_ord_sup 5778 5778 0 0.00 0 4610 5 0 0
v_max_non_paid_invoice 1809 1809 0 0.00 0 595 3 0 0
v_max_non_paid_realizn 3863 3863 0 0.00 0 3106 5 0 0
v_min_id_clo_ord 5792 5792 0 0.00 0 2625 5 0 0
v_min_id_clo_res 1083 1083 0 0.00 0 10107 30 0 0
v_min_id_ord_sup 5778 5778 0 0.00 0 24466 14 0 0
v_min_non_paid_invoice 6260 6260 0 0.00 0 4235 14 0 0
v_min_non_paid_realizn 7556 7556 0 0.00 0 6634 14 0 0
v_our_firm 10902 10902 0 0.00 0 17 1 0 0
v_reserve_write_off 20758 20758 0 0.00 0 3942 6 0 0
x_lock_depdocs_on_canc_invoice 11605 11605 0 0.00 0 14489 105 0 0
x_lock_depdocs_on_canc_sup_ord 418 418 0 0.00 0 11267 288 0 0
x_make_qstorno_1000_1200 5792 5792 0 0.00 1 27413 1186 0 0
x_make_qstorno_1200_2000 5798 5798 0 0.00 2 89578 3096 0 0
x_make_qstorno_2100_3300 477289 477289 0 0.00 0 89450 165 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 13-12-2022 16:39:58 to 13-12-2022 16:40:02.

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
14:08:33 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:09:34 26547 8192000000 9904071776 10212626432 979570928 10.680 100 88 176 0 1008638432 5713776 23353728
14:11:09 11124 8192000000 9935789392 10253545472 1017997264 11.053 100 88 176 0 1049322288 8117040 23207984
14:12:25 7582 8192000000 9963321984 10290556928 1049884560 11.360 100 84 168 0 1081383568 9997072 21501936
14:13:38 12663 8192000000 10023291328 10368053248 1108758528 11.921 100 81 160 0 1139022576 10662992 19601056
14:14:59 4699 8192000000 10064838432 10419048448 1151315744 12.322 100 81 162 0 1182991184 12948736 18726704
14:16:09 3451 8192000000 10113261296 10478604288 1203182064 12.806 100 80 158 0 1233595504 12708544 17704896
14:17:15 2284 8192000000 10147775984 10523127808 1242014912 13.165 100 84 167 0 1274023280 13268384 18739984
14:18:19 1535 8192000000 10181421088 10555994112 1263645296 13.363 100 85 169 1 1295517536 12974400 18897840
14:19:23 1236 8192000000 10202115584 10587115520 1289032592 13.595 100 86 171 0 1319956224 11540112 19383520
14:20:25 1837 8192000000 10195286848 10594496512 1295954960 13.658 100 57 114 0 1317458432 7178240 14325232
14:21:28 4902 8192000000 10206923280 10606694400 1308858704 13.776 100 49 96 0 1328529376 8972592 10698080
14:22:33 14833 8192000000 10213270752 10628190208 1321387648 13.889 100 19 38 0 1331278448 6214864 3675936
14:23:52 40848 8192000000 10211743376 10635386880 1328634320 13.955 100 37 75 0 1348079472 10618512 8826640
14:25:34 8501 8192000000 10216159184 10636513280 1330452368 13.971 100 18 36 0 1340249760 5938560 3858832
14:26:43 5534 8192000000 10217752800 10644013056 1334946576 14.012 100 20 42 0 1345336704 5785968 4604160
14:27:49 11884 8192000000 10208342496 10631036928 1316184512 13.842 99 12 25 0 1324425584 5337584 2903488
14:29:02 28579 8192000000 10210703600 10605211648 1300154432 13.697 99 58 115 0 1320301392 6513424 13633536
14:30:31 4711 8192000000 10174504656 10574225408 1255780816 13.291 98 39 73 0 1271033968 6830816 8422336
14:31:36 5937 8192000000 10135929776 10539249664 1240628128 13.152 99 33 66 0 1253705776 5280800 7796848
14:32:45 24368 8192000000 10157864496 10568187904 1257908592 13.311 99 30 60 0 1271955968 5873008 8174368
14:34:42 6146 8192000000 10169657872 10570784768 1273768640 13.456 100 38 75 1 1288666448 6868624 8029184
14:35:52 8008 8192000000 10180794640 10592288768 1288543536 13.591 100 21 42 1 1299466576 5966880 4956160
14:37:01 8521 8192000000 10193098656 10610507776 1304337888 13.735 100 16 30 0 1317068624 9566336 3164400
14:38:13 62307 8192000000 10227298992 10637901824 1317213552 13.851 100 35 67 1 1330146352 4924464 8008336
14:40:21 6333 8192000000 10258511520 10669465600 1350141280 14.149 100 51 100 0 1371457920 9526144 11790496
14:41:31 12008 8192000000 10270069936 10692481024 1366550784 14.296 100 41 79 1 1384913072 9456752 8905536
14:42:48 18118 8192000000 10296503120 10717683712 1386184448 14.472 100 47 94 0 1408572416 11432432 10955536
14:44:13 11354 8192000000 10298430832 10725167104 1392033504 14.524 100 44 86 0 1414746816 12173568 10539744
14:45:32 22547 8192000000 10328025904 10752344064 1402643888 14.619 100 57 114 0 1435024960 19436288 12944784
14:46:56 9165 8192000000 10327770304 10756841472 1407831744 14.665 100 49 98 1 1437315664 17601616 11882304
14:48:12 2067 8192000000 10329366032 10749489152 1397593024 14.574 100 74 145 0 1437288384 22725328 16970032
14:49:19 9345 8192000000 10325135504 10757238784 1402074480 14.613 100 40 80 0 1432796240 20733408 9988352
14:50:33 6546 8192000000 10318678272 10744188928 1395239616 14.553 100 46 92 1 1426461440 20288768 10933056
14:51:45 1861 8192000000 10338586656 10749022208 1397799488 14.575 100 92 180 1 1440766288 22051024 20915776
14:52:54 5567 8192000000 10294542992 10709610496 1342302928 14.078 96 56 111 1 1377325472 21600112 13422432
14:54:07 1947 8192000000 10330389280 10746773504 1392259904 14.526 100 64 127 1 1433598928 25587680 15751344
14:55:16 3269 8192000000 10340941376 10747699200 1388730480 14.495 100 80 158 0 1438789792 30239216 19820096
14:56:23 1978 8192000000 10331588240 10743078912 1382192016 14.436 100 75 147 0 1430649440 31155008 17302416
14:57:35 1737 8192000000 10299558288 10703728640 1338583504 14.045 99 74 149 2 1395503264 36724080 20195680
14:58:41 2944 8192000000 10293105504 10704220160 1344621904 14.099 100 54 107 0 1395351568 37145952 13583712
14:59:56 20744 8192000000 10306943696 10726494208 1357704736 14.217 99 42 84 0 1402364944 33586160 11074048
15:01:19 16265 8192000000 10312933568 10736852992 1375223376 14.374 100 35 69 0 1417310688 33111040 8976272
15:02:37 13976 8192000000 10304283712 10737020928 1369840240 14.326 100 23 46 0 1414675456 37868448 6966768
15:03:55 27414 8192000000 10295493472 10735722496 1354893312 14.191 100 20 40 0 1409060352 48294976 5872064
15:05:24 30210 8192000000 10281519648 10714988544 1329357424 13.961 99 14 28 0 1378552960 45045648 4149888
15:06:55 32391 8192000000 10264283456 10703417344 1358950720 14.228 100 20 42 0 1372712576 9743520 4018336
15:08:28 10687 8192000000 10260144992 10678288384 1344482288 14.098 99 40 74 0 1359915552 7687936 7745328
15:09:46 63570 8192000000 10272440688 10712317952 1354752624 14.190 99 27 54 0 1368509360 7757168 5999568
15:11:57 24299 8192000000 10286579520 10728083456 1367308208 14.303 99 52 103 0 1388935184 10339696 11287280
15:13:31 36388 8192000000 10290029920 10712862720 1346322064 14.114 97 33 64 2 1362525296 9056976 7146256
15:15:08 27493 8192000000 10314337152 10748592128 1382109408 14.435 99 36 72 0 1405313888 14107824 9096656
15:16:45 19388 8192000000 10322655952 10766614528 1395706032 14.557 100 27 51 1 1418623728 16370368 6547328
15:18:09 102206 8192000000 10323547344 10774990848 1405352928 14.643 100 15 30 0 1429932736 20853488 3726320
15:20:57 59733 8192000000 10309613680 10772946944 1390226000 14.508 99 21 42 0 1420554144 24076000 6252144
15:23:05 34688 8192000000 10322657520 10774286336 1407371136 14.661 100 31 58 0 1422729984 8917760 6441088
15:24:40 70415 8192000000 10303978928 10770018304 1352528288 14.170 96 5 10 0 1370355648 16799264 1028096
15:26:58 67371 8192000000 10300701728 10785558528 1396269600 14.562 100 7 14 1 1422744752 24653152 1822000
15:29:44 52376 8192000000 10323278944 10778349568 1406997968 14.657 100 19 39 0 1427461936 16149808 4314160
15:31:45 109667 8192000000 10276599264 10743648256 1310948656 13.795 93 12 25 0 1331949040 18119120 2881264
15:34:35 10007 8192000000 10300437504 10745868288 1378598448 14.404 99 47 94 1 1399201168 10418064 10184656
15:35:48 23050 8192000000 10309329616 10754424832 1364173184 14.275 98 46 93 0 1390254768 16028784 10052800
15:37:15 23386 8192000000 10290120896 10756227072 1357183648 14.212 98 10 20 0 1375865568 16057712 2624208
15:38:38 76206 8192000000 10297511440 10748628992 1347020560 14.121 97 23 44 0 1361344112 10220448 4103104
15:40:57 89863 8192000000 10272362080 10724777984 1345686464 14.109 98 14 28 0 1357749744 9047328 3015952
15:43:41 16055 8192000000 10284928160 10737623040 1371466880 14.340 100 29 59 0 1391058784 12976304 6615600
15:45:29 55651 8192000000 10261082912 10722631680 1354207904 14.185 99 10 20 0 1366445152 9974448 2262800
15:47:25 63472 8192000000 10268976816 10722103296 1349867824 14.146 99 15 30 1 1363158736 10071440 3219472
15:50:47 26895 8192000000 10275305792 10735230976 1360504384 14.242 100 11 21 0 1380254064 16969776 2779904
15:52:15 28749 8192000000 10291745904 10733985792 1374488448 14.367 100 19 36 0 1388641968 9938416 4215104
15:53:44 49849 8192000000 10262557248 10718715904 1326197664 13.933 97 14 28 0 1344783536 15563616 3022256
15:55:36 67905 8192000000 10269666208 10724581376 1336558000 14.026 98 20 38 0 1355846288 15026000 4262288
15:57:47 86270 8192000000 10280789728 10734686208 1351746240 14.163 98 20 40 0 1373307520 16994288 4566992
16:00:43 36607 8192000000 10295689408 10750205952 1358920368 14.228 99 22 43 0 1378203936 14738640 4544928
16:02:29 131960 8192000000 10294025120 10749661184 1378792944 14.406 100 20 40 1 1399713392 16771136 4149312
16:05:41 39448 8192000000 10297517920 10760167424 1388663296 14.494 100 27 54 0 1408224832 13088320 6473216
16:07:43 70238 8192000000 10295686480 10766475264 1398184240 14.579 100 11 22 0 1412701920 11917232 2600448
16:09:56 45714 8192000000 10280596080 10749345792 1369269440 14.321 98 13 26 0 1378910048 6947680 2692928
16:11:42 62994 8192000000 10296140400 10761506816 1381030592 14.426 99 7 14 0 1390638784 7788448 1819744
16:13:47 42714 8192000000 10304567280 10770100224 1389940784 14.505 99 20 38 1 1405091648 10224720 4926144
16:15:30 53425 8192000000 10315010320 10768769024 1378111584 14.400 98 30 60 1 1393227248 8377136 6738528
16:17:24 52648 8192000000 10300096640 10754576384 1375673856 14.378 98 13 26 0 1387676944 9569984 2433104
16:19:18 49069 8192000000 10301241360 10742882304 1295115648 13.651 93 16 32 0 1307379712 8676640 3587424
16:21:26 64117 8192000000 10291877168 10728632320 1320399472 13.880 96 37 74 0 1340755968 11824672 8531824
16:23:37 82295 8192000000 10272411504 10737397760 1315836912 13.839 96 9 18 0 1334468304 16518720 2112672
16:26:05 37682 8192000000 10267646448 10719539200 1336868192 14.029 99 19 38 0 1358357488 17168784 4320512
16:27:53 69308 8192000000 10249246592 10699759616 1321256048 13.888 97 26 51 1 1335958000 9061232 5640720
16:30:38 38770 8192000000 10254617376 10704064512 1351190000 14.158 100 21 43 1 1364210992 7452736 5568256
16:33:07 44578 8192000000 10232861184 10684211200 1322106496 13.896 97 22 44 0 1334016224 7044640 4865088
16:34:55 62242 8192000000 10241609120 10703699968 1326507264 13.936 98 10 21 0 1339500592 10412624 2580704
16:36:57 40341 8192000000 10228712256 10678673408 1278234032 13.497 95 16 32 0 1288798304 7291536 3272736

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-12-2022 16:40:04 to 13-12-2022 16:40:19.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 13-12-2022 16:40:20 to 13-12-2022 16:40:22.

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          243785
MON$OLDEST_ACTIVE               246156
MON$OLDEST_SNAPSHOT             246156
MON$NEXT_TRANSACTION            246156
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                       2037472
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.33647 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33647 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33647 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33647 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 ---
:226: 
:227: mscs-test-161.budzdorov.ru    Tue Dec 13 16:38:30 2022
:228:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:229: 
:230: 
:231: mscs-test-161.budzdorov.ru    Tue Dec 13 16:38:34 2022
:232:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:233: 
:234: 
:235: mscs-test-161.budzdorov.ru    Tue Dec 13 16:38:35 2022
:236:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:237: 
:238: 
:239: mscs-test-161.budzdorov.ru    Tue Dec 13 16:38:35 2022
:240:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:241: 
--- end of diff output ---

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

13.12.22 16:40:23. 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-12-13 16:40:26 No severe PSQL-related problems occured
13.12.22 16:40:27. End of report.