Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 26.02.2023 19: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 15461700 kB
MemAvailable 30369880 kB
Buffers 0 kB
Cached 16223852 kB
SwapCached 3144 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 3.0.11
DB_NAME                         /home/bases/oltp30-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3333
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp30-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp30
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2023-02-26 19:38:42
DTS_END                         2023-02-26 19:38:42.7220
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/56690
TRN_ID                          238095
ATT_ID                          705
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: 9193.98 24759 1103278
customer order: creation 170.13 350 20416
customer order: refuse 15.04 316 1805
order to supplier: creation 45.33 1172 5439
order to supplier: removal 3.22 98836 386
invoice (draft): creation 46.55 3200 5586
invoice (draft): removal 51.73 704 6208
invoice accept: apply 42.10 17354 5052
invoice accept: cancel 46.83 714 5620
customer reserve: creation 3903.88 172 468465
customer reserve: removal 4570.64 55 548477
realization accept: apply 82.38 138 9885
realization accept: cancel 87.34 102 10481
payment from customer: creation 58.26 100 6991
payment from customer: removal 6.08 71 729
payment to supplier: creation 49.38 109 5925
payment to supplier: removal 3.38 79 405
service: total inventory turnovers 4.50 1131 540
service: total monetary turnovers 6.29 26 755
service: aggregate perf. data 0.91 19567 109
service: refresh index statistics 0.03 350982 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 26-02-2023 19:39:26 to 26-02-2023 19:39:29.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3996
TEST_TIME 2 3908
TEST_TIME 3 4771
TEST_TIME 4 4830
TEST_TIME 5 4733
TEST_TIME 6 4568
TEST_TIME 7 5124
TEST_TIME 8 5095
TEST_TIME 9 5318
TEST_TIME 10 5309
TEST_TIME 11 5681
TEST_TIME 12 5834
TEST_TIME 13 6033
TEST_TIME 14 5943
TEST_TIME 15 6107
TEST_TIME 16 6575
TEST_TIME 17 6817
TEST_TIME 18 7682
TEST_TIME 19 7796
TEST_TIME 20 8174
TEST_TIME 21 8711
TEST_TIME 22 9109
TEST_TIME 23 9142
TEST_TIME 24 9827
TEST_TIME 25 10100
TEST_TIME 26 10548
TEST_TIME 27 10229
TEST_TIME 28 10079
TEST_TIME 29 9877
TEST_TIME 30 9718
TEST_TIME 31 9646
TEST_TIME 32 9559
TEST_TIME 33 9385
TEST_TIME 34 9230
TEST_TIME 35 9399
TEST_TIME 36 9383
TEST_TIME 37 9327
TEST_TIME 38 9263
TEST_TIME 39 9144
TEST_TIME 40 9112
TEST_TIME 41 9079
TEST_TIME 42 9203
TEST_TIME 43 9112
TEST_TIME 44 9076
TEST_TIME 45 9181
TEST_TIME 46 9174
TEST_TIME 47 9091
TEST_TIME 48 9100
TEST_TIME 49 9198
TEST_TIME 50 9287
TEST_TIME 51 9300
TEST_TIME 52 9280
TEST_TIME 53 9380
TEST_TIME 54 9304
TEST_TIME 55 9196
TEST_TIME 56 9138
TEST_TIME 57 9044
TEST_TIME 58 9050
TEST_TIME 59 9093
TEST_TIME 60 9040
TEST_TIME 61 9031
TEST_TIME 62 8949
TEST_TIME 63 8953
TEST_TIME 64 8969
TEST_TIME 65 9113
TEST_TIME 66 9130
TEST_TIME 67 9110
TEST_TIME 68 9063
TEST_TIME 69 9167
TEST_TIME 70 9204
TEST_TIME 71 9226
TEST_TIME 72 9208
TEST_TIME 73 9198
TEST_TIME 74 9217
TEST_TIME 75 9192
TEST_TIME 76 9263
TEST_TIME 77 9195
TEST_TIME 78 9163
TEST_TIME 79 9075
TEST_TIME 80 9062
TEST_TIME 81 9031
TEST_TIME 82 9039
TEST_TIME 83 9061
TEST_TIME 84 9023
TEST_TIME 85 9010
TEST_TIME 86 9013
TEST_TIME 87 8985
TEST_TIME 88 9012
TEST_TIME 89 9051
TEST_TIME 90 9152
TEST_TIME 91 9156
TEST_TIME 92 9156
TEST_TIME 93 9257
TEST_TIME 94 9275
TEST_TIME 95 9291
TEST_TIME 96 9267
TEST_TIME 97 9363
TEST_TIME 98 9371
TEST_TIME 99 9291
TEST_TIME 100 9230
TEST_TIME 101 9230
TEST_TIME 102 9194
TEST_TIME 103 9157
TEST_TIME 104 9109
TEST_TIME 105 9102
TEST_TIME 106 9070
TEST_TIME 107 9061
TEST_TIME 108 9056
TEST_TIME 109 9087
TEST_TIME 110 9070
TEST_TIME 111 9044
TEST_TIME 112 9040
TEST_TIME 113 9154
TEST_TIME 114 9158
TEST_TIME 115 9174
TEST_TIME 116 9162
TEST_TIME 117 9163
TEST_TIME 118 9168
TEST_TIME 119 9154
TEST_TIME 120 9198

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 26-02-2023 19:39:30 to 26-02-2023 19:39:33.

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 1537039 1537039 0 0.00 0 22615 6 0 0
doc_list_biud 1537038 1537038 0 0.00 0 43616 25 0 0
sp_add_invoice_to_stock 5142 5052 90 1.75 27 142425 17354 0 90
sp_cancel_adding_invoice 5620 5620 0 0.00 3 77514 715 0 0
sp_cancel_client_order 1805 1805 0 0.00 4 8920 317 0 0
sp_cancel_customer_reserve 548477 548477 0 0.00 0 43619 55 0 0
sp_cancel_pay_from_customer 729 729 0 0.00 2 5078 72 0 0
sp_cancel_pay_to_supplier 405 405 0 0.00 3 5528 79 0 0
sp_cancel_supplier_invoice 6217 6208 9 0.14 1 29349 704 0 9
sp_cancel_supplier_order 386 386 0 0.00 5 885830 98837 0 0
sp_cancel_write_off 10481 10481 0 0.00 1 9842 103 0 0
sp_client_order 20416 20416 0 0.00 3 23604 350 0 0
sp_customer_reserve 468465 468465 0 0.00 0 38408 172 0 0
sp_fill_shopping_cart 31428 31428 0 0.00 1 22444 41 0 0
sp_fill_shopping_cart_clo_res 468459 468459 0 0.00 0 12139 14 0 0
sp_get_clo_for_invoice 5046 5046 0 0.00 0 35206 362 0 0
sp_kill_cost_storno 17235 17235 0 0.00 0 5782 48 0 0
sp_kill_qstorno_ret_qs2qd 1114029 1114029 0 0.00 0 43613 30 0 0
sp_kill_qty_storno 587907 587907 0 0.00 0 43616 64 0 0
sp_lock_selected_doc 20801 20801 0 0.00 0 7630 5 0 0
sp_make_cost_storno 27847 27847 0 0.00 0 22610 42 0 0
sp_multiply_rows_for_pdistr 27847 27847 0 0.00 0 20560 23 0 0
sp_multiply_rows_for_qdistr 20416 20416 0 0.00 0 15148 164 0 0
sp_pay_from_customer 6991 6991 0 0.00 4 20572 101 0 0
sp_pay_to_supplier 5925 5925 0 0.00 4 22625 110 0 0
sp_payment_common 12916 12916 0 0.00 1 9077 22 0 0
sp_qd_handle_on_cancel_clo 1805 1805 0 0.00 0 8911 292 0 0
sp_qd_handle_on_invoice_upd_sts 10665 10665 0 0.00 0 21637 282 0 0
sp_qd_handle_on_reserve_upd_sts 9885 9885 0 0.00 0 11029 46 0 0
sp_reserve_write_off 9885 9885 0 0.00 6 14796 138 0 0
sp_supplier_invoice 5586 5586 0 0.00 6 89939 3200 0 0
sp_supplier_order 5439 5439 0 0.00 4 39653 1172 0 0
srv_aggregate_perf_data 109 109 0 0.00 2672 373353 19567 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 2193 147766 48473 0 0
srv_find_qd_qs_mism 1055456 1055456 0 0.00 0 15552 5 0 0
srv_make_invnt_saldo 540 540 0 0.00 33 55171 1131 0 0
srv_make_money_saldo 755 755 0 0.00 0 3080 26 0 0
srv_recalc_idx_stat 4 4 0 0.00 110908 628819 350983 0 0
t$perf-abend:sp_add_invoice_to_stock 90 90 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5053 5053 0 0.00 0 14364 160 0 0
t$perf-norm:sp_cancel_adding_invoice 432 432 0 0.00 0 3798 87 0 0
t$perf-norm:sp_cancel_client_order 1805 1805 0 0.00 0 2396 9 0 0
t$perf-norm:sp_cancel_customer_reserve 673 673 0 0.00 0 911 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 729 729 0 0.00 0 1488 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 405 405 0 0.00 0 1942 8 0 0
t$perf-norm:sp_cancel_supplier_invoice 393 393 0 0.00 0 1870 11 0 0
t$perf-norm:sp_cancel_supplier_order 386 386 0 0.00 0 20888 1430 0 0
t$perf-norm:sp_cancel_write_off 1048 1048 0 0.00 0 908 6 0 0
t$perf-norm:sp_client_order 20417 20417 0 0.00 0 16825 15 0 0
t$perf-norm:sp_customer_reserve 923 923 0 0.00 0 1931 5 0 0
t$perf-norm:sp_pay_from_customer 6991 6991 0 0.00 0 16760 10 0 0
t$perf-norm:sp_pay_to_supplier 5925 5925 0 0.00 0 8309 10 0 0
t$perf-norm:sp_reserve_write_off 9885 9885 0 0.00 0 4062 11 0 0
t$perf-norm:sp_supplier_invoice 5587 5587 0 0.00 0 7553 21 0 0
t$perf-norm:sp_supplier_order 5439 5439 0 0.00 0 2316 11 0 0
t$perf-norm:srv_aggregate_perf_data 109 109 0 0.00 0 218 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 705 14 0 0
t$perf-norm:srv_make_invnt_saldo 540 540 0 0.00 0 1441 16 0 0
t$perf-norm:srv_make_money_saldo 755 755 0 0.00 0 8129 22 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 22 6 0 0
v_add_invoice_to_stock 10270 10090 180 1.75 0 12351 21 0 180
v_all_customers 36810 36810 0 0.00 0 124 0 0 0
v_all_suppliers 30646 30646 0 0.00 0 124 1 0 0
v_all_wares 40832 40832 0 0.00 0 123 1 0 0
v_cancel_adding_invoice 864 864 0 0.00 0 5669 18 0 0
v_cancel_client_order 3610 3610 0 0.00 0 3074 4 0 0
v_cancel_customer_prepayment 1458 1458 0 0.00 0 5022 11 0 0
v_cancel_customer_reserve 1346 1346 0 0.00 0 3076 10 0 0
v_cancel_payment_to_supplier 810 810 0 0.00 0 5462 11 0 0
v_cancel_supplier_invoice 804 786 18 2.23 0 2977 22 0 18
v_cancel_supplier_order 772 772 0 0.00 0 1478 4 0 0
v_cancel_write_off 2096 2096 0 0.00 0 4522 17 0 0
v_max_id_clo_ord 5439 5439 0 0.00 0 818 1 0 0
v_max_id_clo_res 923 923 0 0.00 0 2433 13 0 0
v_max_id_ord_sup 5573 5573 0 0.00 0 6195 4 0 0
v_max_non_paid_invoice 1614 1614 0 0.00 0 543 2 0 0
v_max_non_paid_realizn 3834 3834 0 0.00 0 1310 3 0 0
v_min_id_clo_ord 5439 5439 0 0.00 0 2659 5 0 0
v_min_id_clo_res 923 923 0 0.00 0 10016 27 0 0
v_min_id_ord_sup 5573 5573 0 0.00 0 5693 9 0 0
v_min_non_paid_invoice 5925 5925 0 0.00 0 8348 13 0 0
v_min_non_paid_realizn 6991 6991 0 0.00 0 9071 17 0 0
v_our_firm 10336 10336 0 0.00 0 123 1 0 0
v_reserve_write_off 19770 19770 0 0.00 0 6570 6 0 0
x_lock_depdocs_on_canc_invoice 11435 11435 0 0.00 0 15304 112 0 0
x_lock_depdocs_on_canc_sup_ord 386 386 0 0.00 0 5219 292 0 0
x_make_qstorno_1000_1200 5439 5439 0 0.00 1 39636 1110 0 0
x_make_qstorno_1200_2000 5586 5586 0 0.00 2 89578 3039 0 0
x_make_qstorno_2100_3300 468465 468465 0 0.00 0 38407 156 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 26-02-2023 19:39:34 to 26-02-2023 19:39:39.

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:46 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:47 21132 8192000000 9916484944 10229047296 1000213952 10.881 100 87 174 0 1028764560 6043408 22507200
17:11:20 20725 8192000000 9965588736 10291412992 1047140416 11.333 100 90 180 0 1078787888 8702656 22944816
17:12:47 11602 8192000000 9986866976 10321854464 1071118624 11.563 100 77 155 0 1099266384 9001136 19146624
17:14:03 5386 8192000000 10050690912 10399080448 1133434880 12.154 100 84 168 1 1164425312 10820896 20169536
17:15:21 7320 8192000000 10097547808 10461040640 1182556304 12.614 100 77 155 0 1211297280 11908288 16832688
17:16:30 3328 8192000000 10135349280 10504572928 1217180096 12.936 100 80 160 0 1252188176 17435584 17572496
17:17:41 3687 8192000000 10154133968 10530717696 1250187776 13.240 100 80 158 0 1280826752 13644032 16994944
17:18:47 1845 8192000000 10187917808 10569453568 1276599616 13.482 100 79 158 0 1306698624 12572656 17526352
17:19:50 893 8192000000 10215503440 10599018496 1303078464 13.723 100 92 180 0 1336348688 13270512 19999712
17:20:53 4490 8192000000 10204185664 10603487232 1305639040 13.746 100 61 121 3 1328601328 8746624 14215664
17:21:59 4881 8192000000 10202457952 10613166080 1317124240 13.851 100 36 71 0 1331402960 6799136 7479584
17:23:07 9077 8192000000 10197527088 10617876480 1318165920 13.860 100 13 24 1 1326470176 5722144 2582112
17:24:18 34712 8192000000 10211398528 10634018816 1321856112 13.894 100 22 43 0 1332444848 6034608 4554128
17:25:53 10364 8192000000 10214999920 10639671296 1329847040 13.966 100 26 52 0 1342192352 6257088 6088224
17:27:04 8137 8192000000 10222731904 10645487616 1335530944 14.017 100 28 53 0 1347767264 6631456 5604864
17:28:12 53678 8192000000 10202144320 10615021568 1307285248 13.761 98 38 74 0 1321182192 5152304 8744640
17:30:08 5841 8192000000 10136444880 10541588480 1201374944 12.789 93 17 34 0 1214776640 9826512 3575184
17:31:14 9831 8192000000 10138962112 10543112192 1232007456 13.073 97 20 42 0 1241938096 5170736 4759904
17:32:26 6653 8192000000 10134845248 10536534016 1223016912 12.990 97 16 32 0 1231332832 4985840 3330080
17:33:34 19752 8192000000 10150158160 10554966016 1249231792 13.231 99 21 42 0 1259842592 6243104 4367696
17:34:54 7841 8192000000 10166235344 10571755520 1275388976 13.471 100 27 56 0 1288283584 5563104 7331504
17:36:05 8343 8192000000 10191014768 10598658048 1297098896 13.669 100 24 48 0 1308642832 5422240 6121696
17:37:15 52656 8192000000 10214125360 10613379072 1306811424 13.757 100 43 84 0 1321619680 5538752 9269504
17:39:12 8142 8192000000 10238272000 10647670784 1339123760 14.050 100 42 84 2 1355209616 6667808 9418048
17:40:31 15135 8192000000 10264490208 10674294784 1355661104 14.198 100 58 114 2 1381306208 12646416 12998688
17:41:56 14773 8192000000 10275593200 10695172096 1365683056 14.288 100 41 82 1 1387434432 12661200 9090176
17:43:13 30339 8192000000 10293142560 10712428544 1381901888 14.434 100 46 92 0 1407810976 15039136 10869952
17:45:06 9625 8192000000 10311585824 10732748800 1394057552 14.542 100 46 91 1 1423334736 18679968 10597216
17:46:20 8581 8192000000 10319068304 10746736640 1405972640 14.648 100 48 90 2 1435287184 17838528 11476016
17:47:32 27822 8192000000 10309418896 10740330496 1383955584 14.452 98 35 70 0 1416081840 22343760 9782496
17:49:03 5610 8192000000 10342953648 10764877824 1415884752 14.736 100 63 123 1 1454333568 23625152 14823664
17:50:13 2514 8192000000 10361419392 10781138944 1425820432 14.824 100 62 124 0 1465576048 24572800 15182816
17:51:20 7302 8192000000 10365486656 10791862272 1426099616 14.827 100 62 121 0 1477448560 36838208 14510736
17:52:33 15416 8192000000 10341657312 10765844480 1394538640 14.546 99 45 90 0 1440108816 33463808 12106368
17:53:58 2191 8192000000 10349115024 10769797120 1391361248 14.518 99 71 140 1 1450692096 41534144 17796704
17:55:06 2916 8192000000 10379668256 10790985728 1418671760 14.761 100 78 156 2 1481672944 43654864 19346320
17:56:12 2892 8192000000 10355305488 10769190912 1393188416 14.534 99 61 120 0 1450623616 41286384 16148816
17:57:21 6190 8192000000 10353913136 10775932928 1403036288 14.622 99 57 111 1 1453783632 36383952 14363392
17:58:30 13770 8192000000 10335110816 10775261184 1388165456 14.489 99 36 72 0 1445762656 47738336 9858864
17:59:56 33840 8192000000 10302756272 10733981696 1351390448 14.160 98 33 67 0 1399346832 38782128 9174256
18:01:35 25010 8192000000 10303443824 10741559296 1350787104 14.155 99 27 53 2 1396004240 38553200 6663936
18:03:00 38805 8192000000 10282555824 10730557440 1335473696 14.017 98 23 45 0 1376500704 34581104 6445904
18:04:48 26280 8192000000 10238315584 10684624896 1334546256 14.008 100 13 24 1 1352944096 15390304 3007536
18:06:18 75452 8192000000 10273077824 10707742720 1338425680 14.043 98 23 47 0 1353720096 9900016 5394400
18:08:37 52694 8192000000 10250296064 10691399680 1341031136 14.067 99 11 22 0 1356620144 13293328 2295680
18:10:32 31797 8192000000 10246708160 10690088960 1343875280 14.092 99 10 20 0 1356472016 10433024 2163712
18:12:04 47407 8192000000 10271451568 10712395776 1342964304 14.084 98 19 36 0 1358199776 10966880 4268592
18:14:10 97748 8192000000 10254405952 10711068672 1353073728 14.175 99 21 43 0 1374249824 16188064 4988032
18:16:48 50352 8192000000 10266984064 10715103232 1372389936 14.348 100 24 46 1 1383289824 6691280 4208608
18:18:44 59460 8192000000 10263556512 10712539136 1346168992 14.113 98 12 25 0 1357853312 8460224 3224096
18:21:10 34719 8192000000 10289966624 10729717760 1378716016 14.405 100 41 81 0 1397250496 9875792 8658688
18:23:01 58414 8192000000 10282565392 10734903296 1382582368 14.440 100 20 40 0 1403724160 16557168 4584624
18:25:09 22915 8192000000 10302122528 10740404224 1385170336 14.463 100 33 64 1 1401177632 8386464 7620832
18:26:35 75619 8192000000 10305013040 10762125312 1387855088 14.487 99 13 26 0 1403619360 12768016 2996256
18:28:53 87204 8192000000 10300351088 10754727936 1372401104 14.349 98 7 14 0 1385310400 11536592 1372704
18:31:20 126633 8192000000 10318540384 10770976768 1409906816 14.683 100 7 14 0 1428682336 17260416 1515104
18:34:31 52561 8192000000 10314161104 10762645504 1394608304 14.547 99 46 92 0 1417667648 11766064 11293280
18:36:26 31596 8192000000 10305955840 10763272192 1403818544 14.629 100 43 86 0 1433000592 19642144 9539904
18:38:07 32603 8192000000 10291298720 10758127616 1383357744 14.447 99 12 24 0 1399247664 13398464 2491456
18:39:45 71472 8192000000 10295435984 10751594496 1395876640 14.558 100 12 24 0 1406145344 7631568 2637136
18:43:01 66211 8192000000 10272401904 10734104576 1339487984 14.053 96 16 32 0 1358364944 14783552 4093408
18:45:08 43464 8192000000 10285026400 10733125632 1335297584 14.015 96 17 34 0 1349968416 10744176 3926656
18:47:06 53816 8192000000 10284536672 10744791040 1355753424 14.199 98 17 33 0 1372018464 12569520 3695520
18:49:09 63787 8192000000 10278114208 10705584128 1272443216 13.444 92 28 56 0 1290035840 11491248 6101376
18:51:15 15981 8192000000 10279912160 10712993792 1348564368 14.135 99 31 62 0 1364750304 9759616 6426320
18:52:34 46324 8192000000 10276297520 10708901888 1297579872 13.673 95 25 50 0 1311498944 8278640 5640432
18:54:22 117588 8192000000 10252325200 10698305536 1318437104 13.863 97 20 40 1 1342195136 19054384 4703648
18:57:44 21401 8192000000 10271612976 10715832320 1348109216 14.130 99 27 54 0 1365991536 11585856 6296464
18:59:06 64730 8192000000 10245441632 10702516224 1344584912 14.099 99 15 28 1 1360630464 13004832 3040720
19:01:11 58327 8192000000 10247866688 10703790080 1347712112 14.127 99 15 31 0 1363233024 11758752 3762160
19:03:10 51034 8192000000 10267952160 10723401728 1357935072 14.219 99 10 19 0 1371678544 11455104 2288368
19:05:33 53774 8192000000 10285899872 10734022656 1352340032 14.169 98 26 52 0 1373245344 14887968 6017344
19:07:28 76384 8192000000 10273980848 10725920768 1340554928 14.062 97 11 23 0 1358063232 14770752 2737552
19:09:46 95955 8192000000 10294685760 10759790592 1378448464 14.403 100 14 28 0 1395871392 14739296 2683632
19:12:26 67267 8192000000 10304108192 10759041024 1379979008 14.416 99 9 16 1 1393383472 11914816 1489648
19:14:37 39382 8192000000 10329379072 10767630336 1395581264 14.556 99 34 68 0 1416436784 13319088 7536432
19:16:19 147765 8192000000 10304486592 10760163328 1373451984 14.358 98 11 22 0 1388569824 11627200 3490640
19:19:49 40044 8192000000 10282104784 10743517184 1377696912 14.396 99 11 21 1 1386499520 6709680 2092928
19:21:46 126571 8192000000 10290974992 10752856064 1345011552 14.103 96 11 22 0 1360419952 12546544 2861856
19:26:04 70038 8192000000 10301666992 10765193216 1370280240 14.330 98 34 67 0 1395725120 17497056 7947824
19:28:15 69002 8192000000 10285940880 10740252672 1374758688 14.370 99 18 38 0 1388942352 10319888 3863776
19:30:33 81091 8192000000 10285896288 10756427776 1325411664 13.926 95 17 32 0 1344367952 15285216 3671072
19:32:54 61616 8192000000 10281944528 10734260224 1374909632 14.371 100 16 32 0 1390305024 11909856 3485536
19:35:00 115491 8192000000 10286941936 10737938432 1342060640 14.076 97 20 40 0 1356994656 10822992 4111024
19:37:59 52414 8192000000 9058536672 9269862400 224067264 2.662 17 4 8 0 228405776 3565152 773360

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 26-02-2023 19:39:40 to 26-02-2023 19:39:55.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 90 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 26-02-2023 19:39:56 to 26-02-2023 19:39:58.

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          238379
MON$OLDEST_ACTIVE               238380
MON$OLDEST_SNAPSHOT             238380
MON$NEXT_TRANSACTION            238380
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                       2002240
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 ---
:651: 
:652: mscs-test-161.budzdorov.ru    Sun Feb 26 19:38:55 2023
:653:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:654: 
:655: 
:656: mscs-test-161.budzdorov.ru    Sun Feb 26 19:38:55 2023
:657:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:658: 
:659: 
:660: mscs-test-161.budzdorov.ru    Sun Feb 26 19:38:55 2023
:661:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:662: 
:663: 
:664: mscs-test-161.budzdorov.ru    Sun Feb 26 19:38:55 2023
:665:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:666: 
--- end of diff output ---

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

26.02.23 19:39:59. There are 3 logs in /var/tmp/logs-oltp30 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2023-02-26 19:40:02 No severe PSQL-related problems occured
26.02.23 19:40:03. End of report.