Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 15.06.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 16194912 kB
MemAvailable 31257068 kB
Buffers 0 kB
Cached 15502248 kB
SwapCached 2448 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.10
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-06-15 19:38:27
DTS_END                         2022-06-15 19:38:27.7470
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/41630
TRN_ID                          245227
ATT_ID                          735
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: 9555.83 20721 1146699
customer order: creation 176.69 290 21203
customer order: refuse 15.45 372 1854
order to supplier: creation 46.47 1167 5576
order to supplier: removal 3.39 94187 407
invoice (draft): creation 47.13 3024 5655
invoice (draft): removal 55.03 709 6604
invoice accept: apply 42.63 17583 5116
invoice accept: cancel 50.35 774 6042
customer reserve: creation 3959.71 174 475165
customer reserve: removal 4854.18 51 582501
realization accept: apply 84.76 172 10171
realization accept: cancel 86.55 101 10386
payment from customer: creation 61.25 103 7350
payment from customer: removal 5.70 75 684
payment to supplier: creation 50.98 113 6118
payment to supplier: removal 3.51 97 421
service: total inventory turnovers 4.55 1343 546
service: total monetary turnovers 6.57 55 788
service: aggregate perf. data 0.90 20441 108
service: refresh index statistics 0.03 273593 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 15-06-2022 19:39:36 to 15-06-2022 19:39:39.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4233
TEST_TIME 2 5861
TEST_TIME 3 5884
TEST_TIME 4 6332
TEST_TIME 5 5897
TEST_TIME 6 6181
TEST_TIME 7 6058
TEST_TIME 8 6406
TEST_TIME 9 6323
TEST_TIME 10 6531
TEST_TIME 11 6740
TEST_TIME 12 7163
TEST_TIME 13 7240
TEST_TIME 14 7354
TEST_TIME 15 7857
TEST_TIME 16 7711
TEST_TIME 17 7948
TEST_TIME 18 8271
TEST_TIME 19 8308
TEST_TIME 20 8509
TEST_TIME 21 8604
TEST_TIME 22 9116
TEST_TIME 23 9811
TEST_TIME 24 9965
TEST_TIME 25 9749
TEST_TIME 26 10012
TEST_TIME 27 10594
TEST_TIME 28 10804
TEST_TIME 29 10635
TEST_TIME 30 10679
TEST_TIME 31 10680
TEST_TIME 32 10573
TEST_TIME 33 10760
TEST_TIME 34 10712
TEST_TIME 35 10801
TEST_TIME 36 10752
TEST_TIME 37 10777
TEST_TIME 38 10695
TEST_TIME 39 10467
TEST_TIME 40 10225
TEST_TIME 41 10326
TEST_TIME 42 10210
TEST_TIME 43 10198
TEST_TIME 44 10303
TEST_TIME 45 10200
TEST_TIME 46 10102
TEST_TIME 47 10006
TEST_TIME 48 9941
TEST_TIME 49 10047
TEST_TIME 50 9939
TEST_TIME 51 9797
TEST_TIME 52 9949
TEST_TIME 53 9913
TEST_TIME 54 9797
TEST_TIME 55 9735
TEST_TIME 56 9679
TEST_TIME 57 9559
TEST_TIME 58 9569
TEST_TIME 59 9643
TEST_TIME 60 9737
TEST_TIME 61 9807
TEST_TIME 62 9855
TEST_TIME 63 9864
TEST_TIME 64 9932
TEST_TIME 65 9866
TEST_TIME 66 9872
TEST_TIME 67 10099
TEST_TIME 68 10065
TEST_TIME 69 10067
TEST_TIME 70 10076
TEST_TIME 71 10171
TEST_TIME 72 10171
TEST_TIME 73 10136
TEST_TIME 74 10033
TEST_TIME 75 10071
TEST_TIME 76 10023
TEST_TIME 77 9944
TEST_TIME 78 9977
TEST_TIME 79 9978
TEST_TIME 80 9935
TEST_TIME 81 9895
TEST_TIME 82 9917
TEST_TIME 83 9852
TEST_TIME 84 9823
TEST_TIME 85 9799
TEST_TIME 86 9761
TEST_TIME 87 9757
TEST_TIME 88 9698
TEST_TIME 89 9677
TEST_TIME 90 9737
TEST_TIME 91 9713
TEST_TIME 92 9818
TEST_TIME 93 9817
TEST_TIME 94 9809
TEST_TIME 95 9846
TEST_TIME 96 9827
TEST_TIME 97 9979
TEST_TIME 98 9932
TEST_TIME 99 9940
TEST_TIME 100 9949
TEST_TIME 101 9911
TEST_TIME 102 9909
TEST_TIME 103 9842
TEST_TIME 104 9788
TEST_TIME 105 9741
TEST_TIME 106 9791
TEST_TIME 107 9741
TEST_TIME 108 9703
TEST_TIME 109 9682
TEST_TIME 110 9642
TEST_TIME 111 9636
TEST_TIME 112 9624
TEST_TIME 113 9613
TEST_TIME 114 9567
TEST_TIME 115 9548
TEST_TIME 116 9544
TEST_TIME 117 9579
TEST_TIME 118 9579
TEST_TIME 119 9572
TEST_TIME 120 9559

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 15-06-2022 19:39:40 to 15-06-2022 19:39:43.

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 1586269 1586269 0 0.00 0 13099 6 0 0
doc_list_biud 1586269 1586269 0 0.00 0 32833 24 0 0
sp_add_invoice_to_stock 5261 5116 145 2.75 38 166904 17584 0 145
sp_cancel_adding_invoice 6042 6042 0 0.00 3 76416 775 0 0
sp_cancel_client_order 1854 1854 0 0.00 4 15718 373 0 0
sp_cancel_customer_reserve 582501 582501 0 0.00 1 32836 52 0 0
sp_cancel_pay_from_customer 684 684 0 0.00 2 5926 76 0 0
sp_cancel_pay_to_supplier 421 421 0 0.00 3 5619 97 0 0
sp_cancel_supplier_invoice 6620 6604 16 0.24 1 25551 709 0 16
sp_cancel_supplier_order 407 407 0 0.00 5 941187 94187 0 0
sp_cancel_write_off 10386 10386 0 0.00 1 12360 101 0 0
sp_client_order 21203 21203 0 0.00 3 24597 291 0 0
sp_customer_reserve 475165 475165 0 0.00 0 43651 174 0 0
sp_fill_shopping_cart 32429 32429 0 0.00 1 8174 33 0 0
sp_fill_shopping_cart_clo_res 475148 475148 0 0.00 0 30294 12 0 0
sp_get_clo_for_invoice 5099 5099 0 0.00 0 18365 257 0 0
sp_kill_cost_storno 17533 17533 0 0.00 0 9634 51 0 0
sp_kill_qstorno_ret_qs2qd 1182399 1182399 0 0.00 0 32799 28 0 0
sp_kill_qty_storno 623060 623060 0 0.00 0 32833 61 0 0
sp_lock_selected_doc 21239 21239 0 0.00 0 8277 6 0 0
sp_make_cost_storno 28734 28734 0 0.00 0 11708 54 0 0
sp_multiply_rows_for_pdistr 28734 28734 0 0.00 0 11053 31 0 0
sp_multiply_rows_for_qdistr 21203 21203 0 0.00 0 17046 130 0 0
sp_pay_from_customer 7350 7350 0 0.00 4 11440 104 0 0
sp_pay_to_supplier 6118 6118 0 0.00 5 12067 114 0 0
sp_payment_common 13468 13468 0 0.00 1 8692 19 0 0
sp_qd_handle_on_cancel_clo 1854 1854 0 0.00 0 15712 343 0 0
sp_qd_handle_on_invoice_upd_sts 11137 11137 0 0.00 0 30301 314 0 0
sp_qd_handle_on_reserve_upd_sts 10171 10171 0 0.00 0 9577 52 0 0
sp_reserve_write_off 10171 10171 0 0.00 7 13591 173 0 0
sp_supplier_invoice 5655 5655 0 0.00 5 74041 3025 0 0
sp_supplier_order 5576 5576 0 0.00 5 23876 1167 0 0
srv_aggregate_perf_data 108 108 0 0.00 2803 431410 20441 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 2291 152609 45446 0 0
srv_find_qd_qs_mism 1097381 1097381 0 0.00 0 8891 5 0 0
srv_make_invnt_saldo 546 546 0 0.00 61 104351 1343 0 0
srv_make_money_saldo 788 788 0 0.00 0 22256 56 0 0
srv_recalc_idx_stat 4 4 0 0.00 154039 422583 273593 0 0
t$perf-abend:sp_add_invoice_to_stock 145 145 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 16 16 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5116 5116 0 0.00 0 19818 211 0 0
t$perf-norm:sp_cancel_adding_invoice 454 454 0 0.00 0 5111 101 0 0
t$perf-norm:sp_cancel_client_order 1854 1854 0 0.00 0 5889 19 0 0
t$perf-norm:sp_cancel_customer_reserve 717 717 0 0.00 0 4686 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 684 684 0 0.00 0 3857 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 421 421 0 0.00 0 2120 8 0 0
t$perf-norm:sp_cancel_supplier_invoice 411 411 0 0.00 0 3913 19 0 0
t$perf-norm:sp_cancel_supplier_order 407 407 0 0.00 0 47649 2274 0 0
t$perf-norm:sp_cancel_write_off 1025 1025 0 0.00 0 4207 27 0 0
t$perf-norm:sp_client_order 21203 21203 0 0.00 0 10402 24 0 0
t$perf-norm:sp_customer_reserve 1008 1008 0 0.00 0 7434 20 0 0
t$perf-norm:sp_pay_from_customer 7350 7350 0 0.00 0 14763 19 0 0
t$perf-norm:sp_pay_to_supplier 6118 6118 0 0.00 0 9854 16 0 0
t$perf-norm:sp_reserve_write_off 10171 10171 0 0.00 0 9495 17 0 0
t$perf-norm:sp_supplier_invoice 5655 5655 0 0.00 0 13573 25 0 0
t$perf-norm:sp_supplier_order 5576 5576 0 0.00 0 7704 29 0 0
t$perf-norm:srv_aggregate_perf_data 108 108 0 0.00 0 859 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 470 15 0 0
t$perf-norm:srv_make_invnt_saldo 546 546 0 0.00 0 6427 37 0 0
t$perf-norm:srv_make_money_saldo 788 788 0 0.00 0 4225 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 8 4 0 0
v_add_invoice_to_stock 10480 10190 290 2.76 0 3062 15 0 290
v_all_customers 39182 39182 0 0.00 0 20 1 0 0
v_all_suppliers 31488 31488 0 0.00 0 214 1 0 0
v_all_wares 42406 42406 0 0.00 0 20 1 0 0
v_cancel_adding_invoice 908 908 0 0.00 0 190 5 0 0
v_cancel_client_order 3708 3708 0 0.00 0 2962 4 0 0
v_cancel_customer_prepayment 1368 1368 0 0.00 0 1101 5 0 0
v_cancel_customer_reserve 1434 1434 0 0.00 0 408 3 0 0
v_cancel_payment_to_supplier 842 842 0 0.00 0 1541 6 0 0
v_cancel_supplier_invoice 854 822 32 3.74 0 602 11 0 32
v_cancel_supplier_order 814 814 0 0.00 0 910 6 0 0
v_cancel_write_off 2050 2050 0 0.00 0 681 11 0 0
v_max_id_clo_ord 5575 5575 0 0.00 0 431 1 0 0
v_max_id_clo_res 1008 1008 0 0.00 0 3599 12 0 0
v_max_id_ord_sup 5651 5651 0 0.00 0 3828 4 0 0
v_max_non_paid_invoice 1600 1600 0 0.00 0 423 3 0 0
v_max_non_paid_realizn 3714 3714 0 0.00 0 1095 3 0 0
v_min_id_clo_ord 5575 5575 0 0.00 0 2995 6 0 0
v_min_id_clo_res 1008 1008 0 0.00 0 3192 9 0 0
v_min_id_ord_sup 5651 5651 0 0.00 0 4835 4 0 0
v_min_non_paid_invoice 6118 6118 0 0.00 0 8685 13 0 0
v_min_non_paid_realizn 7350 7350 0 0.00 0 6825 13 0 0
v_our_firm 10496 10496 0 0.00 0 20 1 0 0
v_reserve_write_off 20342 20342 0 0.00 0 3402 4 0 0
x_lock_depdocs_on_canc_invoice 12235 12235 0 0.00 0 11032 101 0 0
x_lock_depdocs_on_canc_sup_ord 407 407 0 0.00 0 9933 362 0 0
x_make_qstorno_1000_1200 5576 5576 0 0.00 1 23724 1093 0 0
x_make_qstorno_1200_2000 5655 5655 0 0.00 3 74028 2910 0 0
x_make_qstorno_2100_3300 475165 475165 0 0.00 0 43649 160 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 15-06-2022 19:39:44 to 15-06-2022 19:39:48.

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:31 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:32 24708 8192000000 9903450048 10213847040 975444112 10.640 100 84 168 0 1004204976 6438576 22322288
17:11:04 28420 8192000000 9954134720 10274328576 1027189680 11.141 100 94 188 0 1060350480 8973840 24186960
17:12:37 19745 8192000000 9975601728 10310299648 1064492992 11.499 100 81 162 1 1094560784 10269088 19798704
17:14:08 6972 8192000000 10036815088 10385510400 1121064560 12.037 100 82 163 1 1152535584 12088400 19382624
17:15:17 6554 8192000000 10078067376 10439454720 1170013008 12.497 100 77 154 1 1201024032 13429712 17581312
17:16:25 4989 8192000000 10123177680 10487218176 1209447296 12.864 100 86 172 2 1242270368 14141712 18681360
17:17:33 2725 8192000000 10156147408 10533924864 1246865040 13.209 100 74 146 1 1276640608 12713824 17061744
17:18:37 2079 8192000000 10206026624 10584494080 1290318560 13.607 100 90 180 1 1322821776 12801232 19701984
17:19:41 3975 8192000000 10213324976 10604683264 1302821856 13.721 100 76 151 0 1332555712 13263568 16470288
17:20:54 12757 8192000000 10206584720 10614190080 1316078736 13.841 100 51 100 1 1336315264 8667584 11568944
17:22:10 4713 8192000000 10218238976 10626125824 1321806512 13.893 100 48 96 3 1341086560 8003376 11276672
17:23:16 7839 8192000000 10220972384 10634862592 1329073216 13.959 100 21 41 0 1338868080 5392960 4401904
17:24:25 39640 8192000000 10236317824 10643111936 1331454000 13.980 100 36 72 0 1345091792 4797552 8840240
17:26:14 6035 8192000000 10223337072 10645843968 1337767760 14.037 100 24 48 0 1349318240 6054320 5496160
17:27:21 11257 8192000000 10214993328 10629976064 1315639696 13.837 99 23 46 2 1327283776 6071664 5572416
17:28:34 50239 8192000000 10210289920 10629292032 1282334208 13.534 96 29 58 2 1294269584 5777584 6157792
17:30:26 3871 8192000000 10169412400 10572406784 1272689856 13.446 100 39 78 2 1292485504 11403200 8392448
17:31:30 6193 8192000000 10145852304 10551767040 1261465968 13.343 100 31 62 2 1274621664 5610576 7545120
17:32:41 62952 8192000000 10139596048 10541015040 1226807568 13.025 97 33 66 2 1241077952 6213616 8056768
17:34:49 7911 8192000000 10176482272 10586714112 1284469600 13.554 100 20 40 1 1295061744 6373456 4218688
17:35:59 10880 8192000000 10193363088 10604437504 1298340128 13.680 100 27 54 1 1310879216 6419152 6119936
17:37:10 64031 8192000000 10202128768 10619416576 1309536112 13.782 100 25 50 2 1320243168 5279856 5427200
17:39:22 18260 8192000000 10239062848 10648784896 1335112160 14.013 100 56 112 1 1358111568 10439792 12559616
17:40:46 12834 8192000000 10257600560 10670018560 1341270848 14.069 99 45 90 0 1362413312 10231616 10910848
17:42:07 20192 8192000000 10275763440 10696810496 1365767008 14.289 100 36 72 1 1383283760 8783792 8732960
17:43:35 28398 8192000000 10289622768 10710044672 1376103536 14.382 100 49 99 0 1403924880 16394592 11426752
17:45:25 5857 8192000000 10302232848 10720522240 1385927168 14.470 100 56 112 0 1415389424 15942576 13519680
17:46:36 11824 8192000000 10316693008 10738298880 1395636864 14.556 100 48 95 0 1428923328 21693216 11593248
17:47:52 4098 8192000000 10332472784 10748026880 1399849696 14.594 100 66 133 0 1441059408 25538608 15671104
17:48:59 6532 8192000000 10344892640 10765578240 1405136304 14.641 100 65 129 2 1450217056 28975376 16105376
17:50:14 2289 8192000000 10328411408 10745090048 1386747536 14.477 99 75 150 1 1437438112 32490752 18199824
17:51:20 6952 8192000000 10346680944 10770014208 1406066688 14.649 100 58 116 1 1452580720 32879744 13634288
17:52:32 5147 8192000000 10346501728 10760728576 1398425152 14.581 100 71 141 0 1452024688 36423088 17176448
17:53:41 4943 8192000000 10352378880 10767138816 1401635072 14.610 100 71 138 2 1457664512 39574352 16455088
17:54:50 3237 8192000000 10322242752 10732085248 1367329984 14.303 99 66 132 1 1424292192 40479792 16482416
17:56:01 4167 8192000000 10338994048 10756272128 1372570000 14.350 99 56 112 0 1433820848 46287408 14963440
17:57:12 14002 8192000000 10315499104 10739806208 1361122336 14.247 99 41 82 5 1416567376 43619072 11825968
17:58:47 18248 8192000000 10325782464 10762788864 1380007936 14.417 100 32 64 0 1443563904 54404528 9151440
18:00:20 4677 8192000000 10343522944 10755317760 1361053952 14.247 100 73 146 1 1440594336 60444416 19095968
18:01:28 17796 8192000000 10284015248 10721099776 1341950992 14.075 99 25 50 2 1391717248 43183712 6582544
18:02:55 13426 8192000000 10290988464 10727251968 1365254576 14.285 100 29 57 1 1403472480 30933296 7284608
18:04:25 15791 8192000000 10289708144 10729000960 1356347040 14.205 100 19 38 2 1403526880 41811984 5367856
18:05:41 30054 8192000000 10267159664 10714357760 1337023120 14.031 99 17 34 0 1369226240 28543584 3659536
18:07:48 53866 8192000000 10284688656 10710306816 1341362608 14.070 99 28 56 0 1361772688 14398320 6011760
18:09:49 133127 8192000000 10278957584 10728951808 1343555984 14.089 98 11 21 0 1369923824 24106992 2260848
18:13:03 108487 8192000000 10292272224 10742644736 1352102320 14.166 98 9 19 0 1376873264 23240544 1530400
18:15:54 99240 8192000000 10312649328 10753490944 1373587952 14.359 99 30 61 2 1397904928 17562320 6754656
18:19:02 47895 8192000000 10304624640 10751975424 1391748944 14.521 100 33 66 2 1416080784 16130528 8201312
18:20:59 47276 8192000000 10305344640 10760458240 1390299264 14.509 99 25 50 0 1404809136 8676080 5833792
18:23:04 40172 8192000000 10308613296 10761510912 1391735760 14.521 99 11 23 1 1405437216 11127136 2574320
18:24:45 73074 8192000000 10311728544 10778763264 1414612768 14.725 100 16 32 1 1431729984 12808736 4308480
18:26:59 36374 8192000000 10296495024 10763264000 1392983296 14.532 99 16 32 1 1406823696 9923776 3916624
18:28:36 87576 8192000000 10303699920 10763907072 1397254624 14.571 100 19 38 2 1413155232 10694560 5206048
18:31:04 75662 8192000000 10291212192 10760171520 1358843712 14.227 97 10 20 1 1374672352 13607744 2220896
18:33:22 94149 8192000000 10307825456 10767220736 1388158816 14.489 99 23 46 0 1409397296 15601424 5637056
18:36:04 63710 8192000000 10309216320 10768658432 1400235392 14.597 100 22 44 1 1426342512 20330608 5776512
18:38:09 35055 8192000000 10319462256 10784440320 1406191312 14.650 100 34 68 0 1430802736 16498400 8113024
18:39:45 88378 8192000000 10283071216 10738561024 1337025104 14.031 96 10 20 0 1348914320 9967568 1921648
18:42:14 55052 8192000000 10282044576 10737508352 1309256912 13.779 94 16 33 1 1325428768 12105040 4066816
18:44:13 42789 8192000000 10296354544 10739314688 1359615248 14.234 99 27 55 1 1383846608 17838496 6392864
18:46:07 84741 8192000000 10281406592 10730221568 1330338048 13.970 97 23 46 0 1354477344 18651184 5488112
18:48:36 70417 8192000000 10282655760 10744340480 1369854496 14.326 100 10 20 0 1394228560 21956480 2417584
18:50:47 64404 8192000000 10284983344 10736242688 1352676160 14.172 98 21 42 1 1369417680 11885408 4856112
18:52:51 79904 8192000000 10278289920 10724270080 1364206256 14.275 99 34 68 1 1387924336 15160832 8557248
18:55:13 46325 8192000000 10269850208 10702995456 1336850352 14.029 98 35 70 1 1356469760 11576560 8042848
18:57:03 55994 8192000000 10267428960 10716479488 1324589936 13.918 97 14 28 0 1335069520 7340752 3138832
18:59:01 44328 8192000000 10251902496 10698280960 1314972224 13.831 96 19 38 1 1325790128 6854384 3963520
19:00:46 51143 8192000000 10256343408 10715164672 1353104848 14.175 99 6 12 0 1362399168 8041792 1252528
19:02:37 45576 8192000000 10256643984 10716626944 1341321376 14.069 98 17 33 1 1354907712 8760336 4826000
19:04:32 36829 8192000000 10262937312 10722045952 1369715632 14.324 100 31 62 0 1385661504 8337504 7608368
19:06:35 57345 8192000000 10283015552 10746523648 1368351072 14.312 99 16 32 0 1385948832 13458976 4138784
19:08:39 48537 8192000000 10309469824 10757799936 1366992688 14.300 98 22 44 0 1380643984 8158288 5493008
19:10:28 72079 8192000000 10299868880 10760413184 1397131040 14.569 100 12 24 0 1409194176 9708736 2354400
19:12:41 38879 8192000000 10328461472 10772004864 1404422576 14.634 100 36 71 1 1422900576 10860928 7617072
19:14:21 78668 8192000000 10311255376 10792185856 1372719792 14.351 98 6 12 0 1396563664 22386976 1456896
19:16:40 152609 8192000000 10302013504 10756472832 1335526656 14.017 96 15 30 0 1351951216 13333376 3091184
19:20:19 32137 8192000000 10297158032 10743808000 1359652736 14.234 98 46 92 0 1384282384 14020768 10608880
19:22:50 72627 8192000000 10283566256 10739732480 1351468640 14.161 97 20 39 3 1369700448 13748064 4483744
19:25:14 30338 8192000000 10270935024 10735374336 1355428656 14.196 98 9 17 1 1365805344 8718512 1658176
19:26:46 75151 8192000000 10259435344 10717782016 1292704896 13.629 94 17 34 0 1303680928 7245024 3731008
19:29:26 69753 8192000000 10250942000 10701201408 1323853856 13.912 97 19 36 0 1340854368 13021056 3979456
19:31:36 72294 8192000000 10258930112 10716024832 1350107872 14.148 99 6 13 0 1358529696 7206976 1214848
19:33:52 34601 8192000000 10269005136 10720477184 1367936368 14.309 100 22 44 0 1389645744 16759440 4949936
19:35:26 28660 8192000000 10278201568 10718617600 1347544736 14.125 99 38 77 3 1369426272 12640176 9241360
19:37:02 16080 8192000000 10279208720 10717564928 1360408896 14.241 100 47 94 0 1382145440 11559536 10177008
19:38:20 18493 8192000000 9074289584 9311850496 238166064 2.825 18 2 4 0 243401808 4971152 264592

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 15-06-2022 19:39:50 to 15-06-2022 19:40:05.

Exceptions occured during test was in run

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

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

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          246180
MON$OLDEST_ACTIVE               246181
MON$OLDEST_SNAPSHOT             246181
MON$NEXT_TRANSACTION            246181
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                       2040480
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33602 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33602 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33602 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33602 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 ---
:667: 
:668: mscs-test-161.budzdorov.ru    Wed Jun 15 19:38:44 2022
:669:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:670: 
--- end of diff output ---

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

15.06.22 19:40:09. 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-06-15 19:40:11 No severe PSQL-related problems occured
15.06.22 19:40:13. End of report.