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

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-09-24 19:38:27
DTS_END                         2022-09-24 19:38:27.1660
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/45716
TRN_ID                          238450
ATT_ID                          667
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: 9115.09 22317 1093811
customer order: creation 165.95 379 19914
customer order: refuse 14.98 346 1797
order to supplier: creation 44.44 1240 5333
order to supplier: removal 3.29 92095 395
invoice (draft): creation 45.06 3170 5407
invoice (draft): removal 51.09 690 6131
invoice accept: apply 41.14 17495 4937
invoice accept: cancel 46.28 781 5554
customer reserve: creation 3835.75 170 460290
customer reserve: removal 4579.03 53 549484
realization accept: apply 81.17 215 9740
realization accept: cancel 80.20 102 9624
payment from customer: creation 58.54 131 7025
payment from customer: removal 5.57 137 668
payment to supplier: creation 47.60 179 5712
payment to supplier: removal 3.27 87 392
service: total inventory turnovers 4.54 1214 545
service: total monetary turnovers 6.33 27 759
service: aggregate perf. data 0.83 16813 100
service: refresh index statistics 0.03 311010 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 24-09-2022 19:39:34 to 24-09-2022 19:39:37.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3842
TEST_TIME 2 4797
TEST_TIME 3 3698
TEST_TIME 4 4868
TEST_TIME 5 4792
TEST_TIME 6 4596
TEST_TIME 7 4870
TEST_TIME 8 5080
TEST_TIME 9 4994
TEST_TIME 10 5370
TEST_TIME 11 5314
TEST_TIME 12 5246
TEST_TIME 13 5460
TEST_TIME 14 5429
TEST_TIME 15 5664
TEST_TIME 16 6124
TEST_TIME 17 6818
TEST_TIME 18 6899
TEST_TIME 19 7432
TEST_TIME 20 7674
TEST_TIME 21 7728
TEST_TIME 22 8175
TEST_TIME 23 8219
TEST_TIME 24 8053
TEST_TIME 25 8366
TEST_TIME 26 8882
TEST_TIME 27 10025
TEST_TIME 28 10034
TEST_TIME 29 9784
TEST_TIME 30 9755
TEST_TIME 31 9621
TEST_TIME 32 9641
TEST_TIME 33 9418
TEST_TIME 34 9264
TEST_TIME 35 9234
TEST_TIME 36 9073
TEST_TIME 37 9168
TEST_TIME 38 9298
TEST_TIME 39 9170
TEST_TIME 40 9121
TEST_TIME 41 9092
TEST_TIME 42 9056
TEST_TIME 43 9003
TEST_TIME 44 8994
TEST_TIME 45 8948
TEST_TIME 46 8850
TEST_TIME 47 8935
TEST_TIME 48 8942
TEST_TIME 49 8932
TEST_TIME 50 8910
TEST_TIME 51 9037
TEST_TIME 52 9029
TEST_TIME 53 8977
TEST_TIME 54 9126
TEST_TIME 55 9285
TEST_TIME 56 9305
TEST_TIME 57 9306
TEST_TIME 58 9423
TEST_TIME 59 9366
TEST_TIME 60 9448
TEST_TIME 61 9319
TEST_TIME 62 9271
TEST_TIME 63 9254
TEST_TIME 64 9234
TEST_TIME 65 9159
TEST_TIME 66 9082
TEST_TIME 67 9062
TEST_TIME 68 9104
TEST_TIME 69 9160
TEST_TIME 70 9158
TEST_TIME 71 9148
TEST_TIME 72 9173
TEST_TIME 73 9120
TEST_TIME 74 9190
TEST_TIME 75 9244
TEST_TIME 76 9238
TEST_TIME 77 9299
TEST_TIME 78 9365
TEST_TIME 79 9364
TEST_TIME 80 9318
TEST_TIME 81 9241
TEST_TIME 82 9283
TEST_TIME 83 9250
TEST_TIME 84 9229
TEST_TIME 85 9243
TEST_TIME 86 9190
TEST_TIME 87 9184
TEST_TIME 88 9166
TEST_TIME 89 9138
TEST_TIME 90 9065
TEST_TIME 91 9071
TEST_TIME 92 9106
TEST_TIME 93 9107
TEST_TIME 94 9138
TEST_TIME 95 9119
TEST_TIME 96 9129
TEST_TIME 97 9205
TEST_TIME 98 9182
TEST_TIME 99 9231
TEST_TIME 100 9209
TEST_TIME 101 9235
TEST_TIME 102 9261
TEST_TIME 103 9322
TEST_TIME 104 9316
TEST_TIME 105 9307
TEST_TIME 106 9293
TEST_TIME 107 9283
TEST_TIME 108 9237
TEST_TIME 109 9221
TEST_TIME 110 9200
TEST_TIME 111 9161
TEST_TIME 112 9154
TEST_TIME 113 9121
TEST_TIME 114 9087
TEST_TIME 115 9081
TEST_TIME 116 9086
TEST_TIME 117 9130
TEST_TIME 118 9137
TEST_TIME 119 9146
TEST_TIME 120 9116

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 24-09-2022 19:39:38 to 24-09-2022 19:39:42.

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1518412 1518412 0 0.00 0 21931 6 0 0
doc_list_biud 1518410 1518410 0 0.00 0 26009 24 0 0
sp_add_invoice_to_stock 5025 4937 88 1.75 42 175251 17496 0 88
sp_cancel_adding_invoice 5554 5554 0 0.00 4 80919 781 0 0
sp_cancel_client_order 1797 1797 0 0.00 4 12504 346 0 0
sp_cancel_customer_reserve 549484 549484 0 0.00 0 20610 53 0 0
sp_cancel_pay_from_customer 668 668 0 0.00 2 18368 138 0 0
sp_cancel_pay_to_supplier 392 392 0 0.00 2 3953 87 0 0
sp_cancel_supplier_invoice 6142 6131 11 0.17 2 26033 690 0 11
sp_cancel_supplier_order 395 395 0 0.00 6 1007206 92095 0 0
sp_cancel_write_off 9624 9624 0 0.00 1 12431 103 0 0
sp_client_order 19914 19914 0 0.00 3 24743 380 0 0
sp_customer_reserve 460290 460290 0 0.00 0 55442 171 0 0
sp_fill_shopping_cart 30636 30636 0 0.00 1 23930 40 0 0
sp_fill_shopping_cart_clo_res 460288 460288 0 0.00 0 16420 14 0 0
sp_get_clo_for_invoice 4935 4935 0 0.00 0 26714 319 0 0
sp_kill_cost_storno 16238 16238 0 0.00 0 17398 50 0 0
sp_kill_qstorno_ret_qs2qd 1115118 1115118 0 0.00 0 26003 29 0 0
sp_kill_qty_storno 587659 587659 0 0.00 0 26007 62 0 0
sp_lock_selected_doc 20367 20367 0 0.00 0 7723 6 0 0
sp_make_cost_storno 27412 27412 0 0.00 0 20025 58 0 0
sp_multiply_rows_for_pdistr 27412 27412 0 0.00 0 21920 35 0 0
sp_multiply_rows_for_qdistr 19914 19914 0 0.00 0 12818 146 0 0
sp_pay_from_customer 7025 7025 0 0.00 4 21211 132 0 0
sp_pay_to_supplier 5712 5712 0 0.00 4 22512 179 0 0
sp_payment_common 12737 12737 0 0.00 1 20157 56 0 0
sp_qd_handle_on_cancel_clo 1797 1797 0 0.00 0 12497 322 0 0
sp_qd_handle_on_invoice_upd_sts 10488 10488 0 0.00 0 20147 286 0 0
sp_qd_handle_on_reserve_upd_sts 9740 9740 0 0.00 0 12750 66 0 0
sp_reserve_write_off 9740 9740 0 0.00 6 21301 215 0 0
sp_supplier_invoice 5407 5407 0 0.00 4 92831 3171 0 0
sp_supplier_order 5333 5333 0 0.00 5 51361 1240 0 0
srv_aggregate_perf_data 100 100 0 0.00 2566 93591 16813 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 2207 117703 47585 0 0
srv_find_qd_qs_mism 1046145 1046145 0 0.00 0 13879 5 0 0
srv_make_invnt_saldo 545 545 0 0.00 27 62686 1215 0 0
srv_make_money_saldo 759 759 0 0.00 1 3681 27 0 0
srv_recalc_idx_stat 4 4 0 0.00 149164 457331 311011 0 0
t$perf-abend:sp_add_invoice_to_stock 88 88 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4937 4937 0 0.00 0 26481 156 0 0
t$perf-norm:sp_cancel_adding_invoice 410 410 0 0.00 1 2503 74 0 0
t$perf-norm:sp_cancel_client_order 1797 1797 0 0.00 0 1873 10 0 0
t$perf-norm:sp_cancel_customer_reserve 650 650 0 0.00 0 1986 16 0 0
t$perf-norm:sp_cancel_pay_from_customer 668 668 0 0.00 0 347 2 0 0
t$perf-norm:sp_cancel_pay_to_supplier 392 392 0 0.00 0 5891 17 0 0
t$perf-norm:sp_cancel_supplier_invoice 393 393 0 0.00 0 622 14 0 0
t$perf-norm:sp_cancel_supplier_order 395 395 0 0.00 0 17464 1584 0 0
t$perf-norm:sp_cancel_write_off 988 988 0 0.00 0 5302 15 0 0
t$perf-norm:sp_client_order 19914 19914 0 0.00 0 8610 16 0 0
t$perf-norm:sp_customer_reserve 954 954 0 0.00 0 6587 16 0 0
t$perf-norm:sp_pay_from_customer 7025 7025 0 0.00 0 2540 7 0 0
t$perf-norm:sp_pay_to_supplier 5712 5712 0 0.00 0 7786 9 0 0
t$perf-norm:sp_reserve_write_off 9740 9740 0 0.00 0 5993 8 0 0
t$perf-norm:sp_supplier_invoice 5407 5407 0 0.00 0 6923 18 0 0
t$perf-norm:sp_supplier_order 5333 5333 0 0.00 0 8928 16 0 0
t$perf-norm:srv_aggregate_perf_data 100 100 0 0.00 0 602 14 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 78 3 0 0
t$perf-norm:srv_make_invnt_saldo 545 545 0 0.00 0 898 16 0 0
t$perf-norm:srv_make_money_saldo 759 759 0 0.00 0 1702 16 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 10044 9868 176 1.75 0 19503 23 0 176
v_all_customers 36522 36522 0 0.00 0 17 1 0 0
v_all_suppliers 29614 29614 0 0.00 0 55 1 0 0
v_all_wares 39828 39828 0 0.00 0 15 1 0 0
v_cancel_adding_invoice 820 820 0 0.00 0 19601 33 0 0
v_cancel_client_order 3594 3594 0 0.00 0 3790 5 0 0
v_cancel_customer_prepayment 1336 1336 0 0.00 0 10760 14 0 0
v_cancel_customer_reserve 1300 1300 0 0.00 0 9356 12 0 0
v_cancel_payment_to_supplier 784 784 0 0.00 0 1739 6 0 0
v_cancel_supplier_invoice 808 786 22 2.72 0 3185 17 0 22
v_cancel_supplier_order 790 790 0 0.00 0 662 4 0 0
v_cancel_write_off 1976 1976 0 0.00 0 12314 27 0 0
v_max_id_clo_ord 5330 5330 0 0.00 0 1332 2 0 0
v_max_id_clo_res 954 954 0 0.00 0 2889 13 0 0
v_max_id_ord_sup 5392 5392 0 0.00 0 5894 7 0 0
v_max_non_paid_invoice 1627 1627 0 0.00 0 5923 7 0 0
v_max_non_paid_realizn 3677 3677 0 0.00 0 4513 5 0 0
v_min_id_clo_ord 5330 5330 0 0.00 0 3281 7 0 0
v_min_id_clo_res 954 954 0 0.00 0 2252 6 0 0
v_min_id_ord_sup 5392 5392 0 0.00 0 6007 6 0 0
v_min_non_paid_invoice 5712 5712 0 0.00 0 19764 56 0 0
v_min_non_paid_realizn 7025 7025 0 0.00 0 20147 41 0 0
v_our_firm 10002 10002 0 0.00 0 28 1 0 0
v_reserve_write_off 19480 19480 0 0.00 0 21218 11 0 0
x_lock_depdocs_on_canc_invoice 11292 11292 0 0.00 0 20904 133 0 0
x_lock_depdocs_on_canc_sup_ord 395 395 0 0.00 0 17459 390 0 0
x_make_qstorno_1000_1200 5333 5333 0 0.00 1 51349 1164 0 0
x_make_qstorno_1200_2000 5407 5407 0 0.00 2 85946 3022 0 0
x_make_qstorno_2100_3300 460290 460290 0 0.00 0 51863 154 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 24-09-2022 19:39:43 to 24-09-2022 19:39:47.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
17:08:31 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:32 22226 8192000000 9900161776 10206445568 976412912 10.649 100 86 173 0 1004762096 5568144 22781040
17:10:59 10752 8192000000 9932992448 10253291520 1018418976 11.057 100 83 166 0 1047659696 7678128 21562592
17:12:13 15868 8192000000 9975065632 10303504384 1054032944 11.399 100 82 164 0 1083511120 9014336 20463840
17:13:32 13269 8192000000 10025278704 10371575808 1111309504 11.945 100 76 152 0 1140292544 10483680 18499360
17:14:57 8146 8192000000 10093759584 10451697664 1179006128 12.581 100 80 160 0 1209582976 11949584 18627264
17:16:07 3343 8192000000 10126683056 10499248128 1219785504 12.960 100 79 155 1 1249604288 12810320 17008464
17:17:13 1642 8192000000 10164107264 10541846528 1254314784 13.278 100 77 151 0 1283906560 13263632 16328144
17:18:16 939 8192000000 10203241216 10588442624 1286427184 13.572 100 91 182 1 1320859344 14262368 20169792
17:19:19 1440 8192000000 10211349872 10595667968 1301494672 13.709 100 81 160 3 1332144784 13243488 17406624
17:20:21 6076 8192000000 10188668784 10600882176 1302203328 13.715 100 33 64 0 1317310400 8324512 6782560
17:21:29 9615 8192000000 10199629296 10614620160 1315200992 13.833 100 19 38 0 1325387888 5354192 4832704
17:22:39 8886 8192000000 10207026864 10625056768 1320119360 13.878 100 17 34 0 1330482992 6591920 3771712
17:23:48 32232 8192000000 10220647344 10638835712 1325115760 13.923 100 21 42 0 1335314352 5421520 4777072
17:26:16 6127 8192000000 10230398688 10653097984 1333970048 14.003 100 30 60 0 1347801456 7047664 6783744
17:27:26 8859 8192000000 10216278976 10631127040 1301959104 13.713 98 17 35 1 1311319120 5359648 4000368
17:28:35 51899 8192000000 10169697008 10568527872 1235541696 13.105 95 28 52 2 1247343984 5403184 6399104
17:30:35 4601 8192000000 10147088160 10552115200 1234698816 13.097 97 27 53 2 1246275472 5817840 5758816
17:31:43 6280 8192000000 10136533200 10537168896 1226316896 13.020 98 20 40 0 1236209648 5047920 4844832
17:32:50 17878 8192000000 10157398544 10561884160 1254042288 13.275 99 18 37 1 1263265040 4947696 4275056
17:34:12 39265 8192000000 10164879616 10567970816 1257805872 13.310 99 43 86 0 1272999120 4954736 10238512
17:35:52 8544 8192000000 10179245744 10590031872 1286133408 13.569 100 26 52 0 1297118736 5369984 5615344
17:37:03 8457 8192000000 10187528272 10601455616 1294476096 13.645 100 13 26 0 1303029888 5616672 2937120
17:38:15 12060 8192000000 10209403104 10624393216 1310238528 13.788 100 15 31 0 1322257664 8754560 3264576
17:39:36 36196 8192000000 10232216656 10645483520 1331259392 13.979 100 35 70 1 1347191856 7001200 8931264
17:41:14 59841 8192000000 10250261680 10672439296 1347668784 14.126 100 37 69 1 1366547072 11444096 7434192
17:43:34 23607 8192000000 10274729616 10697805824 1362142352 14.257 100 43 84 1 1384926768 12817024 9967392
17:45:12 4995 8192000000 10287668736 10705088512 1370142624 14.328 100 55 109 0 1399028848 15525488 13360736
17:46:21 4329 8192000000 10296829296 10709753856 1366450192 14.295 99 62 124 1 1408153072 27115632 14587248
17:47:34 5824 8192000000 10308686032 10724454400 1380206656 14.418 100 52 104 0 1419569680 27351968 12011056
17:48:51 7296 8192000000 10303638416 10728755200 1380625648 14.422 100 38 72 0 1417272720 27720944 8926128
17:50:01 10823 8192000000 10311210416 10732187648 1386566992 14.475 100 56 109 0 1425173664 25663552 12943120
17:51:19 5653 8192000000 10345596288 10758324224 1397338800 14.571 100 64 129 0 1446192160 32892096 15961264
17:52:33 3766 8192000000 10337997648 10749779968 1390412112 14.510 100 64 125 0 1442666736 36093616 16161008
17:53:42 3779 8192000000 10332788160 10736226304 1366596624 14.297 99 76 147 2 1426853776 42128816 18128336
17:54:50 2202 8192000000 10340029888 10738507776 1373933424 14.362 100 93 183 3 1440449488 43867648 22648416
17:55:54 10724 8192000000 10311886288 10732945408 1351868624 14.164 98 40 81 1 1405488944 42893648 10726672
17:57:10 5664 8192000000 10297519616 10715418624 1350122096 14.149 100 47 94 1 1407440528 45144704 12173728
17:58:22 11192 8192000000 10304677456 10735153152 1369649344 14.324 100 28 57 0 1415010016 38102928 7257744
17:59:37 7551 8192000000 10333948608 10754007040 1381241808 14.428 100 54 106 0 1440929008 45329728 14357472
18:00:47 13871 8192000000 10294663568 10723487744 1351150752 14.158 99 23 46 0 1395819488 38302304 6366432
18:02:15 36374 8192000000 10313701904 10740400128 1355063440 14.193 99 22 44 0 1406825504 45347168 6414896
18:04:00 24832 8192000000 10306148032 10755559424 1359376080 14.232 99 22 42 2 1416719952 50950992 6392880
18:05:27 49917 8192000000 10255166288 10690113536 1325490848 13.926 98 9 18 0 1361008512 32616464 2901200
18:07:17 34013 8192000000 10277473360 10706173952 1361166624 14.248 100 48 97 0 1380441408 7141536 12133248
18:09:33 85822 8192000000 10247528416 10702249984 1321469552 13.890 97 16 31 1 1335446192 10618064 3358576
18:12:00 96335 8192000000 10285842832 10732130304 1361524512 14.251 99 22 43 0 1379089008 12564624 4999872
18:14:36 68247 8192000000 10286473488 10733867008 1376385040 14.384 100 31 58 1 1399800192 15201408 8213744
18:16:50 67076 8192000000 10282465888 10733793280 1378906112 14.407 100 17 35 0 1393563888 11061648 3596128
18:19:23 36095 8192000000 10274167264 10726903808 1370595776 14.332 99 11 22 0 1385714272 12433488 2685008
18:21:03 83298 8192000000 10277258256 10733490176 1354799712 14.191 98 22 42 1 1371419712 11161904 5458096
18:23:26 73579 8192000000 10266156928 10731347968 1351989616 14.165 98 16 33 0 1372005328 15995248 4020464
18:25:40 79400 8192000000 10285850672 10744868864 1367069216 14.301 99 23 45 0 1381128192 8035600 6023376
18:28:47 52380 8192000000 10301073904 10760003584 1378379600 14.402 98 25 50 0 1400091072 15542608 6168864
18:30:46 59012 8192000000 10312228304 10760445952 1386021984 14.470 99 26 52 0 1407205520 15296752 5886784
18:32:51 62905 8192000000 10308093488 10777837568 1383163808 14.445 99 7 14 0 1400465136 15952656 1348672
18:34:54 41073 8192000000 10311874976 10759389184 1388076336 14.489 99 27 54 0 1407208352 12724912 6407104
18:36:58 14246 8192000000 10308561456 10753986560 1394052720 14.542 100 24 49 0 1406631808 7118544 5460544
18:38:21 72331 8192000000 10298535024 10756255744 1393960896 14.541 100 27 54 0 1412885824 13239456 5685472
18:40:38 62035 8192000000 10259645968 10707664896 1303094320 13.723 94 25 51 1 1320693696 11969264 5630112
18:42:41 40098 8192000000 10250178304 10695311360 1322916992 13.903 97 4 8 0 1329112976 5793616 402368
18:44:24 55385 8192000000 10277017344 10729365504 1367785872 14.307 100 12 24 0 1379506256 8659504 3060880
18:46:28 77875 8192000000 10284623712 10750296064 1333668304 14.000 97 12 24 0 1361046768 24509840 2868624
18:48:46 42074 8192000000 10257119184 10703212544 1295635264 13.656 94 17 34 0 1308167504 8906064 3626176
18:50:33 38104 8192000000 10256065024 10696290304 1324764720 13.920 98 21 42 0 1337566384 7969376 4832288
18:52:13 117701 8192000000 10264593776 10706022400 1297314112 13.671 95 16 30 1 1318076128 17328368 3433648
18:55:11 46417 8192000000 10281694592 10734518272 1333056400 13.995 98 14 28 0 1364130944 27746048 3328496
18:56:57 77736 8192000000 10267470432 10708152320 1328486800 13.953 97 12 24 0 1346303840 15457040 2360000
18:59:17 86423 8192000000 10274741104 10717368320 1366743184 14.298 100 44 88 1 1387066112 9627856 10695072
19:02:03 70963 8192000000 10274892720 10731663360 1373800704 14.361 100 16 31 0 1388599024 11273728 3524592
19:04:15 46860 8192000000 10287810800 10733436928 1383807264 14.451 100 9 18 0 1393929760 7672736 2449760
19:06:13 45809 8192000000 10286418288 10736308224 1389025040 14.497 100 13 26 0 1401427280 9086560 3315680
19:08:01 79486 8192000000 10261956432 10713296896 1357295968 14.213 99 24 48 0 1371783728 9440768 5046992
19:10:21 73490 8192000000 10274199584 10735071232 1353025760 14.175 98 15 30 0 1370500944 13984208 3490976
19:12:37 65327 8192000000 10294733472 10753179648 1392058000 14.524 100 14 28 0 1410777136 15435088 3284048
19:14:46 83851 8192000000 10289116592 10755031040 1360241824 14.240 98 25 50 1 1384801328 18998784 5560720
19:17:11 101956 8192000000 10307691536 10763132928 1398854736 14.585 100 18 37 0 1413102736 10351456 3896544
19:19:53 21616 8192000000 10314833600 10758819840 1394747408 14.548 100 29 59 0 1412796368 11605952 6443008
19:21:24 65446 8192000000 10298874416 10746232832 1366287712 14.294 98 15 30 0 1386539024 17232400 3018912
19:23:29 27826 8192000000 10299675680 10740350976 1350682624 14.154 97 39 78 0 1371237408 12383920 8170864
19:25:05 102905 8192000000 10280329856 10741669888 1338656320 14.045 97 19 38 0 1360238688 17396016 4186352
19:28:01 51764 8192000000 10250651152 10710192128 1336923680 14.030 97 10 21 0 1347938096 8982848 2031568
19:29:55 32525 8192000000 10254806064 10711150592 1350400224 14.151 99 7 14 0 1357638944 5989728 1248992
19:31:31 81115 8192000000 10262277520 10716794880 1325142944 13.923 96 22 44 0 1341049248 10894576 5011728
19:34:02 72196 8192000000 10276966992 10728726528 1332041344 13.986 97 12 24 0 1350570272 15995424 2533504
19:36:15 64485 8192000000 10280213632 10732544000 1370301616 14.330 100 18 36 0 1384411360 9783376 4326368

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 24-09-2022 19:39:49 to 24-09-2022 19:40:04.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 88 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 24-09-2022 19:40:05 to 24-09-2022 19:40:07.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          237651
MON$OLDEST_ACTIVE               239063
MON$OLDEST_SNAPSHOT             239063
MON$NEXT_TRANSACTION            239063
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                       2017920
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.33629 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33629 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33629 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33629 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 ---
:23: 
:24: mscs-test-161.budzdorov.ru    Sat Sep 24 19:38:30 2022
:25:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:26: 
:27: 
:28: mscs-test-161.budzdorov.ru    Sat Sep 24 19:38:34 2022
:29:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:30: 
:31: 
:32: mscs-test-161.budzdorov.ru    Sat Sep 24 19:38:34 2022
:33:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:34: 
--- end of diff output ---

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

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