Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 29.12.2022 19:40
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15056572 kB
MemAvailable 30380088 kB
Buffers 0 kB
Cached 16659316 kB
SwapCached 11192 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-29 19:38:36
DTS_END                         2022-12-29 19:38:36.5270
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/54376
TRN_ID                          244455
ATT_ID                          710
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: 9845.13 17562 1181415
customer order: creation 178.54 323 21425
customer order: refuse 14.92 343 1790
order to supplier: creation 46.60 1254 5592
order to supplier: removal 3.91 99891 469
invoice (draft): creation 46.36 3181 5563
invoice (draft): removal 57.83 783 6940
invoice accept: apply 42.60 17573 5112
invoice accept: cancel 52.70 729 6324
customer reserve: creation 3929.37 175 471524
customer reserve: removal 5162.88 59 619546
realization accept: apply 85.88 159 10305
realization accept: cancel 92.48 105 11098
payment from customer: creation 59.53 112 7144
payment from customer: removal 5.90 75 708
payment to supplier: creation 49.95 127 5994
payment to supplier: removal 3.76 50 451
service: total inventory turnovers 4.68 1204 561
service: total monetary turnovers 6.44 29 773
service: aggregate perf. data 0.77 19411 92
service: refresh index statistics 0.03 205665 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 29-12-2022 19:40:35 to 29-12-2022 19:40:37.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5116
TEST_TIME 2 3565
TEST_TIME 3 4590
TEST_TIME 4 4065
TEST_TIME 5 3994
TEST_TIME 6 4596
TEST_TIME 7 5456
TEST_TIME 8 5730
TEST_TIME 9 6039
TEST_TIME 10 6448
TEST_TIME 11 6395
TEST_TIME 12 6426
TEST_TIME 13 6538
TEST_TIME 14 6640
TEST_TIME 15 6848
TEST_TIME 16 7062
TEST_TIME 17 7027
TEST_TIME 18 7147
TEST_TIME 19 7415
TEST_TIME 20 7640
TEST_TIME 21 8440
TEST_TIME 22 8540
TEST_TIME 23 8618
TEST_TIME 24 8808
TEST_TIME 25 8652
TEST_TIME 26 8987
TEST_TIME 27 9117
TEST_TIME 28 9408
TEST_TIME 29 10716
TEST_TIME 30 10816
TEST_TIME 31 10772
TEST_TIME 32 10760
TEST_TIME 33 10721
TEST_TIME 34 10826
TEST_TIME 35 10886
TEST_TIME 36 10733
TEST_TIME 37 10654
TEST_TIME 38 10580
TEST_TIME 39 10638
TEST_TIME 40 10454
TEST_TIME 41 10280
TEST_TIME 42 10104
TEST_TIME 43 10343
TEST_TIME 44 10266
TEST_TIME 45 10189
TEST_TIME 46 10153
TEST_TIME 47 10149
TEST_TIME 48 10169
TEST_TIME 49 10323
TEST_TIME 50 10199
TEST_TIME 51 10145
TEST_TIME 52 10025
TEST_TIME 53 9891
TEST_TIME 54 9920
TEST_TIME 55 9876
TEST_TIME 56 9807
TEST_TIME 57 9697
TEST_TIME 58 9733
TEST_TIME 59 9789
TEST_TIME 60 9823
TEST_TIME 61 9844
TEST_TIME 62 9836
TEST_TIME 63 9933
TEST_TIME 64 9976
TEST_TIME 65 9996
TEST_TIME 66 9962
TEST_TIME 67 10002
TEST_TIME 68 10043
TEST_TIME 69 10044
TEST_TIME 70 10128
TEST_TIME 71 10136
TEST_TIME 72 10119
TEST_TIME 73 10023
TEST_TIME 74 9976
TEST_TIME 75 9998
TEST_TIME 76 9991
TEST_TIME 77 9919
TEST_TIME 78 9838
TEST_TIME 79 9813
TEST_TIME 80 9794
TEST_TIME 81 9718
TEST_TIME 82 9752
TEST_TIME 83 9869
TEST_TIME 84 9871
TEST_TIME 85 9863
TEST_TIME 86 9853
TEST_TIME 87 9930
TEST_TIME 88 9959
TEST_TIME 89 9962
TEST_TIME 90 9944
TEST_TIME 91 9967
TEST_TIME 92 9973
TEST_TIME 93 10011
TEST_TIME 94 10070
TEST_TIME 95 10049
TEST_TIME 96 9966
TEST_TIME 97 9959
TEST_TIME 98 9923
TEST_TIME 99 9864
TEST_TIME 100 9874
TEST_TIME 101 9825
TEST_TIME 102 9832
TEST_TIME 103 9855
TEST_TIME 104 9826
TEST_TIME 105 9771
TEST_TIME 106 9778
TEST_TIME 107 9748
TEST_TIME 108 9783
TEST_TIME 109 9757
TEST_TIME 110 9763
TEST_TIME 111 9816
TEST_TIME 112 9786
TEST_TIME 113 9810
TEST_TIME 114 9859
TEST_TIME 115 9842
TEST_TIME 116 9851
TEST_TIME 117 9908
TEST_TIME 118 9892
TEST_TIME 119 9865
TEST_TIME 120 9849

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 29-12-2022 19:40:38 to 29-12-2022 19:40:42.

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 1619898 1619898 0 0.00 0 17921 6 0 0
doc_list_biud 1619898 1619898 0 0.00 0 60882 28 0 0
sp_add_invoice_to_stock 5216 5112 104 1.99 24 170615 17573 0 104
sp_cancel_adding_invoice 6324 6324 0 0.00 3 55839 729 0 0
sp_cancel_client_order 1790 1790 0 0.00 4 17092 344 0 0
sp_cancel_customer_reserve 619546 619546 0 0.00 0 40457 59 0 0
sp_cancel_pay_from_customer 708 708 0 0.00 3 4726 75 0 0
sp_cancel_pay_to_supplier 451 451 0 0.00 3 1566 51 0 0
sp_cancel_supplier_invoice 6948 6940 8 0.11 1 60894 784 0 8
sp_cancel_supplier_order 469 469 0 0.00 10 977850 99892 0 0
sp_cancel_write_off 11098 11098 0 0.00 1 6192 106 0 0
sp_client_order 21425 21425 0 0.00 3 22291 323 0 0
sp_customer_reserve 471524 471524 0 0.00 0 66787 176 0 0
sp_fill_shopping_cart 32574 32574 0 0.00 1 12404 34 0 0
sp_fill_shopping_cart_clo_res 471519 471519 0 0.00 0 21715 13 0 0
sp_get_clo_for_invoice 5105 5105 0 0.00 0 24256 237 0 0
sp_kill_cost_storno 18581 18581 0 0.00 0 7196 53 0 0
sp_kill_qstorno_ret_qs2qd 1257599 1257599 0 0.00 0 60878 32 0 0
sp_kill_qty_storno 661576 661576 0 0.00 0 60881 68 0 0
sp_lock_selected_doc 21410 21410 0 0.00 0 6609 6 0 0
sp_make_cost_storno 28547 28547 0 0.00 0 11300 48 0 0
sp_multiply_rows_for_pdistr 28547 28547 0 0.00 0 9694 26 0 0
sp_multiply_rows_for_qdistr 21425 21425 0 0.00 0 19761 147 0 0
sp_pay_from_customer 7144 7144 0 0.00 4 17779 113 0 0
sp_pay_to_supplier 5994 5994 0 0.00 4 18444 127 0 0
sp_payment_common 13138 13138 0 0.00 1 17766 30 0 0
sp_qd_handle_on_cancel_clo 1790 1790 0 0.00 0 7600 305 0 0
sp_qd_handle_on_invoice_upd_sts 11428 11428 0 0.00 0 24459 325 0 0
sp_qd_handle_on_reserve_upd_sts 10305 10305 0 0.00 0 15953 54 0 0
sp_reserve_write_off 10305 10305 0 0.00 6 20982 159 0 0
sp_supplier_invoice 5563 5563 0 0.00 6 110145 3181 0 0
sp_supplier_order 5592 5592 0 0.00 4 51443 1254 0 0
srv_aggregate_perf_data 92 92 0 0.00 2833 203736 19411 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1008 142263 43414 0 0
srv_find_qd_qs_mism 1133593 1133593 0 0.00 0 17921 5 0 0
srv_make_invnt_saldo 561 561 0 0.00 55 62823 1204 0 0
srv_make_money_saldo 773 773 0 0.00 0 2543 30 0 0
srv_recalc_idx_stat 4 4 0 0.00 87114 356713 205666 0 0
t$perf-abend:sp_add_invoice_to_stock 104 104 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5113 5113 0 0.00 0 26736 174 0 0
t$perf-norm:sp_cancel_adding_invoice 437 437 0 0.00 0 4335 86 0 0
t$perf-norm:sp_cancel_client_order 1790 1790 0 0.00 0 6219 13 0 0
t$perf-norm:sp_cancel_customer_reserve 713 713 0 0.00 0 3068 16 0 0
t$perf-norm:sp_cancel_pay_from_customer 708 708 0 0.00 0 5451 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 451 451 0 0.00 0 817 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 418 418 0 0.00 0 822 12 0 0
t$perf-norm:sp_cancel_supplier_order 469 469 0 0.00 0 20089 1500 0 0
t$perf-norm:sp_cancel_write_off 1015 1015 0 0.00 0 2067 9 0 0
t$perf-norm:sp_client_order 21425 21425 0 0.00 0 14270 17 0 0
t$perf-norm:sp_customer_reserve 1105 1105 0 0.00 0 1157 6 0 0
t$perf-norm:sp_pay_from_customer 7144 7144 0 0.00 0 5283 12 0 0
t$perf-norm:sp_pay_to_supplier 5994 5994 0 0.00 0 4543 9 0 0
t$perf-norm:sp_reserve_write_off 10305 10305 0 0.00 0 10399 12 0 0
t$perf-norm:sp_supplier_invoice 5563 5563 0 0.00 0 11734 21 0 0
t$perf-norm:sp_supplier_order 5592 5592 0 0.00 0 10330 18 0 0
t$perf-norm:srv_aggregate_perf_data 92 92 0 0.00 0 406 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 79 2 0 0
t$perf-norm:srv_make_invnt_saldo 561 561 0 0.00 0 875 17 0 0
t$perf-norm:srv_make_money_saldo 773 773 0 0.00 0 10443 28 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 4 7 5 0 0
v_add_invoice_to_stock 10416 10208 208 1.99 0 10877 20 0 208
v_all_customers 38518 38518 0 0.00 0 67 1 0 0
v_all_suppliers 31410 31410 0 0.00 0 88 1 0 0
v_all_wares 42850 42850 0 0.00 0 26 1 0 0
v_cancel_adding_invoice 874 874 0 0.00 0 334 6 0 0
v_cancel_client_order 3580 3580 0 0.00 0 16073 9 0 0
v_cancel_customer_prepayment 1416 1416 0 0.00 0 4687 12 0 0
v_cancel_customer_reserve 1426 1426 0 0.00 0 2829 10 0 0
v_cancel_payment_to_supplier 902 902 0 0.00 0 1552 5 0 0
v_cancel_supplier_invoice 852 836 16 1.87 0 9960 26 0 16
v_cancel_supplier_order 938 938 0 0.00 0 580 5 0 0
v_cancel_write_off 2030 2030 0 0.00 0 2863 13 0 0
v_max_id_clo_ord 5592 5592 0 0.00 0 4151 2 0 0
v_max_id_clo_res 1105 1105 0 0.00 0 2436 11 0 0
v_max_id_ord_sup 5557 5557 0 0.00 0 6792 3 0 0
v_max_non_paid_invoice 1438 1438 0 0.00 0 288 2 0 0
v_max_non_paid_realizn 3862 3862 0 0.00 0 8318 6 0 0
v_min_id_clo_ord 5592 5592 0 0.00 0 4304 7 0 0
v_min_id_clo_res 1105 1105 0 0.00 0 6751 14 0 0
v_min_id_ord_sup 5557 5557 0 0.00 0 5685 4 0 0
v_min_non_paid_invoice 5994 5994 0 0.00 0 9136 20 0 0
v_min_non_paid_realizn 7144 7144 0 0.00 0 17762 26 0 0
v_our_firm 10896 10896 0 0.00 0 13 1 0 0
v_reserve_write_off 20610 20610 0 0.00 0 19846 9 0 0
x_lock_depdocs_on_canc_invoice 12846 12846 0 0.00 0 28994 121 0 0
x_lock_depdocs_on_canc_sup_ord 469 469 0 0.00 0 21688 379 0 0
x_make_qstorno_1000_1200 5592 5592 0 0.00 1 51436 1182 0 0
x_make_qstorno_1200_2000 5563 5563 0 0.00 2 110089 3063 0 0
x_make_qstorno_2100_3300 471524 471524 0 0.00 0 66786 160 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 29-12-2022 19:40:43 to 29-12-2022 19:40:47.

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:40 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:41 18296 8192000000 9902185920 10211254272 984394832 10.727 100 93 187 0 1013765072 5615216 23755024
17:11:02 10649 8192000000 9946869520 10265804800 1025792096 11.128 100 91 182 0 1057231248 8310640 23128512
17:12:22 13444 8192000000 9969665824 10300342272 1057613728 11.434 100 84 168 0 1088564512 9852208 21098576
17:13:37 10817 8192000000 10029803152 10373169152 1106992848 11.904 100 76 151 0 1135548032 10523008 18032176
17:14:51 5910 8192000000 10059244928 10412445696 1145527280 12.267 100 80 160 0 1177017776 12296416 19194080
17:16:00 4244 8192000000 10102267616 10463649792 1190735024 12.690 100 83 166 0 1222268960 13185392 18348544
17:17:05 2546 8192000000 10135475952 10509205504 1227217376 13.028 100 77 149 0 1257017840 13635472 16164992
17:18:10 1371 8192000000 10180766080 10559975424 1266532480 13.390 100 89 174 1 1299140000 13441136 19166384
17:19:12 1507 8192000000 10209926496 10593353728 1293818032 13.639 100 86 172 0 1325127952 12723600 18586320
17:20:14 13451 8192000000 10212913392 10609602560 1306067264 13.750 100 74 146 0 1333505888 11604176 15834448
17:21:28 8359 8192000000 10198264624 10614325248 1313702288 13.820 100 30 60 0 1326934032 6231568 7000176
17:22:38 7977 8192000000 10209803600 10624425984 1321125712 13.887 100 12 22 0 1328712016 5084016 2502288
17:23:47 13667 8192000000 10207929136 10628423680 1324996816 13.922 100 13 27 0 1333305584 5315520 2993248
17:25:03 63304 8192000000 10226481184 10647339008 1331001264 13.976 100 35 70 0 1345055856 5359376 8695216
17:27:06 5407 8192000000 10232373232 10658099200 1343302880 14.087 100 28 56 0 1356355968 6506064 6547024
17:28:14 10448 8192000000 10209233184 10628620288 1310099632 13.787 99 19 38 0 1320338336 6005104 4233600
17:29:24 8457 8192000000 10194827072 10610536448 1296504816 13.663 99 27 54 1 1309161712 5999824 6657072
17:30:33 10803 8192000000 10174049104 10577719296 1228043456 13.036 94 56 110 2 1247369840 6132496 13193888
17:31:45 4207 8192000000 10161433408 10563518464 1258842464 13.319 99 38 76 1 1273443168 6882976 7717728
17:32:51 8315 8192000000 10153121808 10556264448 1242777744 13.172 98 22 42 0 1253122800 5149280 5195776
17:34:01 6882 8192000000 10161585440 10565439488 1269831952 13.420 100 21 42 0 1279610704 4655136 5123616
17:35:10 17140 8192000000 10178422672 10585661440 1282124048 13.532 100 26 50 0 1293999136 6473408 5401680
17:36:27 5645 8192000000 10186757424 10600198144 1297411696 13.672 100 26 53 1 1309198960 5795376 5991888
17:37:39 9715 8192000000 10194506656 10612097024 1304165760 13.733 100 22 44 2 1314660096 5790000 4704336
17:38:52 21927 8192000000 10237027440 10648883200 1335528400 14.017 100 36 72 0 1349949360 6104304 8316656
17:40:18 76083 8192000000 10254442976 10679345152 1354915584 14.192 100 44 80 0 1375361456 10848608 9597264
17:42:39 11974 8192000000 10275469856 10696306688 1368038848 14.309 100 41 82 2 1393334576 15647824 9647904
17:43:53 7428 8192000000 10294269840 10711158784 1381267984 14.428 100 59 112 1 1408426640 14594432 12564224
17:45:13 16263 8192000000 10300579872 10729496576 1387429520 14.483 100 45 91 1 1418049488 19513664 11106304
17:46:43 3238 8192000000 10317052624 10743742464 1395207104 14.552 100 54 105 2 1426215200 18139776 12868320
17:47:50 6251 8192000000 10344251312 10763968512 1410858144 14.692 100 66 132 0 1453417488 26486768 16072576
17:49:02 5104 8192000000 10337362032 10761859072 1409292944 14.678 100 69 133 0 1449181856 23713952 16174960
17:50:24 4247 8192000000 10329017504 10752090112 1404507072 14.635 100 59 117 0 1437855136 19542480 13805584
17:51:35 5136 8192000000 10341139648 10766368768 1415090288 14.729 100 60 118 2 1452993088 23809184 14093616
17:52:53 7608 8192000000 10361060048 10789457920 1421633088 14.787 100 50 97 0 1469360448 35402336 12325024
17:54:06 4994 8192000000 10360926912 10777878528 1414828736 14.727 100 67 134 1 1469063168 37212528 17021904
17:55:20 3159 8192000000 10371271792 10787835904 1420460544 14.777 100 75 150 1 1484981280 45668528 18852208
17:56:27 3733 8192000000 10367244320 10777231360 1400090416 14.596 99 74 147 0 1467599888 48250160 19259312
17:57:36 1481 8192000000 10381353296 10787246080 1407299296 14.660 100 92 185 1 1481039840 50439824 23300720
17:58:43 1006 8192000000 10380517088 10784448512 1402069200 14.613 100 86 172 1 1478769600 54786384 21914016
17:59:47 8688 8192000000 10345922656 10767077376 1391777872 14.522 100 50 100 0 1453366784 48100112 13488800
18:01:01 18322 8192000000 10348494480 10775314432 1392500416 14.528 100 40 80 1 1455186560 51116224 11569920
18:02:21 21760 8192000000 10368082384 10794467328 1401396704 14.607 100 33 66 1 1471338368 60706096 9235568
18:03:51 26975 8192000000 10341515616 10768171008 1377762400 14.397 100 42 85 1 1450340800 60289728 12288672
18:05:20 27673 8192000000 10325961888 10765176832 1368009728 14.309 100 23 46 0 1436766016 61409088 7347200
18:06:56 66583 8192000000 10252488832 10714529792 1252352288 13.260 92 14 28 0 1311401136 55024688 4024160
18:09:28 49674 8192000000 10269042320 10710577152 1357710576 14.217 100 12 24 0 1370709056 10498688 2499792
18:11:18 44926 8192000000 10282454880 10721636352 1329945360 13.967 96 14 28 0 1345095280 12520864 2629056
18:13:04 78784 8192000000 10274545184 10719436800 1334155168 14.005 97 5 10 0 1345822592 10848320 819104
18:15:23 41317 8192000000 10310000736 10744676352 1380290272 14.419 100 39 78 0 1405543184 16618960 8633952
18:17:10 142263 8192000000 10308825936 10760118272 1379269440 14.410 99 29 58 0 1398338576 12671040 6398096
18:21:03 71394 8192000000 10310245584 10772537344 1402469488 14.617 100 31 62 0 1421787248 12309536 7008224
18:23:23 23914 8192000000 10300661200 10750861312 1376136384 14.382 98 14 28 0 1390287200 10576992 3573824
18:24:48 32457 8192000000 10316135952 10773639168 1410857040 14.692 100 36 72 0 1429613376 10596368 8159968
18:26:26 55207 8192000000 10299877312 10766262272 1393631840 14.538 99 25 49 0 1410855712 10954816 6269056
18:28:30 54961 8192000000 10299565888 10768654336 1399243376 14.588 99 4 8 0 1408160736 8106016 811344
18:30:26 40336 8192000000 10300850432 10764886016 1385563104 14.466 98 15 30 0 1396287344 7337296 3386944
18:33:12 31048 8192000000 10313529232 10777735168 1400342224 14.598 99 25 46 0 1414674224 9227728 5104272
18:34:52 50502 8192000000 10314067472 10786611200 1413077952 14.711 100 14 28 0 1429482880 12909264 3495664
18:36:58 64104 8192000000 10295016032 10772807680 1352649984 14.171 96 7 14 0 1367853056 13877184 1325888
18:39:02 9713 8192000000 10290383040 10745835520 1384393152 14.456 100 28 56 0 1397436544 6953184 6090208
18:40:12 16749 8192000000 10296427344 10744983552 1381058512 14.426 100 34 67 1 1400094512 10966176 8069824
18:41:41 26004 8192000000 10272714992 10726862848 1338555872 14.044 97 18 37 1 1350904096 7909680 4438544
18:43:12 35568 8192000000 10237439648 10677788672 1279431616 13.508 94 24 49 0 1297125584 12561728 5132240
18:44:50 43033 8192000000 10272784032 10733817856 1356204624 14.203 99 27 54 0 1377132336 14942368 5985344
18:46:39 19037 8192000000 10278001424 10715279360 1337585264 14.036 99 54 108 0 1362913088 13335536 11992288
18:48:00 48030 8192000000 10250272624 10703290368 1305624224 13.746 97 16 33 0 1324284480 14943968 3716288
18:50:02 44170 8192000000 10264432672 10708000768 1322238352 13.897 98 18 36 0 1333893568 7737776 3917440
18:51:48 137534 8192000000 10260207056 10717499392 1341602848 14.072 99 33 61 1 1367326432 18173920 7549664
18:55:09 60005 8192000000 10259872592 10715127808 1350931648 14.156 99 11 19 0 1361028368 7957888 2138832
18:57:09 84257 8192000000 10259107792 10714497024 1332632880 13.991 98 19 39 0 1348230096 10782672 4814544
18:59:34 66619 8192000000 10270380832 10737094656 1368180240 14.311 100 11 22 0 1386699312 15598288 2920784
19:01:40 61474 8192000000 10267247888 10724491264 1360674368 14.243 100 12 22 0 1378181744 14218368 3289008
19:03:46 35213 8192000000 10272630960 10720145408 1347937552 14.129 99 45 90 0 1368874928 10538704 10398672
19:05:52 88802 8192000000 10280299776 10724270080 1340588496 14.063 98 11 22 0 1355455120 12484368 2382256
19:08:29 22157 8192000000 10280039216 10724540416 1356572768 14.207 99 26 50 1 1370209616 8573360 5063488
19:09:51 28957 8192000000 10294081888 10749943808 1382550352 14.439 100 29 58 0 1404635856 15889024 6196480
19:11:20 46856 8192000000 10316267680 10767257600 1385302576 14.464 99 27 52 1 1406925584 15838416 5784592
19:13:10 112401 8192000000 10306512912 10753335296 1354794464 14.191 97 25 50 0 1371755648 11631200 5329984
19:16:15 77045 8192000000 10295157200 10753376256 1372052832 14.345 98 17 32 0 1386733552 10656512 4024208
19:18:32 76395 8192000000 10291976176 10760019968 1359129600 14.230 97 16 31 0 1378502464 15964832 3408032
19:20:53 65162 8192000000 10299640736 10775072768 1397094832 14.569 100 14 28 0 1419357536 18949744 3312960
19:23:14 77082 8192000000 10278308800 10748682240 1357716848 14.217 97 12 24 0 1371792208 11678656 2396704
19:25:34 102242 8192000000 10286481072 10738245632 1305467488 13.745 93 13 25 0 1321464288 13275840 2720960
19:28:26 65699 8192000000 10260181616 10719256576 1287431824 13.581 94 15 30 0 1301880096 11320192 3128080
19:30:38 24754 8192000000 10286799776 10729099264 1352498304 14.170 99 38 76 0 1373598656 12055808 9044544
19:32:04 92892 8192000000 10277665072 10735341568 1330038496 13.968 97 19 38 0 1351604384 17518848 4047040
19:34:49 60318 8192000000 10253446144 10715004928 1297743616 13.675 96 15 30 0 1317396928 16395536 3257776
19:36:50 63172 8192000000 10261354640 10695872512 1276272528 13.479 94 16 33 0 1292309920 12116064 3921328

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 29-12-2022 19:40:49 to 29-12-2022 19:41:04.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 104 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 29-12-2022 19:41:05 to 29-12-2022 19:41:07.

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          243663
MON$OLDEST_ACTIVE               244623
MON$OLDEST_SNAPSHOT             244623
MON$NEXT_TRANSACTION            244623
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                       2058144
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.33650 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33650 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33650 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33650 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 ---
:1369: 
:1370: mscs-test-161.budzdorov.ru    Thu Dec 29 19:39:09 2022
:1371:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1372: 
--- end of diff output ---

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

29.12.22 19:41:08. 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-29 19:41:11 No severe PSQL-related problems occured
29.12.22 19:41:12. End of report.