Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 18.10.2022 13:37
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 15556508 kB
MemAvailable 30463112 kB
Buffers 0 kB
Cached 16134924 kB
SwapCached 2052 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 2022-10-18 13:37:22
DTS_END                         2022-10-18 13:37:22.2620
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/58540
TRN_ID                          222146
ATT_ID                          595
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: 8878.69 22455 1065443
customer order: creation 174.69 462 20963
customer order: refuse 14.50 366 1740
order to supplier: creation 45.58 1350 5470
order to supplier: removal 3.16 155052 379
invoice (draft): creation 47.09 2806 5651
invoice (draft): removal 48.58 977 5829
invoice accept: apply 41.32 25532 4958
invoice accept: cancel 43.90 1108 5268
customer reserve: creation 3854.28 244 462513
customer reserve: removal 4311.30 90 517356
realization accept: apply 84.54 242 10145
realization accept: cancel 78.68 155 9442
payment from customer: creation 59.93 164 7192
payment from customer: removal 5.78 121 694
payment to supplier: creation 49.35 159 5922
payment to supplier: removal 3.43 117 411
service: total inventory turnovers 4.42 3260 530
service: total monetary turnovers 7.23 313 867
service: aggregate perf. data 0.90 16084 108
service: refresh index statistics 0.04 240499 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 18-10-2022 13:38:10 to 18-10-2022 13:38:13.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4042
TEST_TIME 2 4499
TEST_TIME 3 4266
TEST_TIME 4 4097
TEST_TIME 5 4760
TEST_TIME 6 4826
TEST_TIME 7 5194
TEST_TIME 8 5510
TEST_TIME 9 5651
TEST_TIME 10 6063
TEST_TIME 11 6384
TEST_TIME 12 6537
TEST_TIME 13 6645
TEST_TIME 14 7027
TEST_TIME 15 7232
TEST_TIME 16 7152
TEST_TIME 17 7162
TEST_TIME 18 7547
TEST_TIME 19 8103
TEST_TIME 20 8035
TEST_TIME 21 8361
TEST_TIME 22 8186
TEST_TIME 23 8428
TEST_TIME 24 8815
TEST_TIME 25 9336
TEST_TIME 26 9667
TEST_TIME 27 10666
TEST_TIME 28 11055
TEST_TIME 29 10932
TEST_TIME 30 10677
TEST_TIME 31 10482
TEST_TIME 32 10247
TEST_TIME 33 10230
TEST_TIME 34 10131
TEST_TIME 35 10064
TEST_TIME 36 10221
TEST_TIME 37 10121
TEST_TIME 38 10118
TEST_TIME 39 9964
TEST_TIME 40 9854
TEST_TIME 41 9763
TEST_TIME 42 9699
TEST_TIME 43 9502
TEST_TIME 44 9503
TEST_TIME 45 9425
TEST_TIME 46 9557
TEST_TIME 47 9565
TEST_TIME 48 9516
TEST_TIME 49 9495
TEST_TIME 50 9458
TEST_TIME 51 9456
TEST_TIME 52 9511
TEST_TIME 53 9553
TEST_TIME 54 9572
TEST_TIME 55 9523
TEST_TIME 56 9589
TEST_TIME 57 9677
TEST_TIME 58 9689
TEST_TIME 59 9570
TEST_TIME 60 9520
TEST_TIME 61 9514
TEST_TIME 62 9529
TEST_TIME 63 9434
TEST_TIME 64 9330
TEST_TIME 65 9283
TEST_TIME 66 9224
TEST_TIME 67 9259
TEST_TIME 68 9249
TEST_TIME 69 9202
TEST_TIME 70 9192
TEST_TIME 71 9151
TEST_TIME 72 9127
TEST_TIME 73 9133
TEST_TIME 74 9204
TEST_TIME 75 9231
TEST_TIME 76 9291
TEST_TIME 77 9432
TEST_TIME 78 9384
TEST_TIME 79 9298
TEST_TIME 80 9252
TEST_TIME 81 9266
TEST_TIME 82 9236
TEST_TIME 83 9181
TEST_TIME 84 9120
TEST_TIME 85 9127
TEST_TIME 86 9081
TEST_TIME 87 9015
TEST_TIME 88 9063
TEST_TIME 89 9028
TEST_TIME 90 9011
TEST_TIME 91 9003
TEST_TIME 92 9004
TEST_TIME 93 9041
TEST_TIME 94 9085
TEST_TIME 95 9084
TEST_TIME 96 9072
TEST_TIME 97 9033
TEST_TIME 98 9167
TEST_TIME 99 9224
TEST_TIME 100 9260
TEST_TIME 101 9247
TEST_TIME 102 9182
TEST_TIME 103 9150
TEST_TIME 104 9100
TEST_TIME 105 9073
TEST_TIME 106 9081
TEST_TIME 107 9038
TEST_TIME 108 9007
TEST_TIME 109 8945
TEST_TIME 110 8939
TEST_TIME 111 8920
TEST_TIME 112 8905
TEST_TIME 113 8888
TEST_TIME 114 8887
TEST_TIME 115 8910
TEST_TIME 116 8897
TEST_TIME 117 8875
TEST_TIME 118 8952
TEST_TIME 119 8957
TEST_TIME 120 8898

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 18-10-2022 13:38:14 to 18-10-2022 13:38:17.

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 1490283 1490283 0 0.00 0 14444 12 0 0
doc_list_biud 1490275 1490275 0 0.00 0 61656 37 0 0
sp_add_invoice_to_stock 5067 4958 109 2.15 28 245854 25533 0 109
sp_cancel_adding_invoice 5268 5268 0 0.00 5 86344 1108 0 0
sp_cancel_client_order 1740 1740 0 0.00 6 7392 366 0 0
sp_cancel_customer_reserve 517356 517356 0 0.00 1 61660 90 0 0
sp_cancel_pay_from_customer 694 694 0 0.00 4 9239 121 0 0
sp_cancel_pay_to_supplier 411 411 0 0.00 3 5128 117 0 0
sp_cancel_supplier_invoice 5835 5829 6 0.10 2 33829 978 0 6
sp_cancel_supplier_order 379 379 0 0.00 25 1368337 155052 0 0
sp_cancel_write_off 9442 9442 0 0.00 2 10485 156 0 0
sp_client_order 20963 20963 0 0.00 5 33399 463 0 0
sp_customer_reserve 462513 462513 0 0.00 1 69225 244 0 0
sp_fill_shopping_cart 32081 32081 0 0.00 2 10680 73 0 0
sp_fill_shopping_cart_clo_res 462474 462474 0 0.00 0 21083 20 0 0
sp_get_clo_for_invoice 4910 4910 0 0.00 0 32396 308 0 0
sp_kill_cost_storno 15815 15815 0 0.00 0 11500 63 0 0
sp_kill_qstorno_ret_qs2qd 1050362 1050362 0 0.00 0 61622 40 0 0
sp_kill_qty_storno 555069 555069 0 0.00 0 61655 92 0 0
sp_lock_selected_doc 20774 20774 0 0.00 0 4425 6 0 0
sp_make_cost_storno 28169 28169 0 0.00 0 13077 69 0 0
sp_multiply_rows_for_pdistr 28169 28169 0 0.00 0 8410 30 0 0
sp_multiply_rows_for_qdistr 20963 20963 0 0.00 0 12471 187 0 0
sp_pay_from_customer 7192 7192 0 0.00 5 13063 164 0 0
sp_pay_to_supplier 5922 5922 0 0.00 6 7997 159 0 0
sp_payment_common 13114 13114 0 0.00 2 5309 37 0 0
sp_qd_handle_on_cancel_clo 1740 1740 0 0.00 0 7343 297 0 0
sp_qd_handle_on_invoice_upd_sts 10178 10178 0 0.00 0 15583 448 0 0
sp_qd_handle_on_reserve_upd_sts 10145 10145 0 0.00 0 36767 54 0 0
sp_reserve_write_off 10145 10145 0 0.00 8 36950 242 0 0
sp_supplier_invoice 5651 5651 0 0.00 11 74587 2806 0 0
sp_supplier_order 5470 5470 0 0.00 6 55712 1350 0 0
srv_aggregate_perf_data 108 108 0 0.00 2418 116062 16084 0 0
srv_fill_mon_cache_memory 71 71 0 0.00 1036 131519 36086 0 0
srv_find_qd_qs_mism 1015576 1015576 0 0.00 0 14442 10 0 0
srv_make_invnt_saldo 530 530 0 0.00 58 67677 3260 0 0
srv_make_money_saldo 867 867 0 0.00 1 10563 314 0 0
srv_recalc_idx_stat 5 5 0 0.00 129315 345873 240499 0 0
t$perf-abend:sp_add_invoice_to_stock 109 109 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4958 4958 0 0.00 1 36622 799 0 0
t$perf-norm:sp_cancel_adding_invoice 423 423 0 0.00 1 6940 403 0 0
t$perf-norm:sp_cancel_client_order 1740 1740 0 0.00 0 2860 26 0 0
t$perf-norm:sp_cancel_customer_reserve 641 641 0 0.00 0 1150 23 0 0
t$perf-norm:sp_cancel_pay_from_customer 694 694 0 0.00 0 3708 17 0 0
t$perf-norm:sp_cancel_pay_to_supplier 411 411 0 0.00 0 539 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 383 383 0 0.00 0 3142 41 0 0
t$perf-norm:sp_cancel_supplier_order 379 379 0 0.00 0 65423 5127 0 0
t$perf-norm:sp_cancel_write_off 1049 1049 0 0.00 0 2062 15 0 0
t$perf-norm:sp_client_order 20963 20963 0 0.00 0 5222 29 0 0
t$perf-norm:sp_customer_reserve 963 963 0 0.00 0 1603 14 0 0
t$perf-norm:sp_pay_from_customer 7192 7192 0 0.00 0 11693 16 0 0
t$perf-norm:sp_pay_to_supplier 5922 5922 0 0.00 0 10158 19 0 0
t$perf-norm:sp_reserve_write_off 10145 10145 0 0.00 0 5263 20 0 0
t$perf-norm:sp_supplier_invoice 5651 5651 0 0.00 0 2780 23 0 0
t$perf-norm:sp_supplier_order 5470 5470 0 0.00 0 5394 27 0 0
t$perf-norm:srv_aggregate_perf_data 108 108 0 0.00 0 482 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 71 71 0 0.00 0 983 25 0 0
t$perf-norm:srv_make_invnt_saldo 530 530 0 0.00 0 684 16 0 0
t$perf-norm:srv_make_money_saldo 867 867 0 0.00 0 4309 10 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 4 103 53 0 0
v_add_invoice_to_stock 10036 9818 218 2.17 0 4540 22 0 218
v_all_customers 38624 38624 0 0.00 0 162 3 0 0
v_all_suppliers 30980 30980 0 0.00 0 68 4 0 0
v_all_wares 41924 41924 0 0.00 0 93 3 0 0
v_cancel_adding_invoice 846 846 0 0.00 0 3031 17 0 0
v_cancel_client_order 3480 3480 0 0.00 0 4902 10 0 0
v_cancel_customer_prepayment 1388 1388 0 0.00 0 8628 20 0 0
v_cancel_customer_reserve 1282 1282 0 0.00 0 1429 10 0 0
v_cancel_payment_to_supplier 822 822 0 0.00 0 1580 13 0 0
v_cancel_supplier_invoice 778 766 12 1.54 0 4327 25 0 12
v_cancel_supplier_order 758 758 0 0.00 0 3270 16 0 0
v_cancel_write_off 2098 2098 0 0.00 0 1877 21 0 0
v_max_id_clo_ord 5470 5470 0 0.00 0 194 4 0 0
v_max_id_clo_res 963 963 0 0.00 0 2810 19 0 0
v_max_id_ord_sup 5649 5649 0 0.00 0 3117 6 0 0
v_max_non_paid_invoice 1551 1551 0 0.00 0 346 6 0 0
v_max_non_paid_realizn 3538 3538 0 0.00 0 652 6 0 0
v_min_id_clo_ord 5470 5470 0 0.00 0 1391 6 0 0
v_min_id_clo_res 963 963 0 0.00 0 6969 29 0 0
v_min_id_ord_sup 5649 5649 0 0.00 0 5442 10 0 0
v_min_non_paid_invoice 5922 5922 0 0.00 0 3430 11 0 0
v_min_non_paid_realizn 7192 7192 0 0.00 0 5281 17 0 0
v_our_firm 10608 10608 0 0.00 0 79 4 0 0
v_reserve_write_off 20290 20290 0 0.00 0 9650 13 0 0
x_lock_depdocs_on_canc_invoice 10714 10714 0 0.00 0 11840 118 0 0
x_lock_depdocs_on_canc_sup_ord 379 379 0 0.00 0 13001 338 0 0
x_make_qstorno_1000_1200 5470 5470 0 0.00 2 55697 1214 0 0
x_make_qstorno_1200_2000 5651 5651 0 0.00 5 74412 2598 0 0
x_make_qstorno_2100_3300 462513 462513 0 0.00 0 69214 213 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 18-10-2022 13:38:18 to 18-10-2022 13:38:23.

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
11:07:26 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
11:08:27 27060 8192000000 9734394800 10035015680 809932912 8.997 100 87 172 1 838227520 7570368 20724240
11:09:59 17235 8192000000 9764094496 10075463680 841237024 9.312 100 86 172 0 873168016 11561776 20369216
11:11:21 11520 8192000000 9794652160 10115342336 868842240 9.588 100 79 156 0 906095712 19097024 18156448
11:12:43 7932 8192000000 9858377728 10189152256 911216592 10.009 100 85 166 0 965008928 35648448 18143888
11:13:58 5227 8192000000 9918064384 10257920000 946198272 10.354 100 81 156 0 1023166880 60217600 16751008
11:15:08 2021 8192000000 9968334848 10317721600 989291184 10.775 100 90 179 0 1073461072 65686304 18483584
11:16:12 1767 8192000000 9989019376 10348470272 1017918544 11.052 100 92 181 0 1105935472 70280336 17736592
11:17:16 1464 8192000000 10000412256 10365505536 1030732896 11.176 100 92 170 2 1117704368 68992256 17979216
11:18:19 860 8192000000 10047186864 10421125120 1053449936 11.394 100 94 171 2 1160605248 90041248 17114064
11:19:21 811 8192000000 10029047200 10402029568 1062286768 11.478 100 96 185 2 1145431712 64629744 18515200
11:20:24 989 8192000000 10048277488 10424442880 1070737008 11.559 100 98 190 1 1163662576 73370464 19555104
11:21:28 1014 8192000000 10050507072 10431254528 1078508896 11.633 100 94 177 2 1172295920 75379344 18407680
11:22:30 1197 8192000000 10051034880 10439917568 1084129232 11.687 100 94 181 1 1169793776 67585360 18079184
11:23:32 1217 8192000000 10051473344 10433970176 1089220656 11.735 100 94 185 2 1177721584 69377296 19123632
11:24:35 1051 8192000000 10044727632 10431008768 1090824032 11.750 100 92 176 3 1167149584 58747760 17577792
11:25:38 2144 8192000000 10067824176 10461097984 1097083968 11.810 100 73 142 0 1189305056 77568416 14652672
11:26:41 1151 8192000000 10104508480 10493739008 1105816976 11.893 100 97 180 1 1225661760 101197376 18647408
11:27:43 944 8192000000 10043737840 10428116992 1090887920 11.751 100 85 153 1 1164497008 58189680 15419408
11:28:44 1112 8192000000 10059088688 10444939264 1087067696 11.715 100 96 185 1 1177719312 70462288 20189328
11:29:46 1193 8192000000 10063228464 10450210816 1090530048 11.748 100 85 161 0 1181432800 74106928 16795824
11:30:48 1520 8192000000 10012133424 10404593664 1069993872 11.552 100 71 132 3 1140667776 55811648 14862256
11:31:50 1039 8192000000 10019399520 10405195776 1071373344 11.565 100 81 148 0 1140141872 53815616 14952912
11:32:53 1451 8192000000 10000724672 10380570624 1049156576 11.353 99 82 147 3 1122541184 59483856 13900752
11:33:55 1023 8192000000 9995595056 10362613760 1037140000 11.237 100 96 186 0 1117607072 60098480 20368592
11:34:57 1061 8192000000 9998867824 10373292032 1033303968 11.200 100 94 175 1 1120718192 68762432 18651792
11:35:59 1257 8192000000 9985316368 10358112256 1043425248 11.298 100 93 174 2 1106234448 44129808 18679392
11:37:01 1346 8192000000 9958743216 10335019008 1041859136 11.283 99 86 148 5 1090373680 31614464 16900080
11:38:11 12925 8192000000 9999377968 10380259328 1043720736 11.300 99 59 115 0 1124017840 69290480 11006624
11:39:32 3049 8192000000 10053415648 10434998272 1080404192 11.651 100 75 131 0 1173520688 79520256 13596240
11:40:40 3368 8192000000 10087195808 10475487232 1093166464 11.773 100 72 143 1 1206693472 98586864 14940144
11:41:59 16409 8192000000 10112735280 10513698816 1090101024 11.744 100 63 123 1 1241066768 138012864 12952880
11:43:21 4645 8192000000 10148223120 10537545728 1110571040 11.938 100 73 142 0 1265652000 140450000 14630960
11:44:30 3976 8192000000 10183126608 10577952768 1132218800 12.142 100 74 138 0 1307454912 160284672 14951440
11:45:40 1529 8192000000 10232625536 10625560576 1147330800 12.284 100 90 172 0 1348170640 182374240 18465600
11:46:45 1373 8192000000 10256883184 10665467904 1159761296 12.401 100 94 172 2 1370517856 190752976 20003584
11:47:49 1341 8192000000 10302181216 10701479936 1165265456 12.453 100 92 174 1 1416875808 232203664 19406688
11:48:53 1069 8192000000 10321199712 10728255488 1169955184 12.496 100 95 187 0 1436699456 245020896 21723376
11:49:56 1165 8192000000 10357980512 10757144576 1176272048 12.555 100 90 175 1 1470230128 274344656 19613424
11:51:00 1336 8192000000 10380682736 10800525312 1183789024 12.626 100 89 174 1 1500748592 295761792 21197776
11:52:05 1366 8192000000 10401331648 10822160384 1183561312 12.623 100 91 176 0 1513033776 309168960 20303504
11:53:09 1136 8192000000 10436740624 10843922432 1199313792 12.770 100 95 186 0 1545803808 323826880 22663136
11:54:11 1289 8192000000 10510024656 10919071744 1199360736 12.770 100 93 181 0 1616087632 396682160 20044736
11:55:19 1028 8192000000 10559296976 10993483776 1201812688 12.793 100 95 184 1 1669639216 444142576 23683952
11:56:22 4104 8192000000 10482903568 10938814464 1186330016 12.649 99 63 124 1 1586835568 385423888 15081664
11:57:28 3299 8192000000 10511111088 10933305344 1183725600 12.625 100 69 134 0 1616284128 416550384 16008144
11:58:37 4455 8192000000 10518506176 10984476672 1183792288 12.626 100 72 142 1 1624196752 423304928 17099536
11:59:44 7005 8192000000 10536393520 10965946368 1180959248 12.599 100 54 104 0 1637058528 443086592 13012688
12:01:00 8589 8192000000 10483680816 10972123136 1171687440 12.513 100 47 92 0 1591036672 407862320 11486912
12:02:11 5337 8192000000 10441613824 10929561600 1159788032 12.401 100 38 75 0 1568582416 398951376 9843008
12:03:23 34397 8192000000 10333768608 10867281920 1146703600 12.279 100 15 30 0 1465348960 314256144 4389216
12:04:59 51353 8192000000 10047940352 10521419776 1089427776 11.737 96 6 12 0 1161665360 71163632 1073952
12:06:52 50417 8192000000 10097248752 10531676160 1125491888 12.079 99 31 60 1 1183264992 51521920 6251184
12:08:42 97132 8192000000 10178183488 10636259328 1139851824 12.214 100 46 90 1 1263222992 113870384 9500784
12:11:23 57737 8192000000 10104834208 10574954496 1089033392 11.733 96 21 37 1 1182298880 89668608 3596880
12:13:29 24697 8192000000 10055616784 10514874368 1084430944 11.690 97 39 78 0 1139567312 47277840 7858528
12:14:54 38340 8192000000 10062587680 10508992512 1100225296 11.840 98 22 41 0 1149061360 44512448 4323616
12:16:39 71297 8192000000 10070811808 10523537408 1103861264 11.874 98 18 37 0 1152093152 44595968 3635920
12:18:55 53836 8192000000 10097256096 10573844480 1123084976 12.056 99 15 30 0 1212159424 86015168 3059280
12:21:11 68075 8192000000 10115150000 10579968000 1110560816 11.938 98 22 42 0 1213901408 98369040 4971552
12:23:40 45966 8192000000 10072407984 10514001920 1110926704 11.941 99 12 23 0 1170216528 56961056 2328768
12:25:27 63622 8192000000 10113231520 10574901248 1132284304 12.143 99 37 69 0 1214050224 74353744 7412176
12:27:31 65569 8192000000 10126960512 10603343872 1129305520 12.115 99 20 40 1 1218882416 85052768 4524128
12:29:37 14405 8192000000 10121395632 10573959168 1131297984 12.134 100 44 82 1 1216529152 76371760 8859408
12:30:52 22752 8192000000 10115144272 10572742656 1124405104 12.069 99 44 86 0 1219332976 86214256 8713616
12:32:17 25617 8192000000 10123884336 10554195968 1127637216 12.099 99 36 70 0 1214580720 79017088 7926416
12:33:44 47917 8192000000 10139129664 10598285312 1099962992 11.837 97 21 42 0 1226989328 122269472 4756864
12:35:41 89980 8192000000 10115364496 10575331328 1124120384 12.066 99 44 88 0 1217096480 83503248 9472848
12:38:22 32461 8192000000 10092557664 10573471744 1127976256 12.102 100 35 70 0 1217829152 82489312 7363584
12:39:58 43571 8192000000 10037935264 10511200256 1108581072 11.919 98 8 16 0 1138238336 28447920 1209344
12:41:45 47222 8192000000 10063889568 10533933056 1134268656 12.162 100 7 14 0 1158963008 23143520 1550832
12:43:33 81630 8192000000 10065740160 10552442880 1118424944 12.012 98 19 38 1 1187722064 65407712 3889408
12:45:56 29144 8192000000 10103768032 10542415872 1123296608 12.058 99 33 61 0 1173371152 43823456 6251088
12:47:25 27418 8192000000 10125042816 10569940992 1134113360 12.160 99 30 60 1 1206044016 65736224 6194432
12:48:53 26153 8192000000 10154592592 10586476544 1127267952 12.096 99 40 80 1 1244036768 108498000 8270816
12:50:22 50000 8192000000 10172794832 10667147264 1122384624 12.050 98 37 74 0 1287185024 156554176 8246224
12:52:13 73063 8192000000 10150174448 10617790464 1100616928 11.843 97 23 46 0 1219263584 113520512 5126144
12:54:31 45118 8192000000 10061903840 10480824320 1082962080 11.676 96 38 73 0 1139715648 49704160 7049408
12:56:18 131518 8192000000 10057353120 10550059008 1096197312 11.802 98 33 66 0 1164552160 61254768 7100080
12:59:47 40719 8192000000 10042737920 10533806080 1105189136 11.887 99 10 20 0 1151575424 44213632 2172656
13:01:46 109892 8192000000 10054216896 10512973824 1080731152 11.654 97 12 22 0 1129959328 47354032 1874144
13:04:47 61782 8192000000 10035437088 10538790912 1080557152 11.653 97 9 18 1 1169225072 86607872 2060048
13:06:57 11689 8192000000 10031687632 10466091008 1102462096 11.861 100 31 60 1 1138806448 29869072 6475280
13:08:16 29631 8192000000 10114493872 10554257408 1124215888 12.067 100 26 52 0 1218948096 89014336 5717872
13:09:46 75802 8192000000 10111639408 10549035008 1120782736 12.034 98 14 28 0 1205447264 81734080 2930448
13:12:06 28878 8192000000 10112860720 10541289472 1120685936 12.033 98 31 62 0 1203104144 75705280 6712928
13:13:35 23276 8192000000 10156629728 10586746880 1123379280 12.059 98 41 82 0 1225177280 93194352 8603648
13:15:06 34856 8192000000 10156525696 10644090880 1131282704 12.133 99 38 74 0 1276406656 136985376 8138576
13:16:50 94640 8192000000 10073545808 10541387776 1105071056 11.886 97 15 28 0 1163427728 55476912 2879760
13:19:26 94499 8192000000 10093145344 10546237440 1120256624 12.029 99 37 72 1 1188750624 60200400 8293600
13:22:21 30119 8192000000 10118564944 10567860224 1110770672 11.940 98 37 74 1 1207328048 88910336 7647040
13:23:57 57607 8192000000 10042507520 10507509760 1121549728 12.042 99 11 19 0 1140845920 17530896 1765296
13:25:55 56295 8192000000 10062675920 10516250624 1130584704 12.127 100 20 40 0 1173323952 36994256 5744992
13:28:07 71540 8192000000 10062201696 10523860992 1081160592 11.659 95 10 20 0 1111188016 27684928 2342496
13:30:18 27211 8192000000 10103322560 10542411776 1133702176 12.156 100 33 66 0 1191169168 50695792 6771200
13:32:04 27565 8192000000 10102391792 10571165696 1138727984 12.204 100 27 53 0 1211078688 66816416 5534288
13:33:34 28256 8192000000 10157954224 10603352064 1116684688 11.996 98 46 86 0 1228811952 103251152 8876112
13:35:08 46144 8192000000 10108328192 10596958208 1132100832 12.141 100 27 54 0 1243231168 105484112 5646224
13:37:00 11277 8192000000 10093155824 10518740992 1123532400 12.060 99 52 105 0 1189041920 54899504 10610016

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 18-10-2022 13:38:25 to 18-10-2022 13:38:41.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 109 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 18-10-2022 13:38:42 to 18-10-2022 13:38:44.

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          219934
MON$OLDEST_ACTIVE               223088
MON$OLDEST_SNAPSHOT             223088
MON$NEXT_TRANSACTION            223088
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                       2015808
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.2860 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2860 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2860 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2860 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 ---
:96: 
:97: mscs-test-161.budzdorov.ru    Tue Oct 18 13:37:25 2022
:98:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:99: 
:100: 
:101: mscs-test-161.budzdorov.ru    Tue Oct 18 13:37:26 2022
:102:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:103: 
:104: 
:105: mscs-test-161.budzdorov.ru    Tue Oct 18 13:37:26 2022
:106:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:107: 
:108: 
:109: mscs-test-161.budzdorov.ru    Tue Oct 18 13:37:27 2022
:110:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:111: 
--- end of diff output ---

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

18.10.22 13:38:45. 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
2022-10-18 13:38:48 No severe PSQL-related problems occured
18.10.22 13:38:49. End of report.