Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 02.02.2023 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 15152892 kB
MemAvailable 30399992 kB
Buffers 0 kB
Cached 16532388 kB
SwapCached 3612 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 2023-02-02 16:38:39
DTS_END                         2023-02-02 16:38:39.1770
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/52874
TRN_ID                          248650
ATT_ID                          729
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: 9672.67 15545 1160720
customer order: creation 183.98 324 22077
customer order: refuse 15.57 360 1868
order to supplier: creation 47.57 1348 5708
order to supplier: removal 3.58 99732 430
invoice (draft): creation 48.61 3207 5833
invoice (draft): removal 55.09 812 6611
invoice accept: apply 44.32 18214 5318
invoice accept: cancel 49.78 771 5974
customer reserve: creation 4109.64 180 493157
customer reserve: removal 4803.91 57 576469
realization accept: apply 88.70 159 10644
realization accept: cancel 83.13 99 9975
payment from customer: creation 62.42 109 7490
payment from customer: removal 6.25 97 750
payment to supplier: creation 53.08 112 6370
payment to supplier: removal 3.74 78 449
service: total inventory turnovers 5.19 1142 623
service: total monetary turnovers 7.10 38 852
service: aggregate perf. data 0.98 16966 118
service: refresh index statistics 0.03 167090 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 02-02-2023 16:39:38 to 02-02-2023 16:39:40.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5707
TEST_TIME 2 4824
TEST_TIME 3 4820
TEST_TIME 4 4721
TEST_TIME 5 4740
TEST_TIME 6 4923
TEST_TIME 7 5705
TEST_TIME 8 5800
TEST_TIME 9 5892
TEST_TIME 10 6087
TEST_TIME 11 6336
TEST_TIME 12 6352
TEST_TIME 13 6429
TEST_TIME 14 6740
TEST_TIME 15 6708
TEST_TIME 16 7456
TEST_TIME 17 7836
TEST_TIME 18 7887
TEST_TIME 19 7943
TEST_TIME 20 8035
TEST_TIME 21 8112
TEST_TIME 22 8115
TEST_TIME 23 8468
TEST_TIME 24 8826
TEST_TIME 25 8940
TEST_TIME 26 8947
TEST_TIME 27 8885
TEST_TIME 28 9618
TEST_TIME 29 9892
TEST_TIME 30 10379
TEST_TIME 31 11282
TEST_TIME 32 11194
TEST_TIME 33 11015
TEST_TIME 34 11117
TEST_TIME 35 10884
TEST_TIME 36 10903
TEST_TIME 37 10707
TEST_TIME 38 10766
TEST_TIME 39 10613
TEST_TIME 40 10494
TEST_TIME 41 10434
TEST_TIME 42 10433
TEST_TIME 43 10256
TEST_TIME 44 10198
TEST_TIME 45 10139
TEST_TIME 46 10067
TEST_TIME 47 10387
TEST_TIME 48 10270
TEST_TIME 49 10203
TEST_TIME 50 10161
TEST_TIME 51 10081
TEST_TIME 52 9941
TEST_TIME 53 9892
TEST_TIME 54 9918
TEST_TIME 55 9869
TEST_TIME 56 9754
TEST_TIME 57 9698
TEST_TIME 58 9677
TEST_TIME 59 9650
TEST_TIME 60 9640
TEST_TIME 61 9699
TEST_TIME 62 9789
TEST_TIME 63 9816
TEST_TIME 64 9782
TEST_TIME 65 9870
TEST_TIME 66 9903
TEST_TIME 67 9922
TEST_TIME 68 9984
TEST_TIME 69 9942
TEST_TIME 70 10055
TEST_TIME 71 10005
TEST_TIME 72 10088
TEST_TIME 73 9996
TEST_TIME 74 9903
TEST_TIME 75 10001
TEST_TIME 76 9928
TEST_TIME 77 9902
TEST_TIME 78 9880
TEST_TIME 79 9837
TEST_TIME 80 9798
TEST_TIME 81 9737
TEST_TIME 82 9740
TEST_TIME 83 9709
TEST_TIME 84 9734
TEST_TIME 85 9727
TEST_TIME 86 9706
TEST_TIME 87 9722
TEST_TIME 88 9787
TEST_TIME 89 9783
TEST_TIME 90 9769
TEST_TIME 91 9825
TEST_TIME 92 9812
TEST_TIME 93 9835
TEST_TIME 94 9861
TEST_TIME 95 9857
TEST_TIME 96 9952
TEST_TIME 97 9997
TEST_TIME 98 9921
TEST_TIME 99 9829
TEST_TIME 100 9819
TEST_TIME 101 9823
TEST_TIME 102 9795
TEST_TIME 103 9763
TEST_TIME 104 9756
TEST_TIME 105 9712
TEST_TIME 106 9702
TEST_TIME 107 9655
TEST_TIME 108 9659
TEST_TIME 109 9671
TEST_TIME 110 9657
TEST_TIME 111 9654
TEST_TIME 112 9692
TEST_TIME 113 9674
TEST_TIME 114 9690
TEST_TIME 115 9691
TEST_TIME 116 9679
TEST_TIME 117 9662
TEST_TIME 118 9682
TEST_TIME 119 9684
TEST_TIME 120 9676

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 02-02-2023 16:39:42 to 02-02-2023 16:39:45.

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 1617763 1617763 0 0.00 0 23572 6 0 0
doc_list_biud 1617763 1617763 0 0.00 0 31392 26 0 0
sp_add_invoice_to_stock 5418 5318 100 1.84 26 202140 18214 0 100
sp_cancel_adding_invoice 5974 5974 0 0.00 3 68439 772 0 0
sp_cancel_client_order 1868 1868 0 0.00 3 13223 361 0 0
sp_cancel_customer_reserve 576469 576469 0 0.00 0 31394 58 0 0
sp_cancel_pay_from_customer 750 750 0 0.00 2 12487 98 0 0
sp_cancel_pay_to_supplier 449 449 0 0.00 3 7209 78 0 0
sp_cancel_supplier_invoice 6620 6611 9 0.13 1 12948 812 0 9
sp_cancel_supplier_order 430 430 0 0.00 6 1206899 99733 0 0
sp_cancel_write_off 9975 9975 0 0.00 1 9976 99 0 0
sp_client_order 22077 22077 0 0.00 3 36153 324 0 0
sp_customer_reserve 493157 493157 0 0.00 0 44410 180 0 0
sp_fill_shopping_cart 33607 33607 0 0.00 1 17187 33 0 0
sp_fill_shopping_cart_clo_res 493154 493154 0 0.00 0 21276 14 0 0
sp_get_clo_for_invoice 5314 5314 0 0.00 0 30337 271 0 0
sp_kill_cost_storno 17148 17148 0 0.00 0 6966 52 0 0
sp_kill_qstorno_ret_qs2qd 1169954 1169954 0 0.00 0 31388 31 0 0
sp_kill_qty_storno 617285 617285 0 0.00 0 31392 68 0 0
sp_lock_selected_doc 22104 22104 0 0.00 0 10635 6 0 0
sp_make_cost_storno 29818 29818 0 0.00 0 23357 46 0 0
sp_multiply_rows_for_pdistr 29818 29818 0 0.00 0 8618 25 0 0
sp_multiply_rows_for_qdistr 22077 22077 0 0.00 0 36145 142 0 0
sp_pay_from_customer 7490 7490 0 0.00 4 14627 109 0 0
sp_pay_to_supplier 6370 6370 0 0.00 5 13478 113 0 0
sp_payment_common 13860 13860 0 0.00 1 13607 24 0 0
sp_qd_handle_on_cancel_clo 1868 1868 0 0.00 0 13216 332 0 0
sp_qd_handle_on_invoice_upd_sts 11288 11288 0 0.00 0 21347 333 0 0
sp_qd_handle_on_reserve_upd_sts 10644 10644 0 0.00 0 18583 50 0 0
sp_reserve_write_off 10644 10644 0 0.00 6 18628 160 0 0
sp_supplier_invoice 5833 5833 0 0.00 5 92296 3208 0 0
sp_supplier_order 5708 5708 0 0.00 6 46449 1349 0 0
srv_aggregate_perf_data 118 118 0 0.00 2735 337931 16966 0 0
srv_fill_mon_cache_memory 68 68 0 0.00 918 123095 39593 0 0
srv_find_qd_qs_mism 1110000 1110000 0 0.00 0 11648 6 0 0
srv_make_invnt_saldo 623 623 0 0.00 27 100482 1143 0 0
srv_make_money_saldo 852 852 0 0.00 1 7846 39 0 0
srv_recalc_idx_stat 4 4 0 0.00 118393 209159 167091 0 0
t$perf-abend:sp_add_invoice_to_stock 100 100 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 5318 5318 0 0.00 0 12805 180 0 0
t$perf-norm:sp_cancel_adding_invoice 453 453 0 0.00 1 5545 107 0 0
t$perf-norm:sp_cancel_client_order 1868 1868 0 0.00 0 9447 22 0 0
t$perf-norm:sp_cancel_customer_reserve 726 726 0 0.00 0 4875 31 0 0
t$perf-norm:sp_cancel_pay_from_customer 750 750 0 0.00 0 1336 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 449 449 0 0.00 0 1402 8 0 0
t$perf-norm:sp_cancel_supplier_invoice 430 430 0 0.00 0 8580 51 0 0
t$perf-norm:sp_cancel_supplier_order 430 430 0 0.00 0 25890 1463 0 0
t$perf-norm:sp_cancel_write_off 1041 1041 0 0.00 0 1934 13 0 0
t$perf-norm:sp_client_order 22077 22077 0 0.00 0 10490 17 0 0
t$perf-norm:sp_customer_reserve 1028 1028 0 0.00 0 7585 19 0 0
t$perf-norm:sp_pay_from_customer 7490 7490 0 0.00 0 4518 10 0 0
t$perf-norm:sp_pay_to_supplier 6370 6370 0 0.00 0 7814 10 0 0
t$perf-norm:sp_reserve_write_off 10644 10644 0 0.00 0 8792 13 0 0
t$perf-norm:sp_supplier_invoice 5833 5833 0 0.00 0 9118 20 0 0
t$perf-norm:sp_supplier_order 5708 5708 0 0.00 0 8664 19 0 0
t$perf-norm:srv_aggregate_perf_data 118 118 0 0.00 0 1121 15 0 0
t$perf-norm:srv_fill_mon_cache_memory 68 68 0 0.00 0 340 8 0 0
t$perf-norm:srv_make_invnt_saldo 623 623 0 0.00 0 847 24 0 0
t$perf-norm:srv_make_money_saldo 852 852 0 0.00 0 1185 19 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 10826 10626 200 1.84 0 12461 22 0 200
v_all_customers 40024 40024 0 0.00 0 20 1 0 0
v_all_suppliers 32290 32290 0 0.00 0 22 1 0 0
v_all_wares 44154 44154 0 0.00 0 48 1 0 0
v_cancel_adding_invoice 906 906 0 0.00 0 311 6 0 0
v_cancel_client_order 3736 3736 0 0.00 0 3613 5 0 0
v_cancel_customer_prepayment 1500 1500 0 0.00 0 12466 15 0 0
v_cancel_customer_reserve 1452 1452 0 0.00 0 3848 8 0 0
v_cancel_payment_to_supplier 898 898 0 0.00 0 3481 15 0 0
v_cancel_supplier_invoice 878 860 18 2.05 0 3144 21 0 18
v_cancel_supplier_order 860 860 0 0.00 0 1875 11 0 0
v_cancel_write_off 2082 2082 0 0.00 0 2052 13 0 0
v_max_id_clo_ord 5707 5707 0 0.00 0 1158 1 0 0
v_max_id_clo_res 1028 1028 0 0.00 0 4478 26 0 0
v_max_id_ord_sup 5823 5823 0 0.00 0 2798 4 0 0
v_max_non_paid_invoice 1755 1755 0 0.00 0 2884 4 0 0
v_max_non_paid_realizn 3999 3999 0 0.00 0 3117 5 0 0
v_min_id_clo_ord 5707 5707 0 0.00 0 1193 4 0 0
v_min_id_clo_res 1028 1028 0 0.00 0 2903 8 0 0
v_min_id_ord_sup 5823 5823 0 0.00 0 5677 8 0 0
v_min_non_paid_invoice 6370 6370 0 0.00 0 6676 14 0 0
v_min_non_paid_realizn 7490 7490 0 0.00 0 13600 19 0 0
v_our_firm 11112 11112 0 0.00 0 13 1 0 0
v_reserve_write_off 21288 21288 0 0.00 0 14885 9 0 0
x_lock_depdocs_on_canc_invoice 12155 12155 0 0.00 0 23398 115 0 0
x_lock_depdocs_on_canc_sup_ord 430 430 0 0.00 0 17870 408 0 0
x_make_qstorno_1000_1200 5708 5708 0 0.00 1 46433 1282 0 0
x_make_qstorno_1200_2000 5833 5833 0 0.00 2 92289 3086 0 0
x_make_qstorno_2100_3300 493157 493157 0 0.00 0 44409 164 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 02-02-2023 16:39:46 to 02-02-2023 16:39:50.

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:43 2 8192000000 8800005040 8811544576 6401568 0.078 2 1 2 0 6501200 56848 42784
14:09:43 12028 8192000000 9895465888 10206081024 985549584 10.738 100 89 178 0 1014701152 6295024 22856544
14:11:02 21471 8192000000 9954200576 10277138432 1036140432 11.228 100 86 172 0 1066554560 8505360 21908768
14:12:27 16355 8192000000 9978843968 10310627328 1063815280 11.493 100 88 176 0 1096260464 10428848 22016336
14:13:46 9522 8192000000 10012193120 10354540544 1100634976 11.844 100 82 164 0 1132357104 12051408 19670720
14:14:59 6735 8192000000 10058808544 10409852928 1144552800 12.258 100 86 172 0 1178317856 13672032 20093024
14:16:08 4031 8192000000 10102541568 10467250176 1188519888 12.670 100 77 149 0 1217531952 11811024 17201040
14:17:14 2781 8192000000 10139235504 10516934656 1237607856 13.124 100 74 144 1 1266423904 12994720 15821328
14:18:18 1129 8192000000 10180333744 10560483328 1265683136 13.382 100 79 152 2 1295707520 13400112 16624272
14:19:21 1208 8192000000 10210146272 10596102144 1298106048 13.678 100 83 163 0 1328306864 11705168 18495648
14:20:25 25072 8192000000 10213429584 10611167232 1311876624 13.803 100 60 121 1 1333726048 6122128 15727296
14:21:51 11365 8192000000 10202797072 10616586240 1315380528 13.835 100 19 37 1 1325879552 5391568 5107456
14:23:03 10829 8192000000 10207065360 10625490944 1323206384 13.906 100 16 32 0 1332286240 5405520 3674336
14:24:15 12906 8192000000 10215125440 10634137600 1328997680 13.958 100 13 26 0 1337728784 5635744 3095360
14:25:30 69723 8192000000 10240779424 10651738112 1340141792 14.059 100 48 94 0 1357121712 5373488 11606432
14:27:41 7700 8192000000 10207261168 10632269824 1313246576 13.816 98 20 40 0 1324364080 6116992 5000512
14:28:50 7539 8192000000 10178583040 10586669056 1244935536 13.192 95 20 40 1 1254773600 5415936 4422128
14:29:58 15992 8192000000 10159269280 10562199552 1209720848 12.867 94 34 61 2 1222105376 4650608 7733920
14:32:05 4251 8192000000 10134280000 10518880256 1221267792 12.973 99 41 82 0 1237730560 7174000 9288768
14:33:10 7133 8192000000 10150504624 10548408320 1246532144 13.206 99 17 34 0 1256109104 5664096 3912864
14:34:18 9878 8192000000 10179417056 10590556160 1288267440 13.588 100 25 52 1 1298558160 5001248 5289472
14:35:29 17870 8192000000 10190086544 10595540992 1290119808 13.605 100 45 89 0 1306042192 5219360 10703024
14:36:49 9966 8192000000 10191968096 10605989888 1302874208 13.721 100 27 54 0 1314699520 5501824 6323488
14:38:00 10206 8192000000 10222023760 10629259264 1317195680 13.851 100 27 50 0 1327642928 4588976 5858272
14:39:15 6276 8192000000 10243267136 10656190464 1340606320 14.063 100 52 102 1 1360172064 7994560 11571184
14:40:25 43925 8192000000 10253533776 10675503104 1350611216 14.153 100 37 75 1 1367775696 8599840 8564640
14:42:14 25864 8192000000 10269795888 10694373376 1368075968 14.310 100 47 94 0 1390734000 11704048 10953984
14:43:52 17820 8192000000 10295962976 10716180480 1384074480 14.453 100 52 104 0 1410033792 14191328 11767984
14:45:17 15430 8192000000 10308300880 10734424064 1394742064 14.548 100 43 86 0 1423024352 17319248 10963040
14:46:37 24155 8192000000 10320575312 10744524800 1399948464 14.595 100 57 114 0 1432185280 19068512 13168304
14:48:10 6501 8192000000 10323914544 10750988288 1396354480 14.563 99 55 110 1 1431934256 22765360 12814416
14:49:20 9751 8192000000 10321245776 10753146880 1402921120 14.621 100 40 80 0 1436263056 23439472 9902464
14:50:46 7128 8192000000 10319604640 10739724288 1387405696 14.483 100 53 106 0 1427786528 27703200 12677632
14:51:58 4352 8192000000 10328026080 10749501440 1389848032 14.505 100 58 115 1 1436551792 32168608 14535152
14:53:07 8745 8192000000 10311515104 10726395904 1365105632 14.283 99 61 122 1 1413817216 33824656 14886928
14:54:20 4837 8192000000 10324070432 10730188800 1366086368 14.292 100 79 154 2 1428721856 43189104 19446384
14:55:29 1357 8192000000 10316488688 10724126720 1369390160 14.322 100 80 160 2 1420494624 32172304 18932160
14:56:36 2537 8192000000 10297112576 10701291520 1350209552 14.149 100 72 144 0 1401365808 33970480 17185776
14:57:46 1351 8192000000 10326479632 10730242048 1370853904 14.335 100 72 140 1 1428654096 40565232 17234960
14:58:52 917 8192000000 10333243584 10734977024 1369999552 14.327 99 78 156 1 1434577424 43836128 20741744
14:59:57 2016 8192000000 10360099776 10768076800 1395641200 14.556 100 75 147 0 1465042752 50279472 19122080
15:01:04 3453 8192000000 10365139584 10767818752 1391305248 14.518 100 84 169 0 1466078704 53723920 21049536
15:02:09 2989 8192000000 10347903088 10760175616 1375239632 14.374 99 55 108 1 1444795856 54718832 14837392
15:03:18 11289 8192000000 10361024640 10786381824 1402832832 14.620 100 35 71 0 1464627456 52131616 9663008
15:04:32 17446 8192000000 10346063520 10774110208 1386171952 14.472 100 35 70 1 1452352768 56043136 10137680
15:06:08 29443 8192000000 10349491600 10780950528 1349529376 14.143 97 28 56 0 1421763920 63805824 8428720
15:07:40 41294 8192000000 10315716288 10758799360 1359288816 14.231 99 23 46 0 1417660608 52002256 6369536
15:09:41 70771 8192000000 10283386720 10733936640 1335736608 14.019 97 5 10 0 1350363968 13702192 925168
15:11:52 26212 8192000000 10293269376 10731851776 1375110448 14.373 100 26 53 0 1391894160 10937440 5846272
15:13:29 98114 8192000000 10296206432 10744373248 1358571888 14.225 98 25 50 0 1374997040 11234848 5190304
15:16:08 41249 8192000000 10293197072 10738663424 1382378128 14.438 100 41 79 2 1406872896 14891408 9603360
15:18:02 70736 8192000000 10311467392 10756685824 1388895744 14.496 100 45 87 0 1413697872 13987696 10814432
15:20:21 71473 8192000000 10307270208 10763493376 1401090320 14.605 100 12 24 1 1415095536 10567152 3438064
15:22:34 44421 8192000000 10318800672 10765582336 1400332528 14.598 99 16 32 0 1416902880 12458912 4111440
15:24:19 30380 8192000000 10338381776 10781954048 1418714016 14.761 100 43 86 0 1446015264 16253184 11048064
15:25:56 53848 8192000000 10316263024 10775326720 1415057696 14.729 100 6 12 0 1424439616 8091168 1290752
15:27:58 21235 8192000000 10316260544 10778791936 1410411488 14.688 99 20 40 0 1422694992 8227584 4055920
15:29:19 62710 8192000000 10322227680 10790981632 1425265712 14.819 100 11 22 1 1442790448 15101136 2423600
15:31:47 31346 8192000000 10311478560 10779959296 1408447216 14.670 100 13 26 1 1429458448 17563664 3447568
15:33:19 43565 8192000000 10280819104 10739290112 1366977616 14.300 98 11 22 0 1376466288 7299856 2188816
15:35:08 55263 8192000000 10265077152 10734440448 1358491744 14.224 98 5 11 0 1366336000 6937552 906704
15:37:11 71145 8192000000 10278064304 10737336320 1310497984 13.791 94 6 12 0 1323883104 12149632 1235488
15:39:23 20160 8192000000 10273448064 10722783232 1315866496 13.839 95 19 37 1 1327799856 8121360 3812000
15:40:48 44970 8192000000 10282002432 10739924992 1369433648 14.322 99 15 30 0 1382895696 10025696 3436352
15:42:33 52463 8192000000 10279632384 10741669888 1372106880 14.346 99 31 62 0 1389605168 10619296 6878992
15:44:31 31005 8192000000 10272947696 10729775104 1330334480 13.970 97 28 57 1 1345224144 8510112 6379552
15:46:03 70042 8192000000 10259415376 10710069248 1282998064 13.540 94 11 21 1 1301971792 16679728 2294000
15:48:15 88881 8192000000 10257244160 10709766144 1278599232 13.500 94 12 22 0 1293482368 12109232 2773904
15:50:45 37557 8192000000 10278563808 10724372480 1347345648 14.124 99 29 58 0 1367458352 13466400 6646304
15:52:34 88435 8192000000 10270440208 10726187008 1332556848 13.990 98 21 42 0 1359136016 21825968 4753200
15:55:03 40656 8192000000 10255501536 10703474688 1351012656 14.157 100 21 43 0 1365156592 9406688 4737248
15:56:44 37973 8192000000 10250288064 10707533824 1343619232 14.090 99 14 27 0 1354615520 8386160 2610128
15:58:32 49733 8192000000 10257962176 10708688896 1353780400 14.181 100 11 22 0 1366919344 10502496 2636448
16:00:26 51618 8192000000 10256988928 10724884480 1348756240 14.136 99 14 28 0 1363642256 11253872 3632144
16:02:42 37105 8192000000 10282531392 10735222784 1370264768 14.329 100 39 75 1 1388012160 8696576 9050816
16:04:25 49595 8192000000 10295140480 10742628352 1359965472 14.237 98 29 58 0 1381095328 14749584 6380272
16:06:55 37669 8192000000 10290105472 10736295936 1370575104 14.332 99 36 71 2 1386056352 8812160 6669088
16:08:33 42652 8192000000 10299323248 10743459840 1366042640 14.292 98 29 58 0 1382284288 10170864 6070784
16:10:20 64665 8192000000 10305579104 10770477056 1393674480 14.539 100 23 47 0 1410634736 12395904 4564352
16:12:25 46456 8192000000 10326967456 10778161152 1402592144 14.618 100 18 36 0 1417628944 11188416 3848384
16:14:14 123095 8192000000 10301128896 10764054528 1357132512 14.212 97 6 12 0 1375351264 16820928 1397824
16:17:17 77673 8192000000 10300221056 10759512064 1366476624 14.295 98 34 68 1 1386008096 12056080 7475392
16:19:49 21350 8192000000 10272234576 10725961728 1335530352 14.017 97 20 40 1 1353564368 14111408 3922608
16:21:20 80640 8192000000 10269237344 10735157248 1338109696 14.040 97 5 10 0 1348429952 9230416 1089840
16:23:42 66518 8192000000 10266499696 10733096960 1345736112 14.109 98 14 28 0 1358140400 9045232 3359056
16:25:48 66299 8192000000 10274128832 10735091712 1365167936 14.284 99 16 32 0 1378888528 10115536 3605056
16:28:43 43126 8192000000 10286146304 10735869952 1364030304 14.274 99 27 54 0 1378648112 7857776 6760032
16:30:33 98457 8192000000 10277064064 10736779264 1301351232 13.708 94 19 38 0 1315302960 9436864 4514864
16:33:14 38469 8192000000 10269980000 10713731072 1305077120 13.741 95 21 42 0 1320073648 10427216 4569312
16:34:54 29761 8192000000 10281871056 10720792576 1327182016 13.942 97 42 82 1 1346808192 10640320 8985856
16:36:27 47658 8192000000 10272580448 10733871104 1341395600 14.070 98 34 68 1 1366660880 17793216 7472064
16:38:20 26230 8192000000 9134869232 9379336192 287812416 3.394 22 2 4 0 297598304 9373440 412448

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 02-02-2023 16:39:52 to 02-02-2023 16:40:08.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 100 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 02-02-2023 16:40:09 to 02-02-2023 16:40:11.

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          247813
MON$OLDEST_ACTIVE               249123
MON$OLDEST_SNAPSHOT             249123
MON$NEXT_TRANSACTION            249123
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                       2045664
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.33658 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33658 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33658 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33658 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 ---
:42: 
:43: mscs-test-161.budzdorov.ru    Thu Feb  2 16:38:51 2023
:44:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:45: 
:46: 
:47: mscs-test-161.budzdorov.ru    Thu Feb  2 16:38:57 2023
:48:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:49: 
:50: 
:51: mscs-test-161.budzdorov.ru    Thu Feb  2 16:38:57 2023
:52:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:53: 
--- end of diff output ---

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

02.02.23 16:40:12. 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
2023-02-02 16:40:14 No severe PSQL-related problems occured
02.02.23 16:40:16. End of report.