Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 02.03.2023 22: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 15122300 kB
MemAvailable 30452528 kB
Buffers 0 kB
Cached 16599876 kB
SwapCached 3044 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-03-02 22:38:41
DTS_END                         2023-03-02 22:38:41.5900
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(131:22) ==> SP_CANCEL_ADDING_INVOICE(33:5) ==> SP_ADD_INVOICE_TO_STOCK(69:14) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/35844
TRN_ID                          239856
ATT_ID                          714
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: 9645.94 17708 1157513
customer order: creation 177.12 319 21254
customer order: refuse 14.11 361 1693
order to supplier: creation 46.23 1196 5547
order to supplier: removal 3.80 90472 456
invoice (draft): creation 46.48 3271 5578
invoice (draft): removal 55.70 685 6684
invoice accept: apply 43.23 17321 5188
invoice accept: cancel 50.13 757 6015
customer reserve: creation 3969.88 173 476386
customer reserve: removal 4931.63 55 591796
realization accept: apply 84.63 158 10156
realization accept: cancel 91.48 104 10978
payment from customer: creation 59.38 98 7125
payment from customer: removal 5.68 77 682
payment to supplier: creation 50.19 112 6023
payment to supplier: removal 3.67 66 440
service: total inventory turnovers 5.08 1057 610
service: total monetary turnovers 6.79 39 815
service: aggregate perf. data 0.69 27675 83
service: refresh index statistics 0.03 210166 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 02-03-2023 22:40:33 to 02-03-2023 22:40:35.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3069
TEST_TIME 2 4823
TEST_TIME 3 5689
TEST_TIME 4 5503
TEST_TIME 5 5437
TEST_TIME 6 5763
TEST_TIME 7 5847
TEST_TIME 8 5911
TEST_TIME 9 6617
TEST_TIME 10 6760
TEST_TIME 11 6675
TEST_TIME 12 6530
TEST_TIME 13 6553
TEST_TIME 14 6616
TEST_TIME 15 6998
TEST_TIME 16 7199
TEST_TIME 17 7502
TEST_TIME 18 8007
TEST_TIME 19 8371
TEST_TIME 20 8431
TEST_TIME 21 8887
TEST_TIME 22 8987
TEST_TIME 23 9241
TEST_TIME 24 9565
TEST_TIME 25 9794
TEST_TIME 26 9590
TEST_TIME 27 9786
TEST_TIME 28 9557
TEST_TIME 29 9821
TEST_TIME 30 10946
TEST_TIME 31 10853
TEST_TIME 32 10665
TEST_TIME 33 10711
TEST_TIME 34 10683
TEST_TIME 35 10528
TEST_TIME 36 10389
TEST_TIME 37 10276
TEST_TIME 38 10541
TEST_TIME 39 10494
TEST_TIME 40 10517
TEST_TIME 41 10276
TEST_TIME 42 10121
TEST_TIME 43 10242
TEST_TIME 44 10145
TEST_TIME 45 10021
TEST_TIME 46 10001
TEST_TIME 47 9998
TEST_TIME 48 10004
TEST_TIME 49 9991
TEST_TIME 50 9901
TEST_TIME 51 9824
TEST_TIME 52 9768
TEST_TIME 53 9809
TEST_TIME 54 9676
TEST_TIME 55 9730
TEST_TIME 56 9666
TEST_TIME 57 9713
TEST_TIME 58 9705
TEST_TIME 59 9710
TEST_TIME 60 9698
TEST_TIME 61 9677
TEST_TIME 62 9814
TEST_TIME 63 9836
TEST_TIME 64 9897
TEST_TIME 65 9873
TEST_TIME 66 9983
TEST_TIME 67 10044
TEST_TIME 68 9982
TEST_TIME 69 10083
TEST_TIME 70 9993
TEST_TIME 71 9902
TEST_TIME 72 9928
TEST_TIME 73 9835
TEST_TIME 74 9777
TEST_TIME 75 9771
TEST_TIME 76 9828
TEST_TIME 77 9783
TEST_TIME 78 9729
TEST_TIME 79 9707
TEST_TIME 80 9635
TEST_TIME 81 9574
TEST_TIME 82 9624
TEST_TIME 83 9850
TEST_TIME 84 9813
TEST_TIME 85 9811
TEST_TIME 86 9796
TEST_TIME 87 9804
TEST_TIME 88 9804
TEST_TIME 89 9836
TEST_TIME 90 9863
TEST_TIME 91 9889
TEST_TIME 92 9936
TEST_TIME 93 9928
TEST_TIME 94 9845
TEST_TIME 95 9817
TEST_TIME 96 9747
TEST_TIME 97 9804
TEST_TIME 98 9751
TEST_TIME 99 9722
TEST_TIME 100 9662
TEST_TIME 101 9671
TEST_TIME 102 9613
TEST_TIME 103 9618
TEST_TIME 104 9609
TEST_TIME 105 9597
TEST_TIME 106 9601
TEST_TIME 107 9632
TEST_TIME 108 9621
TEST_TIME 109 9646
TEST_TIME 110 9691
TEST_TIME 111 9665
TEST_TIME 112 9667
TEST_TIME 113 9691
TEST_TIME 114 9715
TEST_TIME 115 9773
TEST_TIME 116 9785
TEST_TIME 117 9786
TEST_TIME 118 9733
TEST_TIME 119 9698
TEST_TIME 120 9649

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 02-03-2023 22:40:36 to 02-03-2023 22:40:40.

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 1599627 1599627 0 0.00 0 19791 6 0 0
doc_list_biud 1599620 1599620 0 0.00 0 44301 26 0 0
sp_add_invoice_to_stock 5313 5188 125 2.35 36 154203 17321 0 125
sp_cancel_adding_invoice 6015 6015 0 0.00 4 69519 758 0 0
sp_cancel_client_order 1693 1693 0 0.00 3 11729 361 0 0
sp_cancel_customer_reserve 591796 591796 0 0.00 0 44302 56 0 0
sp_cancel_pay_from_customer 682 682 0 0.00 3 3252 78 0 0
sp_cancel_pay_to_supplier 440 440 0 0.00 3 5177 66 0 0
sp_cancel_supplier_invoice 6691 6684 7 0.10 2 15666 685 0 7
sp_cancel_supplier_order 456 456 0 0.00 6 1003280 90473 0 0
sp_cancel_write_off 10978 10978 0 0.00 1 18866 105 0 0
sp_client_order 21254 21254 0 0.00 3 24701 320 0 0
sp_customer_reserve 476386 476386 0 0.00 0 39067 173 0 0
sp_fill_shopping_cart 32364 32364 0 0.00 1 18104 35 0 0
sp_fill_shopping_cart_clo_res 476365 476365 0 0.00 0 15804 12 0 0
sp_get_clo_for_invoice 5167 5167 0 0.00 0 12986 243 0 0
sp_kill_cost_storno 18115 18115 0 0.00 0 9032 49 0 0
sp_kill_qstorno_ret_qs2qd 1201710 1201710 0 0.00 0 44299 30 0 0
sp_kill_qty_storno 632945 632945 0 0.00 0 44301 64 0 0
sp_lock_selected_doc 21094 21094 0 0.00 0 6436 5 0 0
sp_make_cost_storno 28471 28471 0 0.00 0 10365 51 0 0
sp_multiply_rows_for_pdistr 28471 28471 0 0.00 0 7268 23 0 0
sp_multiply_rows_for_qdistr 21254 21254 0 0.00 0 8628 137 0 0
sp_pay_from_customer 7125 7125 0 0.00 4 13727 98 0 0
sp_pay_to_supplier 6023 6023 0 0.00 4 12928 112 0 0
sp_payment_common 13148 13148 0 0.00 1 13681 27 0 0
sp_qd_handle_on_cancel_clo 1693 1693 0 0.00 0 11721 335 0 0
sp_qd_handle_on_invoice_upd_sts 11182 11182 0 0.00 0 31000 308 0 0
sp_qd_handle_on_reserve_upd_sts 10156 10156 0 0.00 0 9916 48 0 0
sp_reserve_write_off 10156 10156 0 0.00 7 12551 158 0 0
sp_supplier_invoice 5578 5578 0 0.00 6 83495 3271 0 0
sp_supplier_order 5547 5547 0 0.00 5 33789 1197 0 0
srv_aggregate_perf_data 83 83 0 0.00 2341 780452 27676 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1755 133646 41499 0 0
srv_find_qd_qs_mism 1109161 1109161 0 0.00 0 19790 5 0 0
srv_make_invnt_saldo 610 610 0 0.00 18 84188 1058 0 0
srv_make_money_saldo 815 815 0 0.00 1 6827 40 0 0
srv_recalc_idx_stat 4 4 0 0.00 167471 263222 210167 0 0
t$perf-abend:sp_add_invoice_to_stock 125 125 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5188 5188 0 0.00 0 28425 151 0 0
t$perf-norm:sp_cancel_adding_invoice 383 383 0 0.00 0 4953 92 0 0
t$perf-norm:sp_cancel_client_order 1693 1693 0 0.00 0 3370 16 0 0
t$perf-norm:sp_cancel_customer_reserve 707 707 0 0.00 0 1710 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 682 682 0 0.00 0 1052 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 440 440 0 0.00 0 1025 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 417 417 0 0.00 0 791 8 0 0
t$perf-norm:sp_cancel_supplier_order 456 456 0 0.00 0 21051 1343 0 0
t$perf-norm:sp_cancel_write_off 993 993 0 0.00 0 972 4 0 0
t$perf-norm:sp_client_order 21254 21254 0 0.00 0 12753 15 0 0
t$perf-norm:sp_customer_reserve 1027 1027 0 0.00 0 2176 6 0 0
t$perf-norm:sp_pay_from_customer 7125 7125 0 0.00 0 8927 8 0 0
t$perf-norm:sp_pay_to_supplier 6023 6023 0 0.00 0 3250 7 0 0
t$perf-norm:sp_reserve_write_off 10156 10156 0 0.00 0 8837 11 0 0
t$perf-norm:sp_supplier_invoice 5578 5578 0 0.00 0 5216 16 0 0
t$perf-norm:sp_supplier_order 5547 5547 0 0.00 0 7671 15 0 0
t$perf-norm:srv_aggregate_perf_data 83 83 0 0.00 0 144 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 4 0 0 0
t$perf-norm:srv_make_invnt_saldo 610 610 0 0.00 0 8602 39 0 0
t$perf-norm:srv_make_money_saldo 815 815 0 0.00 0 1536 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 9 5 0 0
v_add_invoice_to_stock 10584 10334 250 2.36 0 4303 18 0 250
v_all_customers 38714 38714 0 0.00 0 102 1 0 0
v_all_suppliers 30866 30866 0 0.00 0 103 1 0 0
v_all_wares 42508 42508 0 0.00 0 21 1 0 0
v_cancel_adding_invoice 766 766 0 0.00 0 1119 8 0 0
v_cancel_client_order 3386 3386 0 0.00 0 4316 5 0 0
v_cancel_customer_prepayment 1364 1364 0 0.00 0 3240 10 0 0
v_cancel_customer_reserve 1414 1414 0 0.00 0 3383 11 0 0
v_cancel_payment_to_supplier 880 880 0 0.00 0 499 3 0 0
v_cancel_supplier_invoice 848 834 14 1.65 0 6622 27 0 14
v_cancel_supplier_order 912 912 0 0.00 0 3605 6 0 0
v_cancel_write_off 1986 1986 0 0.00 0 6673 17 0 0
v_max_id_clo_ord 5547 5547 0 0.00 0 249 1 0 0
v_max_id_clo_res 1027 1027 0 0.00 0 2964 24 0 0
v_max_id_ord_sup 5563 5563 0 0.00 0 5716 7 0 0
v_max_non_paid_invoice 1700 1700 0 0.00 0 165 2 0 0
v_max_non_paid_realizn 3802 3802 0 0.00 0 13653 8 0 0
v_min_id_clo_ord 5547 5547 0 0.00 0 2990 8 0 0
v_min_id_clo_res 1027 1027 0 0.00 0 4411 12 0 0
v_min_id_ord_sup 5563 5563 0 0.00 0 12092 10 0 0
v_min_non_paid_invoice 6023 6023 0 0.00 0 12388 22 0 0
v_min_non_paid_realizn 7125 7125 0 0.00 0 7341 17 0 0
v_our_firm 10440 10440 0 0.00 0 79 1 0 0
v_reserve_write_off 20312 20312 0 0.00 0 6683 6 0 0
x_lock_depdocs_on_canc_invoice 12282 12282 0 0.00 0 20369 112 0 0
x_lock_depdocs_on_canc_sup_ord 456 456 0 0.00 0 8753 359 0 0
x_make_qstorno_1000_1200 5547 5547 0 0.00 2 33781 1125 0 0
x_make_qstorno_1200_2000 5578 5578 0 0.00 2 83479 3137 0 0
x_make_qstorno_2100_3300 476386 476386 0 0.00 0 37388 158 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 02-03-2023 22:40:41 to 02-03-2023 22:40:45.

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
20:08:45 7 8192000000 8798879696 8810168320 5307904 0.064 2 1 2 0 5407504 56816 42784
20:09:46 19405 8192000000 9888288704 10198384640 973083216 10.617 100 84 168 0 1000849280 6195472 21570592
20:11:07 19127 8192000000 9936164128 10254643200 1016702800 11.040 100 91 182 0 1048619088 8347104 23569184
20:12:40 11057 8192000000 9964867024 10291830784 1047254592 11.334 100 90 178 1 1079671856 9543072 22874192
20:13:56 9516 8192000000 10014954784 10357850112 1100413184 11.842 100 74 148 0 1128602080 10700464 17488432
20:15:08 7607 8192000000 10075586432 10430312448 1155890368 12.365 100 80 160 0 1187716720 13441168 18385184
20:16:18 5224 8192000000 10105666560 10471579648 1196038112 12.740 100 68 136 1 1224571264 13606944 14926208
20:17:25 2989 8192000000 10144408160 10516000768 1228768480 13.043 100 83 163 0 1260545616 13956032 17821104
20:18:30 1739 8192000000 10178274192 10555371520 1268385744 13.407 100 79 157 0 1297365984 11985072 16995168
20:19:33 1308 8192000000 10209815312 10593492992 1294560656 13.646 100 89 179 2 1328613072 13265456 20786960
20:20:35 23992 8192000000 10210388240 10604290048 1304031072 13.732 100 67 133 0 1329241984 9895920 15314992
20:22:00 6472 8192000000 10191878912 10608009216 1308576848 13.773 100 33 67 0 1323825968 7575024 7674096
20:23:07 5819 8192000000 10209452624 10622619648 1314985728 13.831 100 23 46 0 1327451104 6923568 5541808
20:24:13 53901 8192000000 10203921360 10626793472 1320484576 13.881 100 31 62 0 1333179808 5496688 7198544
20:26:07 9999 8192000000 10223580656 10642780160 1328763856 13.956 100 15 30 0 1337988496 5953200 3271440
20:27:17 7762 8192000000 10209395232 10629111808 1306677600 13.756 98 22 43 1 1317101504 5476672 4947232
20:28:29 9984 8192000000 10206736432 10623692800 1310635200 13.792 99 24 48 0 1321294336 5287456 5371680
20:29:43 27281 8192000000 10179133872 10594250752 1225100784 13.009 93 22 43 0 1235710208 5318144 5291280
20:31:11 2760 8192000000 10155387392 10558156800 1254986400 13.284 99 25 50 0 1266901104 6606080 5308624
20:32:16 7956 8192000000 10131077152 10529386496 1209514336 12.865 96 22 45 0 1219652640 5099376 5038928
20:33:27 67625 8192000000 10162104416 10565480448 1263107680 13.358 99 28 56 0 1274990080 5908960 5973440
20:35:37 10476 8192000000 10180197040 10581458944 1274252048 13.460 99 32 62 0 1286514944 4912096 7350800
20:36:48 7649 8192000000 10188146352 10597056512 1294418912 13.644 100 21 42 1 1308414160 9514192 4481056
20:37:55 10711 8192000000 10199406032 10613436416 1304732896 13.738 100 20 40 1 1314480976 4980400 4767680
20:39:07 38195 8192000000 10231808832 10636664832 1329184000 13.960 100 49 98 0 1347536272 7763792 10588480
20:40:47 6764 8192000000 10251252096 10663329792 1346553040 14.116 100 52 104 1 1368401168 10132704 11715424
20:41:58 11748 8192000000 10262705488 10675224576 1357655824 14.216 100 46 90 0 1377455504 9946800 9852880
20:43:14 14067 8192000000 10274969536 10695393280 1358654304 14.225 99 42 83 0 1380455104 11985360 9815440
20:44:32 29148 8192000000 10279463872 10707812352 1375221344 14.374 100 43 86 1 1400761632 15173952 10366336
20:46:07 3690 8192000000 10293007088 10715398144 1381681472 14.432 100 50 96 1 1407880416 15024832 11174112
20:47:17 5429 8192000000 10303245728 10728415232 1382270960 14.437 100 40 78 1 1418468192 25956976 10240256
20:48:26 5599 8192000000 10326728176 10749575168 1399856048 14.594 100 51 102 0 1435181280 23741904 11583328
20:49:38 3012 8192000000 10322067904 10743566336 1397291392 14.571 100 64 125 0 1432688096 20471216 14925488
20:50:49 4138 8192000000 10338739664 10757672960 1408479744 14.670 100 67 130 0 1447480944 23032960 15968240
20:51:57 4149 8192000000 10347466432 10767740928 1411009936 14.693 100 69 135 1 1457845264 30415408 16419920
20:53:21 2653 8192000000 10343407104 10764021760 1405130464 14.641 100 59 116 2 1451631344 32057728 14443152
20:54:29 6073 8192000000 10333510224 10758393856 1396922848 14.568 100 58 114 0 1444152928 33169408 14060672
20:55:41 1754 8192000000 10327078112 10733232128 1364551216 14.278 99 88 175 3 1421945072 36510736 20883120
20:56:46 1929 8192000000 10305859696 10709147648 1357141856 14.212 100 74 147 2 1407776944 31814704 18820384
20:57:52 3449 8192000000 10292043040 10698391552 1339917008 14.057 98 70 139 1 1391195744 34103424 17175312
20:59:04 12020 8192000000 10312323424 10741669888 1378202256 14.400 100 41 82 0 1428636032 39687392 10746384
21:00:20 7791 8192000000 10325701520 10750382080 1382559472 14.439 100 35 70 0 1432183200 40349760 9273968
21:01:34 9439 8192000000 10304395104 10721378304 1345624000 14.108 99 39 78 2 1396721136 40556144 10540992
21:02:48 12479 8192000000 10319105088 10744184832 1363323056 14.267 99 35 70 0 1415952240 43444976 9184208
21:04:05 26143 8192000000 10327270704 10750894080 1386147360 14.471 100 47 94 0 1433260416 34457488 12655568
21:05:32 21775 8192000000 10318137968 10760884224 1365288096 14.285 99 26 52 0 1413499632 41047392 7164144
21:07:06 20401 8192000000 10297330656 10736312320 1361593920 14.252 100 17 34 0 1411158416 44004480 5560016
21:08:40 35291 8192000000 10291241056 10717777920 1345700880 14.109 98 32 62 1 1374066368 20724160 7641328
21:10:20 64954 8192000000 10285432320 10730516480 1351727520 14.163 98 11 22 0 1370431120 16312336 2391264
21:12:25 84578 8192000000 10290457024 10726858752 1336503584 14.026 97 20 39 1 1352452464 11484480 4464400
21:14:52 35580 8192000000 10297709376 10731925504 1365637040 14.288 99 31 62 0 1388394464 15659024 7098400
21:17:02 35423 8192000000 10294786592 10733756416 1378885552 14.407 100 28 56 0 1399386416 13955952 6544912
21:18:54 133646 8192000000 10299912944 10759147520 1385193648 14.463 100 21 42 3 1416184448 26155312 4835488
21:23:20 35178 8192000000 10301810512 10749497344 1389772016 14.504 100 17 34 0 1402994688 9227248 3995424
21:24:56 55302 8192000000 10310458672 10747822080 1370818272 14.334 98 25 50 1 1386458976 9565952 6074752
21:26:58 3573 8192000000 10315705408 10757799936 1397476528 14.573 100 57 116 0 1420534048 10015152 13042368
21:28:09 21845 8192000000 10307427312 10769375232 1402532880 14.618 100 13 27 0 1419165440 13472848 3159712
21:29:31 20744 8192000000 10314306432 10772627456 1406347680 14.651 100 5 10 0 1421295872 13669776 1278416
21:30:59 59038 8192000000 10286312432 10760466432 1370550944 14.332 98 10 20 0 1390869216 17740208 2578064
21:33:15 22079 8192000000 10295059024 10756902912 1388966832 14.497 100 35 70 1 1413301136 16730848 7603456
21:34:43 50350 8192000000 10290200192 10757586944 1380337680 14.420 99 8 16 0 1391312560 9102048 1872832
21:36:44 41909 8192000000 10298829696 10746793984 1361462192 14.250 98 37 72 1 1378292112 8247136 8582784
21:38:27 37227 8192000000 10299615200 10759475200 1370341248 14.330 98 18 34 0 1385203488 10740128 4122112
21:40:05 56375 8192000000 10292687312 10756583424 1351113824 14.157 96 19 38 1 1368835392 12828032 4893536
21:42:06 59415 8192000000 10288150720 10734489600 1325421120 13.926 96 14 28 0 1344074224 15802880 2850224
21:44:06 98871 8192000000 10293807264 10755428352 1337471344 14.035 96 19 38 1 1362491792 20417872 4602576
21:46:56 74642 8192000000 10261910032 10717814784 1299652208 13.692 95 17 34 0 1313798992 10274816 3871968
21:49:12 112128 8192000000 10260517984 10716643328 1321856448 13.894 97 32 64 0 1342949360 13994848 7098064
21:52:06 28400 8192000000 10267702048 10717749248 1352915200 14.174 100 31 64 0 1378183472 18432912 6835360
21:53:37 69897 8192000000 10257930512 10725097472 1336894848 14.029 98 15 29 0 1356104176 15709376 3499952
21:55:48 68374 8192000000 10248822176 10696216576 1314474384 13.827 97 2 4 0 1323164480 8356800 333296
21:58:09 68076 8192000000 10283271792 10736541696 1368164400 14.311 100 21 42 0 1389789936 16818928 4806608
22:00:46 80882 8192000000 10282111504 10746613760 1370844304 14.335 100 9 16 1 1396834432 24152544 1837584
22:03:10 52035 8192000000 10292423744 10746302464 1364592688 14.279 99 20 40 1 1379948496 10648528 4707280
22:05:02 22470 8192000000 10301096624 10742829056 1376251488 14.383 100 40 81 0 1395600800 10672064 8677248
22:06:25 44950 8192000000 10294632304 10748391424 1378062976 14.399 100 30 60 1 1396670864 11085200 7522688
22:08:12 118584 8192000000 10294781984 10747883520 1313225360 13.815 94 19 37 0 1329369360 11702608 4441392
22:12:06 99605 8192000000 10307476448 10766589952 1392306736 14.526 100 15 30 1 1404039664 8349488 3383440
22:14:49 45421 8192000000 10298343440 10767368192 1385045824 14.462 99 32 64 1 1412869328 20478832 7344672
22:16:53 46428 8192000000 10301472288 10765901824 1397792768 14.575 100 8 16 0 1405726528 6174928 1758832
22:18:40 91192 8192000000 10301286080 10774818816 1381274368 14.428 98 16 32 0 1398125600 12805728 4045504
22:21:16 34733 8192000000 10282647376 10759168000 1379344192 14.411 99 10 20 0 1396176432 14488800 2343440
22:22:51 71346 8192000000 10292027248 10750930944 1342031056 14.076 97 10 20 0 1354111904 10051712 2029136
22:25:07 48532 8192000000 10286307072 10752638976 1364081232 14.274 98 25 50 1 1379608944 9977072 5550640
22:27:07 34136 8192000000 10285701776 10754826240 1350920496 14.156 97 24 48 1 1369689856 13281920 5487440
22:28:44 61850 8192000000 10294822032 10747691008 1334097776 14.004 96 15 30 0 1346083344 8578096 3407472
22:30:47 51475 8192000000 10279484512 10739929088 1330903440 13.975 96 16 32 0 1350336448 15802224 3630784
22:32:40 64039 8192000000 10282398640 10736590848 1325617904 13.928 96 21 42 0 1340336944 10103760 4615280
22:34:45 120510 8192000000 10265496416 10730303488 1319953296 13.876 96 21 40 0 1341855984 16993744 4908944
22:38:29 25886 8192000000 9506804832 9846390784 651749792 7.369 49 5 10 0 659701280 6981424 970064

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 02-03-2023 22:40:47 to 02-03-2023 22:41:02.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 125 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 02-03-2023 22:41:03 to 02-03-2023 22:41:05.

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          239657
MON$OLDEST_ACTIVE               240587
MON$OLDEST_SNAPSHOT             240587
MON$NEXT_TRANSACTION            240587
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                       2052064
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.33665 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33665 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33665 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33665 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 ---
:896: 
:897: mscs-test-161.budzdorov.ru    Thu Mar  2 22:39:00 2023
:898:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:899: 
--- end of diff output ---

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

02.03.23 22:41:07. 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-03-02 22:41:09 No severe PSQL-related problems occured
02.03.23 22:41:10. End of report.