Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 18.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 14772204 kB
MemAvailable 30043624 kB
Buffers 0 kB
Cached 16902272 kB
SwapCached 3412 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-18 16:38:31
DTS_END                         2022-12-18 16:38:31.7980
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/53488
TRN_ID                          239029
ATT_ID                          649
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: 9556.62 25298 1146794
customer order: creation 169.32 368 20318
customer order: refuse 14.79 348 1775
order to supplier: creation 45.02 1245 5402
order to supplier: removal 3.73 102325 447
invoice (draft): creation 45.50 3138 5460
invoice (draft): removal 55.93 733 6712
invoice accept: apply 42.29 18450 5075
invoice accept: cancel 50.37 729 6044
customer reserve: creation 3915.63 183 469876
customer reserve: removal 4912.43 62 589491
realization accept: apply 84.33 196 10119
realization accept: cancel 89.85 99 10782
payment from customer: creation 57.25 101 6870
payment from customer: removal 5.54 86 665
payment to supplier: creation 48.98 114 5877
payment to supplier: removal 3.73 92 448
service: total inventory turnovers 4.38 1196 526
service: total monetary turnovers 6.73 46 807
service: aggregate perf. data 0.80 21624 96
service: refresh index statistics 0.03 354830 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 18-12-2022 16:39:36 to 18-12-2022 16:39:39.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2120
TEST_TIME 2 4420
TEST_TIME 3 3857
TEST_TIME 4 4165
TEST_TIME 5 5094
TEST_TIME 6 4955
TEST_TIME 7 5506
TEST_TIME 8 5372
TEST_TIME 9 5545
TEST_TIME 10 5535
TEST_TIME 11 5676
TEST_TIME 12 5979
TEST_TIME 13 6384
TEST_TIME 14 6693
TEST_TIME 15 6706
TEST_TIME 16 6810
TEST_TIME 17 6961
TEST_TIME 18 7205
TEST_TIME 19 7557
TEST_TIME 20 7731
TEST_TIME 21 8100
TEST_TIME 22 8470
TEST_TIME 23 8706
TEST_TIME 24 8828
TEST_TIME 25 8906
TEST_TIME 26 9032
TEST_TIME 27 9302
TEST_TIME 28 9692
TEST_TIME 29 9736
TEST_TIME 30 9977
TEST_TIME 31 9850
TEST_TIME 32 10883
TEST_TIME 33 10873
TEST_TIME 34 10818
TEST_TIME 35 10672
TEST_TIME 36 10686
TEST_TIME 37 10739
TEST_TIME 38 10607
TEST_TIME 39 10473
TEST_TIME 40 10277
TEST_TIME 41 10233
TEST_TIME 42 10034
TEST_TIME 43 10070
TEST_TIME 44 10032
TEST_TIME 45 10042
TEST_TIME 46 9890
TEST_TIME 47 9862
TEST_TIME 48 9938
TEST_TIME 49 9918
TEST_TIME 50 10120
TEST_TIME 51 10249
TEST_TIME 52 10140
TEST_TIME 53 10079
TEST_TIME 54 9985
TEST_TIME 55 9948
TEST_TIME 56 9968
TEST_TIME 57 9934
TEST_TIME 58 9857
TEST_TIME 59 9861
TEST_TIME 60 9813
TEST_TIME 61 9728
TEST_TIME 62 9644
TEST_TIME 63 9752
TEST_TIME 64 9782
TEST_TIME 65 9804
TEST_TIME 66 9874
TEST_TIME 67 9833
TEST_TIME 68 9897
TEST_TIME 69 9907
TEST_TIME 70 9876
TEST_TIME 71 9896
TEST_TIME 72 9871
TEST_TIME 73 9921
TEST_TIME 74 9925
TEST_TIME 75 10043
TEST_TIME 76 10054
TEST_TIME 77 9943
TEST_TIME 78 9940
TEST_TIME 79 9901
TEST_TIME 80 9831
TEST_TIME 81 9817
TEST_TIME 82 9770
TEST_TIME 83 9771
TEST_TIME 84 9761
TEST_TIME 85 9682
TEST_TIME 86 9658
TEST_TIME 87 9590
TEST_TIME 88 9557
TEST_TIME 89 9479
TEST_TIME 90 9492
TEST_TIME 91 9617
TEST_TIME 92 9617
TEST_TIME 93 9610
TEST_TIME 94 9608
TEST_TIME 95 9707
TEST_TIME 96 9689
TEST_TIME 97 9683
TEST_TIME 98 9712
TEST_TIME 99 9669
TEST_TIME 100 9731
TEST_TIME 101 9726
TEST_TIME 102 9762
TEST_TIME 103 9737
TEST_TIME 104 9752
TEST_TIME 105 9676
TEST_TIME 106 9682
TEST_TIME 107 9635
TEST_TIME 108 9621
TEST_TIME 109 9584
TEST_TIME 110 9548
TEST_TIME 111 9577
TEST_TIME 112 9546
TEST_TIME 113 9528
TEST_TIME 114 9512
TEST_TIME 115 9558
TEST_TIME 116 9529
TEST_TIME 117 9507
TEST_TIME 118 9554
TEST_TIME 119 9629
TEST_TIME 120 9562

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 18-12-2022 16:39:40 to 18-12-2022 16:39:44.

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 1582312 1582312 0 0.00 0 12610 6 0 0
doc_list_biud 1582312 1582312 0 0.00 0 87162 29 0 0
sp_add_invoice_to_stock 5209 5075 134 2.57 23 193738 18450 0 134
sp_cancel_adding_invoice 6044 6044 0 0.00 4 86323 730 0 0
sp_cancel_client_order 1775 1775 0 0.00 4 12975 348 0 0
sp_cancel_customer_reserve 589491 589491 0 0.00 0 24760 62 0 0
sp_cancel_pay_from_customer 665 665 0 0.00 2 7423 87 0 0
sp_cancel_pay_to_supplier 448 448 0 0.00 3 5772 92 0 0
sp_cancel_supplier_invoice 6721 6712 9 0.13 1 87179 733 0 9
sp_cancel_supplier_order 447 447 0 0.00 5 1172288 102325 0 0
sp_cancel_write_off 10782 10782 0 0.00 1 8172 99 0 0
sp_client_order 20318 20318 0 0.00 3 24823 368 0 0
sp_customer_reserve 469876 469876 0 0.00 0 149326 183 0 0
sp_fill_shopping_cart 31175 31175 0 0.00 1 16776 34 0 0
sp_fill_shopping_cart_clo_res 469868 469868 0 0.00 0 18057 13 0 0
sp_get_clo_for_invoice 5067 5067 0 0.00 0 15730 269 0 0
sp_kill_cost_storno 17939 17939 0 0.00 0 7419 53 0 0
sp_kill_qstorno_ret_qs2qd 1196923 1196923 0 0.00 0 87159 33 0 0
sp_kill_qty_storno 630437 630437 0 0.00 0 87161 72 0 0
sp_lock_selected_doc 20951 20951 0 0.00 0 6374 4 0 0
sp_make_cost_storno 27933 27933 0 0.00 0 12598 52 0 0
sp_multiply_rows_for_pdistr 27933 27933 0 0.00 0 6253 26 0 0
sp_multiply_rows_for_qdistr 20318 20318 0 0.00 0 9794 168 0 0
sp_pay_from_customer 6870 6870 0 0.00 4 10618 102 0 0
sp_pay_to_supplier 5877 5877 0 0.00 5 9336 115 0 0
sp_payment_common 12747 12747 0 0.00 1 10613 21 0 0
sp_qd_handle_on_cancel_clo 1775 1775 0 0.00 0 12967 317 0 0
sp_qd_handle_on_invoice_upd_sts 11111 11111 0 0.00 0 19796 345 0 0
sp_qd_handle_on_reserve_upd_sts 10119 10119 0 0.00 0 24724 79 0 0
sp_reserve_write_off 10119 10119 0 0.00 6 24795 197 0 0
sp_supplier_invoice 5460 5460 0 0.00 6 84218 3138 0 0
sp_supplier_order 5402 5402 0 0.00 5 34947 1245 0 0
srv_aggregate_perf_data 96 96 0 0.00 2000 535306 21624 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 2407 163214 43595 0 0
srv_find_qd_qs_mism 1099448 1099448 0 0.00 0 11858 5 0 0
srv_make_invnt_saldo 526 526 0 0.00 31 71273 1197 0 0
srv_make_money_saldo 807 807 0 0.00 1 11742 46 0 0
srv_recalc_idx_stat 4 4 0 0.00 168662 467011 354830 0 0
t$perf-abend:sp_add_invoice_to_stock 134 134 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 5075 5075 0 0.00 0 9464 195 0 0
t$perf-norm:sp_cancel_adding_invoice 384 384 0 0.00 0 5297 101 0 0
t$perf-norm:sp_cancel_client_order 1775 1775 0 0.00 0 7115 16 0 0
t$perf-norm:sp_cancel_customer_reserve 698 698 0 0.00 0 1271 8 0 0
t$perf-norm:sp_cancel_pay_from_customer 665 665 0 0.00 0 2557 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 448 448 0 0.00 0 3581 19 0 0
t$perf-norm:sp_cancel_supplier_invoice 413 413 0 0.00 0 3260 20 0 0
t$perf-norm:sp_cancel_supplier_order 447 447 0 0.00 0 23330 1664 0 0
t$perf-norm:sp_cancel_write_off 935 935 0 0.00 0 6134 20 0 0
t$perf-norm:sp_client_order 20318 20318 0 0.00 0 7804 20 0 0
t$perf-norm:sp_customer_reserve 1015 1015 0 0.00 0 1034 5 0 0
t$perf-norm:sp_pay_from_customer 6870 6870 0 0.00 0 8853 19 0 0
t$perf-norm:sp_pay_to_supplier 5877 5877 0 0.00 0 6866 12 0 0
t$perf-norm:sp_reserve_write_off 10119 10119 0 0.00 0 7264 13 0 0
t$perf-norm:sp_supplier_invoice 5460 5460 0 0.00 0 6929 19 0 0
t$perf-norm:sp_supplier_order 5402 5402 0 0.00 0 8539 21 0 0
t$perf-norm:srv_aggregate_perf_data 96 96 0 0.00 0 432 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 269 6 0 0
t$perf-norm:srv_make_invnt_saldo 526 526 0 0.00 0 1592 22 0 0
t$perf-norm:srv_make_money_saldo 807 807 0 0.00 0 3663 28 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 6 4 0 0
v_add_invoice_to_stock 10402 10134 268 2.57 0 4757 19 0 268
v_all_customers 36950 36950 0 0.00 0 42 1 0 0
v_all_suppliers 30026 30026 0 0.00 0 149 1 0 0
v_all_wares 40636 40636 0 0.00 0 76 1 0 0
v_cancel_adding_invoice 768 768 0 0.00 0 3021 9 0 0
v_cancel_client_order 3550 3550 0 0.00 0 4441 8 0 0
v_cancel_customer_prepayment 1330 1330 0 0.00 0 3329 6 0 0
v_cancel_customer_reserve 1396 1396 0 0.00 0 1277 5 0 0
v_cancel_payment_to_supplier 896 896 0 0.00 0 4593 11 0 0
v_cancel_supplier_invoice 844 826 18 2.13 0 1582 16 0 18
v_cancel_supplier_order 894 894 0 0.00 0 331 4 0 0
v_cancel_write_off 1870 1870 0 0.00 0 2376 15 0 0
v_max_id_clo_ord 5402 5402 0 0.00 0 506 1 0 0
v_max_id_clo_res 1015 1015 0 0.00 0 3880 15 0 0
v_max_id_ord_sup 5455 5455 0 0.00 0 4052 4 0 0
v_max_non_paid_invoice 1721 1721 0 0.00 0 423 3 0 0
v_max_non_paid_realizn 3586 3586 0 0.00 0 735 4 0 0
v_min_id_clo_ord 5402 5402 0 0.00 0 4455 8 0 0
v_min_id_clo_res 1015 1015 0 0.00 0 5365 19 0 0
v_min_id_ord_sup 5455 5455 0 0.00 0 12234 10 0 0
v_min_non_paid_invoice 5877 5877 0 0.00 0 5895 14 0 0
v_min_non_paid_realizn 6870 6870 0 0.00 0 10606 15 0 0
v_our_firm 10254 10254 0 0.00 0 24 1 0 0
v_reserve_write_off 20238 20238 0 0.00 0 3879 7 0 0
x_lock_depdocs_on_canc_invoice 12343 12343 0 0.00 0 11195 116 0 0
x_lock_depdocs_on_canc_sup_ord 447 447 0 0.00 0 3938 316 0 0
x_make_qstorno_1000_1200 5402 5402 0 0.00 1 34891 1169 0 0
x_make_qstorno_1200_2000 5460 5460 0 0.00 3 68531 3025 0 0
x_make_qstorno_2100_3300 469876 469876 0 0.00 0 149324 167 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 18-12-2022 16:39:45 to 18-12-2022 16:39:49.

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:35 2 8192000000 8797934432 8808988672 4622400 0.056 2 1 2 0 4721888 56704 42784
14:09:36 24800 8192000000 9901769728 10213289984 985242752 10.735 100 86 172 0 1013920976 5785728 22892496
14:11:04 17205 8192000000 9962487552 10287206400 1038879360 11.254 100 87 174 1 1069728496 8178528 22670608
14:12:30 10037 8192000000 9989033760 10322116608 1067055984 11.524 100 85 170 0 1098237728 9874864 21306880
14:13:48 7675 8192000000 10030514960 10379812864 1116138224 11.990 100 75 150 0 1143763696 10647616 16977856
14:15:01 4803 8192000000 10081635200 10440765440 1164223600 12.443 100 77 154 0 1194836640 12189024 18424016
14:16:10 2431 8192000000 10125427200 10495070208 1210747456 12.876 100 80 158 0 1242319408 13578672 17993280
14:17:16 2412 8192000000 10163559168 10536710144 1238908144 13.136 100 85 170 0 1271440048 13703072 18828832
14:18:20 1538 8192000000 10192703856 10572410880 1266117136 13.386 100 81 162 0 1297040096 12065456 18857504
14:19:23 1117 8192000000 10205310640 10593796096 1284818784 13.557 100 81 161 0 1314711008 12130624 17761600
14:20:26 15769 8192000000 10217557232 10610794496 1295999600 13.659 100 69 135 3 1322819248 12107312 14712336
14:21:43 2995 8192000000 10201146720 10615832576 1304563584 13.737 100 36 70 0 1319378720 6841552 7973584
14:22:48 8945 8192000000 10205805712 10628116480 1315598336 13.837 100 18 36 0 1325901296 6293664 4009296
14:23:58 7541 8192000000 10227698192 10636079104 1320780128 13.884 100 19 37 0 1330672416 5762960 4129328
14:25:06 26953 8192000000 10229335664 10656952320 1325969584 13.931 100 15 30 0 1339288720 9149184 4169952
14:26:34 4961 8192000000 10230607136 10653134848 1327743280 13.947 100 29 58 0 1340696256 5946992 7005984
14:27:41 8686 8192000000 10226588352 10650333184 1324458848 13.917 100 17 34 0 1334449264 5681136 4309280
14:28:50 58536 8192000000 10188944032 10607853568 1232236080 13.075 94 23 46 0 1246493776 9112832 5144864
14:30:51 6590 8192000000 10162349856 10566778880 1237447104 13.123 98 29 58 1 1249530080 5983744 6099232
14:32:00 10058 8192000000 10133911504 10533765120 1200365152 12.780 96 24 48 0 1211328208 4940160 6022896
14:33:11 8834 8192000000 10151512000 10548793344 1229766768 13.052 97 35 70 4 1243637776 6386336 7484672
14:34:21 32844 8192000000 10157685792 10571735040 1270541840 13.427 100 18 36 0 1279939936 5375776 4022320
14:35:55 10282 8192000000 10182109440 10593189888 1278145952 13.496 99 19 39 2 1288269952 5203168 4920832
14:37:05 5455 8192000000 10196726896 10608766976 1300608672 13.701 100 25 50 1 1311988688 5377504 6002512
14:38:15 84015 8192000000 10229072864 10640965632 1323840512 13.911 100 34 66 1 1336928224 5931328 7156384
14:40:59 23877 8192000000 10245711072 10666287104 1347941216 14.129 100 30 58 1 1361551312 6756784 6853312
14:42:36 19812 8192000000 10278677664 10695843840 1363531552 14.269 100 52 104 2 1388711776 13542032 11638192
14:44:03 17761 8192000000 10286463328 10709901312 1375011904 14.372 100 47 93 0 1399503952 13678480 10813568
14:45:31 2545 8192000000 10302441504 10727735296 1391560128 14.520 100 52 102 1 1417945456 13828384 12556944
14:46:36 6155 8192000000 10313525824 10738065408 1397507728 14.573 100 55 110 1 1427315792 16673392 13134672
14:47:44 15023 8192000000 10328925264 10756726784 1397734064 14.575 99 37 71 0 1429475056 23225552 8515440
14:49:09 9212 8192000000 10336103248 10763022336 1412564208 14.707 100 46 92 1 1447620112 23947744 11108160
14:50:30 6887 8192000000 10331926160 10756395008 1403903360 14.630 100 56 111 0 1444058256 25377952 14776944
14:51:42 2404 8192000000 10351642000 10772795392 1409267856 14.677 100 75 150 0 1460140256 32767808 18104592
14:52:52 5408 8192000000 10334993328 10763575296 1408918256 14.674 100 48 96 0 1448613312 27998576 11696480
14:54:01 14869 8192000000 10338652672 10759483392 1396704592 14.566 100 57 115 1 1447934848 36719184 14511072
14:55:28 4346 8192000000 10358884688 10777976832 1411530880 14.698 100 73 145 1 1466843840 36535840 18777120
14:56:56 2808 8192000000 10339096448 10746355712 1379248096 14.410 100 75 151 0 1439754768 41742896 18763776
14:58:03 2534 8192000000 10339998768 10750820352 1389819088 14.504 100 68 133 0 1446992800 39965056 17208656
14:59:12 2662 8192000000 10367288416 10773217280 1388853824 14.496 100 85 171 1 1462048176 51575520 21618832
15:00:19 3699 8192000000 10340631440 10760699904 1386117008 14.471 100 54 109 1 1445319616 44409280 14793328
15:01:32 7746 8192000000 10339165216 10771263488 1391680688 14.521 100 39 78 0 1449326912 46937472 10708752
15:02:46 8392 8192000000 10355725728 10781757440 1394021296 14.542 99 42 81 1 1451309232 46370704 10917232
15:03:59 5723 8192000000 10352417376 10768490496 1385420048 14.465 100 49 98 0 1449728672 50913120 13395504
15:05:10 5517 8192000000 10330712576 10753851392 1365114288 14.283 100 46 90 0 1434791888 56922272 12755328
15:06:23 34234 8192000000 10302863040 10737815552 1355684624 14.199 99 32 63 0 1413655392 49195696 8775072
15:08:06 13621 8192000000 10317328160 10754658304 1363040896 14.265 99 33 65 0 1414682016 43205376 8435744
15:09:28 25759 8192000000 10316009264 10759397376 1367637584 14.306 100 16 32 0 1424916624 52474368 4804672
15:10:59 117907 8192000000 10269002368 10717712384 1274132208 13.459 93 7 14 0 1294105040 18508064 1464768
15:13:59 60211 8192000000 10299872608 10746265600 1364019296 14.273 99 7 14 1 1385015712 19666672 1329744
15:16:03 69294 8192000000 10314111680 10750742528 1389225232 14.499 100 35 70 1 1410571952 12655616 8691104
15:18:14 33712 8192000000 10320996432 10754813952 1399228112 14.588 100 59 118 2 1424414032 11477424 13708496
15:20:12 68846 8192000000 10317591792 10769555456 1396979152 14.568 100 38 75 0 1417803440 12306464 8517824
15:22:26 55536 8192000000 10319023104 10773086208 1397565424 14.573 99 38 76 0 1421526768 15902608 8058736
15:24:23 109021 8192000000 10338252320 10788790272 1404139840 14.632 99 32 64 0 1432011312 20210496 7660976
15:27:13 26114 8192000000 10358457008 10809114624 1424640160 14.814 100 37 74 0 1451836608 18019088 9177360
15:28:43 45338 8192000000 10321477168 10798284800 1405612560 14.645 99 26 52 1 1435331168 23509168 6209440
15:30:30 163191 8192000000 10323248432 10790952960 1389954336 14.505 97 19 38 1 1405392800 10791056 4647408
15:34:13 49117 8192000000 10300939424 10767577088 1384332832 14.455 98 7 14 0 1395585408 9766896 1485680
15:36:02 21093 8192000000 10316293872 10779926528 1408316960 14.669 100 12 24 0 1423913680 12685616 2911104
15:37:24 64277 8192000000 10293233472 10749050880 1367944992 14.309 98 18 36 0 1385189856 12799584 4445280
15:39:38 68469 8192000000 10310801456 10779504640 1397290176 14.571 99 14 28 0 1409816112 9460144 3065792
15:41:48 58490 8192000000 10287790800 10751229952 1343299584 14.087 96 12 24 0 1354623008 8757024 2566400
15:44:15 61090 8192000000 10301233088 10771787776 1394177936 14.543 100 17 34 0 1409859248 11648480 4032832
15:47:07 54134 8192000000 10269673136 10725642240 1324626128 13.919 96 10 20 0 1335876016 9125744 2124144
15:49:07 76983 8192000000 10272799888 10722484224 1304461536 13.736 95 18 36 0 1319510272 11094016 3954720
15:51:24 38018 8192000000 10289744176 10741280768 1324823776 13.920 97 32 64 0 1349989888 18509552 6656560
15:53:05 91638 8192000000 10281843536 10745364480 1312468336 13.808 96 16 33 0 1331304416 15354160 3481920
15:55:39 74342 8192000000 10279815392 10731589632 1368893584 14.317 100 23 46 0 1383328848 8928288 5506976
15:57:58 20468 8192000000 10251224528 10692517888 1338408352 14.043 99 31 63 0 1353828768 8395040 7025376
15:59:19 65301 8192000000 10239683088 10695917568 1295044896 13.650 96 24 48 0 1309885616 9565184 5275536
16:01:24 72169 8192000000 10247545952 10708570112 1345629248 14.108 100 10 20 1 1362480288 14200464 2650576
16:04:24 62686 8192000000 10250656976 10715156480 1343853936 14.092 99 12 22 2 1358203728 10943872 3405920
16:06:28 56091 8192000000 10269450000 10725228544 1365710512 14.289 100 24 48 0 1381113824 10166448 5236864
16:08:28 69449 8192000000 10273143136 10754994176 1370639184 14.333 100 9 18 0 1384685856 12236000 1810672
16:10:41 42605 8192000000 10276126096 10740154368 1367051088 14.301 99 14 28 0 1378690752 8594512 3045152
16:12:24 65272 8192000000 10300195296 10759585792 1376653696 14.387 99 18 36 0 1394263984 13797792 3812496
16:14:33 40361 8192000000 10316078976 10769727488 1397810752 14.575 100 17 34 1 1408641328 6744384 4086192
16:16:13 45432 8192000000 10312891712 10770018304 1386735920 14.477 99 31 61 0 1403684096 10425312 6522864
16:18:01 15473 8192000000 10330235360 10769166336 1388219760 14.490 99 67 135 0 1415001584 11980416 14801408
16:19:20 50164 8192000000 10299763312 10755239936 1368896944 14.317 98 12 24 1 1380443808 9246208 2300656
16:21:10 19558 8192000000 10309904112 10760388608 1381221376 14.427 99 38 73 0 1397578288 8338464 8018448
16:22:33 94337 8192000000 10275089984 10730643456 1329832800 13.966 96 12 24 0 1341870688 9327440 2710448
16:25:07 54788 8192000000 10292165888 10758766592 1389794080 14.504 100 20 40 1 1405112432 10814640 4503712
16:27:04 84795 8192000000 10294196992 10759172096 1385480352 14.466 99 10 18 0 1396896624 9219952 2196320
16:30:08 68093 8192000000 10282201824 10749075456 1374840992 14.370 99 25 50 0 1390003200 9299616 5862592
16:32:26 87291 8192000000 10271024704 10742034432 1328070352 13.950 96 13 26 0 1343815424 12765136 2979936
16:35:03 31980 8192000000 10269931072 10721628160 1319795248 13.875 96 20 40 0 1336718576 12002624 4920704
16:36:47 55476 8192000000 10263612080 10734678016 1329184096 13.960 98 5 10 0 1348458640 18010320 1264224

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 18-12-2022 16:39:51 to 18-12-2022 16:40:06.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 134 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 18-12-2022 16:40:07 to 18-12-2022 16:40:09.

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          237937
MON$OLDEST_ACTIVE               239523
MON$OLDEST_SNAPSHOT             239523
MON$NEXT_TRANSACTION            239523
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                       2052768
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.33648 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33648 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33648 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33648 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 ---
:808: 
:809: mscs-test-161.budzdorov.ru    Sun Dec 18 16:38:32 2022
:810:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:811: 
:812: 
:813: mscs-test-161.budzdorov.ru    Sun Dec 18 16:38:41 2022
:814:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:815: 
--- end of diff output ---

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

18.12.22 16:40:10. 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-18 16:40:13 No severe PSQL-related problems occured
18.12.22 16:40:14. End of report.