Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 01.03.2023 04: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 15307316 kB
MemAvailable 30431188 kB
Buffers 20 kB
Cached 16368456 kB
SwapCached 3084 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 4.0.3
DB_NAME                         /home/bases/oltp40-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/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/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=3400
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/oltp40-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-oltp40
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-01 04:37:27
DTS_END                         2023-03-01 04:37:27.0680
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(68:17)
IP                              ::1/51900
TRN_ID                          218912
ATT_ID                          612
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: 8727.06 30437 1047247
customer order: creation 162.23 488 19468
customer order: refuse 14.11 394 1693
order to supplier: creation 44.39 1397 5327
order to supplier: removal 3.19 149132 383
invoice (draft): creation 43.94 3456 5273
invoice (draft): removal 46.41 960 5569
invoice accept: apply 41.08 26980 4930
invoice accept: cancel 42.39 1160 5087
customer reserve: creation 3856.19 255 462743
customer reserve: removal 4193.01 88 503161
realization accept: apply 81.95 280 9834
realization accept: cancel 71.22 166 8546
payment from customer: creation 58.26 180 6991
payment from customer: removal 5.58 144 669
payment to supplier: creation 47.89 220 5747
payment to supplier: removal 3.33 166 399
service: total inventory turnovers 3.94 4032 473
service: total monetary turnovers 7.04 390 845
service: aggregate perf. data 0.88 14038 105
service: refresh index statistics 0.03 404810 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 01-03-2023 04:39:25 to 01-03-2023 04:39:27.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7890
TEST_TIME 2 5608
TEST_TIME 3 5579
TEST_TIME 4 5966
TEST_TIME 5 5883
TEST_TIME 6 5822
TEST_TIME 7 6015
TEST_TIME 8 5988
TEST_TIME 9 6051
TEST_TIME 10 5961
TEST_TIME 11 6277
TEST_TIME 12 6604
TEST_TIME 13 6704
TEST_TIME 14 6964
TEST_TIME 15 7224
TEST_TIME 16 7223
TEST_TIME 17 7367
TEST_TIME 18 7371
TEST_TIME 19 7695
TEST_TIME 20 7803
TEST_TIME 21 8088
TEST_TIME 22 8161
TEST_TIME 23 8264
TEST_TIME 24 8588
TEST_TIME 25 8586
TEST_TIME 26 8695
TEST_TIME 27 9077
TEST_TIME 28 9389
TEST_TIME 29 9707
TEST_TIME 30 9872
TEST_TIME 31 10658
TEST_TIME 32 10593
TEST_TIME 33 10437
TEST_TIME 34 10438
TEST_TIME 35 10302
TEST_TIME 36 10190
TEST_TIME 37 9988
TEST_TIME 38 9984
TEST_TIME 39 9916
TEST_TIME 40 9861
TEST_TIME 41 9796
TEST_TIME 42 9767
TEST_TIME 43 9707
TEST_TIME 44 9634
TEST_TIME 45 9535
TEST_TIME 46 9484
TEST_TIME 47 9352
TEST_TIME 48 9270
TEST_TIME 49 9181
TEST_TIME 50 9204
TEST_TIME 51 9207
TEST_TIME 52 9131
TEST_TIME 53 9050
TEST_TIME 54 8937
TEST_TIME 55 8873
TEST_TIME 56 8920
TEST_TIME 57 8843
TEST_TIME 58 8812
TEST_TIME 59 8770
TEST_TIME 60 8806
TEST_TIME 61 8845
TEST_TIME 62 8808
TEST_TIME 63 8803
TEST_TIME 64 8805
TEST_TIME 65 8830
TEST_TIME 66 8775
TEST_TIME 67 8884
TEST_TIME 68 8927
TEST_TIME 69 8966
TEST_TIME 70 8938
TEST_TIME 71 9033
TEST_TIME 72 9106
TEST_TIME 73 9059
TEST_TIME 74 9010
TEST_TIME 75 8935
TEST_TIME 76 8964
TEST_TIME 77 8935
TEST_TIME 78 8893
TEST_TIME 79 8894
TEST_TIME 80 8845
TEST_TIME 81 8791
TEST_TIME 82 8754
TEST_TIME 83 8706
TEST_TIME 84 8726
TEST_TIME 85 8716
TEST_TIME 86 8680
TEST_TIME 87 8737
TEST_TIME 88 8757
TEST_TIME 89 8776
TEST_TIME 90 8766
TEST_TIME 91 8777
TEST_TIME 92 8806
TEST_TIME 93 8790
TEST_TIME 94 8807
TEST_TIME 95 8887
TEST_TIME 96 8910
TEST_TIME 97 8832
TEST_TIME 98 8805
TEST_TIME 99 8772
TEST_TIME 100 8801
TEST_TIME 101 8769
TEST_TIME 102 8724
TEST_TIME 103 8675
TEST_TIME 104 8644
TEST_TIME 105 8646
TEST_TIME 106 8709
TEST_TIME 107 8726
TEST_TIME 108 8705
TEST_TIME 109 8694
TEST_TIME 110 8717
TEST_TIME 111 8696
TEST_TIME 112 8814
TEST_TIME 113 8793
TEST_TIME 114 8874
TEST_TIME 115 8887
TEST_TIME 116 8913
TEST_TIME 117 8878
TEST_TIME 118 8855
TEST_TIME 119 8799
TEST_TIME 120 8750

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 01-03-2023 04:39:29 to 01-03-2023 04:39:32.

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 1470637 1470637 0 0.00 0 26186 14 0 0
doc_list_biud 1470620 1470620 0 0.00 0 39136 36 0 0
sp_add_invoice_to_stock 5038 4930 108 2.14 30 204343 26981 0 108
sp_cancel_adding_invoice 5087 5087 0 0.00 7 67967 1161 0 0
sp_cancel_client_order 1693 1693 0 0.00 5 10291 394 0 0
sp_cancel_customer_reserve 503161 503161 0 0.00 1 22550 89 0 0
sp_cancel_pay_from_customer 669 669 0 0.00 4 16362 145 0 0
sp_cancel_pay_to_supplier 399 399 0 0.00 4 5734 167 0 0
sp_cancel_supplier_invoice 5576 5569 7 0.12 3 39145 961 0 7
sp_cancel_supplier_order 383 383 0 0.00 7 1287746 149133 0 0
sp_cancel_write_off 8546 8546 0 0.00 2 11306 167 0 0
sp_client_order 19468 19468 0 0.00 6 32697 488 0 0
sp_customer_reserve 462743 462743 0 0.00 1 83256 255 0 0
sp_fill_shopping_cart 30058 30058 0 0.00 1 43844 85 0 0
sp_fill_shopping_cart_clo_res 462715 462715 0 0.00 0 15577 20 0 0
sp_get_clo_for_invoice 4888 4888 0 0.00 0 46944 405 0 0
sp_kill_cost_storno 14701 14701 0 0.00 0 11727 74 0 0
sp_kill_qstorno_ret_qs2qd 1020820 1020820 0 0.00 0 39134 39 0 0
sp_kill_qty_storno 539156 539156 0 0.00 0 39136 90 0 0
sp_lock_selected_doc 20266 20266 0 0.00 0 12240 9 0 0
sp_make_cost_storno 27460 27460 0 0.00 0 26174 88 0 0
sp_multiply_rows_for_pdistr 27456 27456 0 0.00 0 9485 41 0 0
sp_multiply_rows_for_qdistr 19467 19467 0 0.00 0 20082 177 0 0
sp_pay_from_customer 6991 6991 0 0.00 5 15153 181 0 0
sp_pay_to_supplier 5747 5747 0 0.00 6 22190 221 0 0
sp_payment_common 12736 12736 0 0.00 2 22171 47 0 0
sp_qd_handle_on_cancel_clo 1693 1693 0 0.00 0 8526 318 0 0
sp_qd_handle_on_invoice_upd_sts 9971 9971 0 0.00 0 11494 483 0 0
sp_qd_handle_on_reserve_upd_sts 9833 9833 0 0.00 0 14148 66 0 0
sp_reserve_write_off 9834 9834 0 0.00 8 14845 280 0 0
sp_supplier_invoice 5273 5273 0 0.00 8 88234 3457 0 0
sp_supplier_order 5327 5327 0 0.00 8 49582 1398 0 0
srv_aggregate_perf_data 105 105 0 0.00 2699 120963 14039 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 894 123235 35817 0 0
srv_find_qd_qs_mism 998002 998002 0 0.00 0 12855 11 0 0
srv_make_invnt_saldo 473 473 0 0.00 72 76715 4032 0 0
srv_make_money_saldo 845 845 0 0.00 1 16780 391 0 0
srv_recalc_idx_stat 4 4 0 0.00 210608 562982 404810 0 0
t$perf-abend:sp_add_invoice_to_stock 108 108 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 4934 4934 0 0.00 0 18344 855 0 0
t$perf-norm:sp_cancel_adding_invoice 397 397 0 0.00 2 5251 405 0 0
t$perf-norm:sp_cancel_client_order 1693 1693 0 0.00 0 1403 24 0 0
t$perf-norm:sp_cancel_customer_reserve 685 685 0 0.00 0 827 22 0 0
t$perf-norm:sp_cancel_pay_from_customer 669 669 0 0.00 0 223 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 399 399 0 0.00 0 455 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 360 360 0 0.00 0 1550 34 0 0
t$perf-norm:sp_cancel_supplier_order 383 383 0 0.00 0 77597 5818 0 0
t$perf-norm:sp_cancel_write_off 970 970 0 0.00 0 10000 30 0 0
t$perf-norm:sp_client_order 19470 19470 0 0.00 0 10005 33 0 0
t$perf-norm:sp_customer_reserve 984 984 0 0.00 0 991 13 0 0
t$perf-norm:sp_pay_from_customer 6991 6991 0 0.00 0 10015 20 0 0
t$perf-norm:sp_pay_to_supplier 5747 5747 0 0.00 0 2815 17 0 0
t$perf-norm:sp_reserve_write_off 9834 9834 0 0.00 0 11183 22 0 0
t$perf-norm:sp_supplier_invoice 5273 5273 0 0.00 0 6911 31 0 0
t$perf-norm:sp_supplier_order 5328 5328 0 0.00 0 7224 30 0 0
t$perf-norm:srv_aggregate_perf_data 105 105 0 0.00 0 594 18 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 36 3 0 0
t$perf-norm:srv_make_invnt_saldo 473 473 0 0.00 0 7518 26 0 0
t$perf-norm:srv_make_money_saldo 845 845 0 0.00 0 4257 13 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 4 176 67 0 0
v_add_invoice_to_stock 9970 9754 216 2.16 0 10128 28 0 216
v_all_customers 35887 35887 0 0.00 0 70 3 0 0
v_all_suppliers 29739 29739 0 0.00 0 71 4 0 0
v_all_wares 38932 38932 0 0.00 0 117 4 0 0
v_cancel_adding_invoice 794 794 0 0.00 0 530 13 0 0
v_cancel_client_order 3386 3386 0 0.00 0 3586 11 0 0
v_cancel_customer_prepayment 1338 1338 0 0.00 0 4692 14 0 0
v_cancel_customer_reserve 1370 1370 0 0.00 1 2756 11 0 0
v_cancel_payment_to_supplier 798 798 0 0.00 0 5713 30 0 0
v_cancel_supplier_invoice 734 720 14 1.90 0 1281 25 0 14
v_cancel_supplier_order 766 766 0 0.00 0 3337 14 0 0
v_cancel_write_off 1940 1940 0 0.00 0 1031 22 0 0
v_max_id_clo_ord 5325 5325 0 0.00 0 185 5 0 0
v_max_id_clo_res 984 984 0 0.00 0 1736 13 0 0
v_max_id_ord_sup 5266 5266 0 0.00 0 3967 7 0 0
v_max_non_paid_invoice 1467 1467 0 0.00 0 506 7 0 0
v_max_non_paid_realizn 3665 3665 0 0.00 0 1069 7 0 0
v_min_id_clo_ord 5325 5325 0 0.00 0 3920 11 0 0
v_min_id_clo_res 984 984 0 0.00 0 2494 11 0 0
v_min_id_ord_sup 5266 5266 0 0.00 0 7367 13 0 0
v_min_non_paid_invoice 5746 5746 0 0.00 0 22163 23 0 0
v_min_non_paid_realizn 6990 6990 0 0.00 0 7695 19 0 0
v_our_firm 9694 9694 0 0.00 0 70 4 0 0
v_reserve_write_off 19666 19666 0 0.00 0 7155 12 0 0
x_lock_depdocs_on_canc_invoice 10296 10296 0 0.00 0 10157 135 0 0
x_lock_depdocs_on_canc_sup_ord 383 383 0 0.00 0 6960 342 0 0
x_make_qstorno_1000_1200 5327 5327 0 0.00 2 43994 1231 0 0
x_make_qstorno_1200_2000 5273 5273 0 0.00 3 83103 3214 0 0
x_make_qstorno_2100_3300 462740 462740 0 0.00 0 83224 223 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 01-03-2023 04:39:33 to 01-03-2023 04:39:37.

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
02:07:31 1 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
02:08:32 12858 8192000000 9702638192 9997787136 785919664 8.753 100 87 173 0 814577920 7213136 21445120
02:09:48 10541 8192000000 9753221216 10055196672 833387440 9.233 100 93 187 0 866845072 11263168 22194464
02:11:12 18131 8192000000 9790599888 10105044992 859738608 9.498 100 83 167 0 894353056 15202864 19411584
02:12:40 9624 8192000000 9843976384 10176933888 912893680 10.026 100 80 160 0 960304560 29557984 17852896
02:13:56 5794 8192000000 9899331472 10240483328 955507184 10.445 100 82 157 0 1017521312 45974992 16039136
02:15:08 2622 8192000000 9957594368 10306547712 977325216 10.658 100 89 177 1 1073650032 77901968 18422848
02:16:14 2201 8192000000 9996226768 10354536448 1009770592 10.973 100 91 174 1 1102876816 75988704 17117520
02:17:19 1111 8192000000 9996266352 10360053760 1029788288 11.166 100 91 169 2 1113515328 66278784 17448256
02:18:22 1059 8192000000 10024980256 10394435584 1048664528 11.348 100 99 190 0 1144313296 76033920 19614848
02:19:25 994 8192000000 10027001328 10401628160 1059299136 11.450 100 89 174 3 1149026352 71956016 17771200
02:20:28 4735 8192000000 10031926672 10407321600 1068058592 11.534 100 80 148 4 1157654432 75289488 14306352
02:21:33 993 8192000000 10036067584 10423091200 1074564288 11.596 100 85 163 0 1159139152 67480464 17094400
02:22:36 973 8192000000 10049421600 10433937408 1078503552 11.633 100 92 181 0 1171623264 74606128 18513584
02:23:38 1297 8192000000 10049831264 10432819200 1084649696 11.692 100 98 182 5 1174302160 70612928 19039536
02:24:40 1092 8192000000 10062223168 10450825216 1093874864 11.779 100 89 172 0 1185217888 74577936 16765088
02:25:42 1089 8192000000 10058627888 10447306752 1096740560 11.807 100 95 177 1 1180182016 65501472 17939984
02:26:44 2046 8192000000 10064476288 10450857984 1099532432 11.833 100 85 166 2 1181268848 64012896 17723520
02:27:47 1571 8192000000 10083939808 10479144960 1100616320 11.843 100 79 152 2 1204591584 88600352 15374912
02:28:49 885 8192000000 10075818768 10467389440 1093348256 11.774 100 87 161 2 1194832560 85324880 16159424
02:29:51 1253 8192000000 10051468688 10436014080 1092495280 11.766 100 96 185 3 1175131824 63595536 19041008
02:30:54 1055 8192000000 10021308144 10402549760 1082291920 11.669 100 89 170 2 1140218944 40106272 17820752
02:31:55 1230 8192000000 10018472400 10398085120 1066688320 11.520 100 96 181 2 1142219616 56423792 19107504
02:32:57 6409 8192000000 10003728016 10388357120 1051252640 11.373 99 56 110 3 1125634576 63121536 11260400
02:34:05 1459 8192000000 9994190992 10379943936 1048682112 11.348 100 75 138 1 1117929072 52767280 16479680
02:35:08 939 8192000000 10007085456 10386350080 1050019696 11.361 100 84 159 0 1127350912 60920096 16411120
02:36:09 1191 8192000000 9955700928 10320433152 1016205200 11.035 100 95 179 3 1080945168 45544720 19195248
02:37:11 6283 8192000000 9992818512 10379223040 1053594608 11.395 100 59 111 1 1116146768 51357584 11194576
02:38:23 1657 8192000000 10008838672 10388922368 1056083664 11.419 100 82 160 1 1128281040 56148416 16048960
02:39:28 17924 8192000000 10019480912 10410287104 1066602096 11.520 100 68 136 0 1143577328 63495584 13479648
02:40:49 10036 8192000000 10052261312 10453868544 1083406512 11.680 100 64 122 1 1171484528 75801888 12276128
02:42:16 7354 8192000000 10067556544 10452578304 1092933936 11.771 100 81 156 2 1185391984 75428304 17029744
02:43:30 6639 8192000000 10114200032 10516717568 1111695840 11.948 100 72 140 0 1242345936 115630656 15019440
02:44:41 1574 8192000000 10168602208 10557636608 1122191984 12.048 100 87 164 1 1279614688 140226224 17196480
02:45:47 10274 8192000000 10189451600 10605240320 1135358576 12.172 100 65 130 0 1303773440 153503600 14911264
02:47:05 12033 8192000000 10220176080 10620424192 1144119968 12.254 100 73 143 0 1330846864 171613040 15113856
02:48:24 1974 8192000000 10292904736 10692292608 1164508432 12.445 100 90 178 0 1411068176 226022432 20537312
02:49:28 1063 8192000000 10277457680 10675478528 1169511904 12.492 100 97 185 0 1392281760 202251136 20518720
02:50:31 888 8192000000 10313059200 10718908416 1178480992 12.576 100 88 168 1 1424933200 226839856 19612352
02:51:33 1029 8192000000 10321232128 10755403776 1179241712 12.583 100 92 174 2 1437766384 238721456 19803216
02:52:38 1517 8192000000 10331773296 10736058368 1177624384 12.568 99 88 165 0 1441373600 245561040 18188176
02:53:42 1016 8192000000 10388745424 10789900288 1184975632 12.637 100 92 181 2 1493777408 287841344 20960432
02:54:46 1050 8192000000 10433391744 10837434368 1195970672 12.739 100 97 194 0 1538313936 320859232 21484032
02:55:49 940 8192000000 10458829280 10884993024 1191350224 12.696 100 98 193 0 1563412144 349472304 22589616
02:56:52 1264 8192000000 10455819504 10870943744 1192261840 12.704 100 96 189 0 1566435328 352125408 22048080
02:57:55 1220 8192000000 10460854016 10900766720 1192745600 12.709 100 98 192 0 1563676880 349320736 21610544
02:58:58 2134 8192000000 10456709088 10892480512 1189713456 12.681 100 81 155 3 1558670032 350785488 18171088
03:00:04 6311 8192000000 10437062736 10878582784 1162749360 12.429 100 62 119 2 1543139936 365491808 14898768
03:01:14 3120 8192000000 10455086560 10911764480 1169179184 12.489 100 51 100 0 1563943616 381985536 12778896
03:02:25 3061 8192000000 10429243360 10846924800 1162705984 12.429 100 70 126 1 1539762448 362269152 14787312
03:03:32 2296 8192000000 10393795216 10876129280 1168198288 12.480 100 54 107 0 1501526048 319937056 13390704
03:04:38 14241 8192000000 10394784944 10842955776 1167565040 12.474 100 45 90 0 1496805104 317719392 11520672
03:06:07 45475 8192000000 10329959952 10852884480 1157361792 12.379 100 27 55 0 1457838240 293353616 7122832
03:08:21 39565 8192000000 10114960256 10591977472 1134095536 12.160 99 12 24 0 1287252288 149938976 3217776
03:10:01 23102 8192000000 10122628896 10557366272 1136865504 12.186 99 38 74 0 1214494960 69545840 8083616
03:11:36 57448 8192000000 10135192944 10586771456 1139339104 12.209 99 34 66 0 1231323088 84429536 7554448
03:13:50 53095 8192000000 10145817376 10592243712 1150307616 12.312 100 24 47 0 1244212576 88786656 5118304
03:15:44 97089 8192000000 10112041232 10635878400 1117410240 12.003 97 15 28 1 1207536992 87017632 3109120
03:18:42 111385 8192000000 10125926032 10590642176 1129338016 12.115 98 17 34 0 1238574688 105444848 3791824
03:21:47 92613 8192000000 10092437792 10548387840 1104157360 11.877 96 34 68 0 1142534240 31719552 6657328
03:24:40 13728 8192000000 10065912336 10532089856 1133871904 12.158 99 39 67 1 1165237568 23588816 7776848
03:25:55 31960 8192000000 10091641456 10535997440 1123848080 12.063 98 28 56 1 1190798720 61146352 5804288
03:27:28 54239 8192000000 10082777872 10557636608 1139649488 12.212 100 20 39 0 1209774944 66279616 3845840
03:30:16 52860 8192000000 10091922720 10552795136 1132467360 12.145 99 19 38 1 1198196352 61918592 3810400
03:32:12 27648 8192000000 10088874016 10549825536 1116586944 11.995 98 56 105 1 1185668272 57219952 11861376
03:33:40 42617 8192000000 10065792112 10534535168 1088478368 11.728 96 17 34 1 1147580656 55245744 3856544
03:35:24 56839 8192000000 10057715744 10538438656 1124382992 12.068 100 12 24 1 1201772272 74716544 2672736
03:37:27 59815 8192000000 10039812096 10559885312 1089796944 11.741 97 18 33 0 1161117008 67771600 3548464
03:39:48 65413 8192000000 10081551552 10543493120 1121377584 12.040 100 27 54 0 1188028832 61433296 5217952
03:42:19 33336 8192000000 10130831760 10588348416 1119761648 12.025 98 23 46 1 1228608480 103691008 5155824
03:43:54 81765 8192000000 10118507360 10587615232 1113382688 11.964 98 21 42 0 1271551216 153162768 5005760
03:46:32 48334 8192000000 10129872944 10580520960 1117472032 12.003 98 31 60 0 1182607616 58928080 6207504
03:48:21 45654 8192000000 10051632032 10521309184 1099799792 11.836 97 23 44 0 1163398496 58523632 5075072
03:50:08 23628 8192000000 10055392032 10480066560 1082028080 11.667 96 35 57 2 1128730736 40354896 6347760
03:51:32 123234 8192000000 10051333648 10546368512 1113968272 11.970 99 36 70 0 1207940576 86467392 7504912
03:54:45 68918 8192000000 10051532640 10509082624 1095387472 11.794 97 21 42 0 1145600448 46258800 3954176
03:56:55 59353 8192000000 10035172352 10483126272 1077190128 11.621 96 7 14 0 1096209616 17304784 1714704
03:58:55 106204 8192000000 10070223040 10543030272 1104327696 11.879 98 12 24 0 1156970016 50208752 2433568
04:01:42 85372 8192000000 10071148816 10512838656 1080759040 11.655 96 10 20 0 1150266624 67573008 1934576
04:04:15 45274 8192000000 10092861872 10542702592 1133088240 12.150 100 24 46 0 1188161328 50322688 4750400
04:06:01 48833 8192000000 10063249024 10506964992 1127329680 12.096 99 20 38 2 1179430144 48160176 3940288
04:07:53 24197 8192000000 10103040432 10543128576 1116833488 11.997 98 42 84 0 1178792528 54172336 7786704
04:09:20 22114 8192000000 10135867152 10565890048 1122216528 12.048 98 46 92 0 1196899552 65657984 9025040
04:10:44 31688 8192000000 10130830608 10586550272 1154975200 12.356 100 44 88 0 1248149424 84334592 8839632
04:12:23 30265 8192000000 10092150032 10553864192 1094674944 11.787 96 31 61 0 1165193936 64733328 5785664
04:13:54 99482 8192000000 10083047824 10518999040 1137257424 12.190 100 37 73 1 1181726128 37100784 7367920
04:16:35 4081 8192000000 10101772704 10537029632 1144146272 12.255 100 55 106 3 1206341360 50698720 11496368
04:17:40 63347 8192000000 10048090448 10544222208 1114816944 11.978 98 7 14 0 1152331840 36556016 958880
04:19:44 36428 8192000000 10079039808 10531078144 1123476416 12.060 99 12 24 0 1178632304 52469008 2686880
04:21:43 38361 8192000000 10124623584 10572255232 1101274096 11.850 96 20 40 0 1200043232 95013216 3755920
04:23:28 43174 8192000000 10120916656 10554519552 1132240304 12.142 100 30 58 0 1239323808 100200976 6882528
04:25:16 12529 8192000000 10129236448 10563117056 1140716192 12.222 100 42 82 1 1222392528 73109376 8566960
04:26:29 19127 8192000000 10165446784 10592276480 1115291616 11.982 98 47 90 0 1261209104 136515936 9401552
04:27:59 65714 8192000000 10166986752 10604601344 1045245360 11.315 92 23 46 0 1195292336 144926224 5120752
04:30:05 52910 8192000000 10122898976 10544836608 1078870336 11.637 97 29 56 1 1188737200 103448432 6418432
04:32:04 7970 8192000000 10080584320 10499174400 1099551616 11.833 99 56 110 0 1161048368 50407776 11088976
04:33:15 52473 8192000000 10110450448 10577801216 1112886080 11.960 99 33 66 0 1192480832 73151760 6442992
04:35:11 97685 8192000000 10073713040 10520469504 1109867024 11.931 99 27 54 1 1189761120 74110544 5783552

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 01-03-2023 04:39:39 to 01-03-2023 04:39:56.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 108 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 01-03-2023 04:39:57 to 01-03-2023 04:39:59.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          217282
MON$OLDEST_ACTIVE               219710
MON$OLDEST_SNAPSHOT             219710
MON$NEXT_TRANSACTION            219710
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-30 07:30:08.6030 GMT
MON$PAGES                       2031008
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             778
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2904 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2904 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2904 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2904 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.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 ---
:29: 
:30: mscs-test-161.budzdorov.ru    Wed Mar  1 04:37:29 2023
:31:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:32: 
:33: 
:34: mscs-test-161.budzdorov.ru    Wed Mar  1 04:37:32 2023
:35:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:36: 
:37: 
:38: mscs-test-161.budzdorov.ru    Wed Mar  1 04:37:36 2023
:39:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:40: 
--- end of diff output ---

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

01.03.23 04:40:00. There are 3 logs in /var/tmp/logs-oltp40 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-01 04:40:02 No severe PSQL-related problems occured
01.03.23 04:40:04. End of report.