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

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2023-01-12 10:37:22
DTS_END                         2023-01-12 10:37:22.3790
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/53290
TRN_ID                          211796
ATT_ID                          599
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: 8652.28 28124 1038274
customer order: creation 162.01 490 19441
customer order: refuse 13.79 388 1655
order to supplier: creation 43.04 1381 5165
order to supplier: removal 3.10 183748 372
invoice (draft): creation 42.00 3368 5040
invoice (draft): removal 48.90 1018 5868
invoice accept: apply 38.80 27423 4656
invoice accept: cancel 44.01 1210 5281
customer reserve: creation 3660.42 259 439250
customer reserve: removal 4317.92 104 518150
realization accept: apply 77.91 279 9349
realization accept: cancel 78.97 174 9476
payment from customer: creation 55.44 173 6653
payment from customer: removal 5.44 132 653
payment to supplier: creation 45.04 196 5405
payment to supplier: removal 3.13 124 376
service: total inventory turnovers 3.93 3800 471
service: total monetary turnovers 7.48 336 897
service: aggregate perf. data 0.93 18330 111
service: refresh index statistics 0.04 319552 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 12-01-2023 10:39:35 to 12-01-2023 10:39:37.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5424
TEST_TIME 2 5035
TEST_TIME 3 5624
TEST_TIME 4 5732
TEST_TIME 5 5865
TEST_TIME 6 6120
TEST_TIME 7 5909
TEST_TIME 8 5859
TEST_TIME 9 6182
TEST_TIME 10 6305
TEST_TIME 11 6712
TEST_TIME 12 6927
TEST_TIME 13 6845
TEST_TIME 14 6974
TEST_TIME 15 7239
TEST_TIME 16 7168
TEST_TIME 17 7272
TEST_TIME 18 7753
TEST_TIME 19 8023
TEST_TIME 20 8083
TEST_TIME 21 8186
TEST_TIME 22 8142
TEST_TIME 23 8172
TEST_TIME 24 8377
TEST_TIME 25 8622
TEST_TIME 26 9371
TEST_TIME 27 9814
TEST_TIME 28 9918
TEST_TIME 29 9946
TEST_TIME 30 10721
TEST_TIME 31 11408
TEST_TIME 32 11517
TEST_TIME 33 11279
TEST_TIME 34 10980
TEST_TIME 35 10872
TEST_TIME 36 10766
TEST_TIME 37 10651
TEST_TIME 38 10438
TEST_TIME 39 10285
TEST_TIME 40 10083
TEST_TIME 41 10166
TEST_TIME 42 10132
TEST_TIME 43 9931
TEST_TIME 44 9746
TEST_TIME 45 9641
TEST_TIME 46 9519
TEST_TIME 47 9610
TEST_TIME 48 9589
TEST_TIME 49 9426
TEST_TIME 50 9511
TEST_TIME 51 9479
TEST_TIME 52 9351
TEST_TIME 53 9235
TEST_TIME 54 9179
TEST_TIME 55 9081
TEST_TIME 56 9073
TEST_TIME 57 9000
TEST_TIME 58 8947
TEST_TIME 59 8945
TEST_TIME 60 9000
TEST_TIME 61 9087
TEST_TIME 62 9004
TEST_TIME 63 9130
TEST_TIME 64 9167
TEST_TIME 65 9142
TEST_TIME 66 9176
TEST_TIME 67 9162
TEST_TIME 68 9152
TEST_TIME 69 9177
TEST_TIME 70 9165
TEST_TIME 71 9047
TEST_TIME 72 8956
TEST_TIME 73 8959
TEST_TIME 74 8917
TEST_TIME 75 8839
TEST_TIME 76 8756
TEST_TIME 77 8732
TEST_TIME 78 8747
TEST_TIME 79 8770
TEST_TIME 80 8809
TEST_TIME 81 8804
TEST_TIME 82 8783
TEST_TIME 83 8882
TEST_TIME 84 8849
TEST_TIME 85 8835
TEST_TIME 86 8880
TEST_TIME 87 8846
TEST_TIME 88 8806
TEST_TIME 89 9022
TEST_TIME 90 8988
TEST_TIME 91 8906
TEST_TIME 92 8861
TEST_TIME 93 8852
TEST_TIME 94 8788
TEST_TIME 95 8708
TEST_TIME 96 8733
TEST_TIME 97 8771
TEST_TIME 98 8750
TEST_TIME 99 8760
TEST_TIME 100 8732
TEST_TIME 101 8751
TEST_TIME 102 8747
TEST_TIME 103 8743
TEST_TIME 104 8713
TEST_TIME 105 8832
TEST_TIME 106 8839
TEST_TIME 107 8875
TEST_TIME 108 8846
TEST_TIME 109 8911
TEST_TIME 110 8940
TEST_TIME 111 8932
TEST_TIME 112 8935
TEST_TIME 113 8886
TEST_TIME 114 8824
TEST_TIME 115 8779
TEST_TIME 116 8748
TEST_TIME 117 8704
TEST_TIME 118 8715
TEST_TIME 119 8728
TEST_TIME 120 8679

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 12-01-2023 10:39:39 to 12-01-2023 10: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 1442128 1442128 0 0.00 0 14731 14 0 0
doc_list_biud 1442117 1442117 0 0.00 0 40390 43 0 0
sp_add_invoice_to_stock 4780 4656 124 2.59 47 200156 27423 0 124
sp_cancel_adding_invoice 5281 5281 0 0.00 5 92780 1210 0 0
sp_cancel_client_order 1655 1655 0 0.00 5 17155 388 0 0
sp_cancel_customer_reserve 518150 518150 0 0.00 1 40428 105 0 0
sp_cancel_pay_from_customer 653 653 0 0.00 4 4369 132 0 0
sp_cancel_pay_to_supplier 376 376 0 0.00 3 1785 124 0 0
sp_cancel_supplier_invoice 5876 5868 8 0.13 3 26410 1019 0 8
sp_cancel_supplier_order 372 372 0 0.00 12 1611756 183748 0 0
sp_cancel_write_off 9476 9476 0 0.00 2 18782 174 0 0
sp_client_order 19441 19441 0 0.00 6 24196 491 0 0
sp_customer_reserve 439250 439250 0 0.00 0 47075 259 0 0
sp_fill_shopping_cart 29637 29637 0 0.00 1 21453 86 0 0
sp_fill_shopping_cart_clo_res 439214 439214 0 0.00 0 13401 19 0 0
sp_get_clo_for_invoice 4609 4609 0 0.00 0 37444 364 0 0
sp_kill_cost_storno 15786 15786 0 0.00 0 7187 74 0 0
sp_kill_qstorno_ret_qs2qd 1052016 1052016 0 0.00 0 40337 46 0 0
sp_kill_qty_storno 554758 554758 0 0.00 0 40383 104 0 0
sp_lock_selected_doc 19407 19407 0 0.00 0 3368 6 0 0
sp_make_cost_storno 26016 26016 0 0.00 0 11116 75 0 0
sp_multiply_rows_for_pdistr 26014 26014 0 0.00 0 7461 37 0 0
sp_multiply_rows_for_qdistr 19441 19441 0 0.00 0 13517 182 0 0
sp_pay_from_customer 6653 6653 0 0.00 5 12360 173 0 0
sp_pay_to_supplier 5405 5405 0 0.00 6 12313 197 0 0
sp_payment_common 12057 12057 0 0.00 2 8790 45 0 0
sp_qd_handle_on_cancel_clo 1655 1655 0 0.00 0 17112 315 0 0
sp_qd_handle_on_invoice_upd_sts 9889 9889 0 0.00 0 16517 507 0 0
sp_qd_handle_on_reserve_upd_sts 9348 9348 0 0.00 0 18047 73 0 0
sp_reserve_write_off 9349 9349 0 0.00 8 18207 279 0 0
sp_supplier_invoice 5040 5040 0 0.00 10 77692 3368 0 0
sp_supplier_order 5165 5165 0 0.00 8 42480 1382 0 0
srv_aggregate_perf_data 111 111 0 0.00 2155 339138 18331 0 0
srv_fill_mon_cache_memory 72 72 0 0.00 610 120829 33723 0 0
srv_find_qd_qs_mism 991913 991913 0 0.00 0 14729 11 0 0
srv_make_invnt_saldo 471 471 0 0.00 68 141203 3801 0 0
srv_make_money_saldo 897 897 0 0.00 1 8239 337 0 0
srv_recalc_idx_stat 5 5 0 0.00 84089 639305 319553 0 0
t$perf-abend:sp_add_invoice_to_stock 124 124 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4663 4663 0 0.00 0 18679 907 0 0
t$perf-norm:sp_cancel_adding_invoice 379 379 0 0.00 1 6364 441 0 0
t$perf-norm:sp_cancel_client_order 1655 1655 0 0.00 0 8547 34 0 0
t$perf-norm:sp_cancel_customer_reserve 655 655 0 0.00 0 4535 36 0 0
t$perf-norm:sp_cancel_pay_from_customer 653 653 0 0.00 0 3492 21 0 0
t$perf-norm:sp_cancel_pay_to_supplier 376 376 0 0.00 0 743 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 406 406 0 0.00 0 3368 46 0 0
t$perf-norm:sp_cancel_supplier_order 372 372 0 0.00 0 99728 6963 0 0
t$perf-norm:sp_cancel_write_off 961 961 0 0.00 0 671 17 0 0
t$perf-norm:sp_client_order 19441 19441 0 0.00 0 6914 32 0 0
t$perf-norm:sp_customer_reserve 968 968 0 0.00 0 562 13 0 0
t$perf-norm:sp_pay_from_customer 6653 6653 0 0.00 0 10533 19 0 0
t$perf-norm:sp_pay_to_supplier 5405 5405 0 0.00 0 4667 19 0 0
t$perf-norm:sp_reserve_write_off 9349 9349 0 0.00 0 13091 21 0 0
t$perf-norm:sp_supplier_invoice 5040 5040 0 0.00 0 6322 29 0 0
t$perf-norm:sp_supplier_order 5165 5165 0 0.00 0 4644 32 0 0
t$perf-norm:srv_aggregate_perf_data 111 111 0 0.00 0 406 15 0 0
t$perf-norm:srv_fill_mon_cache_memory 72 72 0 0.00 0 295 8 0 0
t$perf-norm:srv_make_invnt_saldo 471 471 0 0.00 0 729 11 0 0
t$perf-norm:srv_make_money_saldo 897 897 0 0.00 0 282 3 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 7 186 76 0 0
v_add_invoice_to_stock 9456 9208 248 2.62 0 4414 25 0 248
v_all_customers 35486 35486 0 0.00 0 68 4 0 0
v_all_suppliers 28580 28580 0 0.00 0 85 4 0 0
v_all_wares 38880 38880 0 0.00 0 198 4 0 0
v_cancel_adding_invoice 758 758 0 0.00 0 697 15 0 0
v_cancel_client_order 3310 3310 0 0.00 0 3709 14 0 0
v_cancel_customer_prepayment 1306 1306 0 0.00 0 4333 16 0 0
v_cancel_customer_reserve 1310 1310 0 0.00 0 3395 17 0 0
v_cancel_payment_to_supplier 752 752 0 0.00 0 1700 16 0 0
v_cancel_supplier_invoice 828 812 16 1.93 0 755 23 0 16
v_cancel_supplier_order 744 744 0 0.00 0 694 12 0 0
v_cancel_write_off 1922 1922 0 0.00 0 4360 25 0 0
v_max_id_clo_ord 5162 5162 0 0.00 0 2584 5 0 0
v_max_id_clo_res 968 968 0 0.00 0 1262 11 0 0
v_max_id_ord_sup 5032 5032 0 0.00 0 5112 10 0 0
v_max_non_paid_invoice 1308 1308 0 0.00 0 134 7 0 0
v_max_non_paid_realizn 3498 3498 0 0.00 0 4501 8 0 0
v_min_id_clo_ord 5162 5162 0 0.00 0 4830 9 0 0
v_min_id_clo_res 968 968 0 0.00 0 7328 23 0 0
v_min_id_ord_sup 5032 5032 0 0.00 0 8046 12 0 0
v_min_non_paid_invoice 5405 5405 0 0.00 0 4392 16 0 0
v_min_non_paid_realizn 6652 6652 0 0.00 0 6711 19 0 0
v_our_firm 9702 9702 0 0.00 0 57 4 0 0
v_reserve_write_off 18689 18689 0 0.00 0 3799 12 0 0
x_lock_depdocs_on_canc_invoice 10743 10743 0 0.00 0 13850 132 0 0
x_lock_depdocs_on_canc_sup_ord 372 372 0 0.00 0 10116 379 0 0
x_make_qstorno_1000_1200 5165 5165 0 0.00 3 42412 1221 0 0
x_make_qstorno_1200_2000 5040 5040 0 0.00 5 77679 3113 0 0
x_make_qstorno_2100_3300 439249 439249 0 0.00 0 41084 228 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 12-01-2023 10:39:43 to 12-01-2023 10:39:48.

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
08:07:26 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
08:08:27 22464 8192000000 9723162864 10025164800 803743152 8.934 100 86 172 0 832370416 7417616 21209648
08:09:57 16380 8192000000 9761754928 10070454272 839331136 9.293 100 91 182 1 874583744 12904112 22348496
08:11:16 18770 8192000000 9809270784 10126041088 868230352 9.582 100 88 176 0 915902288 26751376 20920560
08:12:40 13404 8192000000 9873271664 10209710080 920424224 10.100 100 78 156 0 973478944 36159920 16894800
08:13:56 4732 8192000000 9938556800 10284240896 966079744 10.548 100 89 175 0 1045731088 61059664 18591680
08:15:05 2701 8192000000 9975334096 10329153536 998546208 10.864 100 95 188 0 1091504352 73351952 19606192
08:16:10 1687 8192000000 10006117088 10369392640 1024468752 11.115 100 89 171 0 1121908144 80577984 16861408
08:17:14 2543 8192000000 10008111952 10377314304 1036180512 11.228 100 90 174 1 1123304784 70986464 16137808
08:18:18 940 8192000000 10038308448 10415411200 1057915328 11.437 100 95 182 0 1163373840 86782880 18675632
08:19:20 1242 8192000000 10042896592 10424037376 1067211568 11.525 100 95 178 0 1165970976 80763216 17996192
08:20:22 1296 8192000000 10039135104 10419646464 1070719328 11.559 100 90 169 2 1155479376 68382320 16377728
08:21:25 1103 8192000000 10042274848 10426232832 1082726880 11.673 100 96 185 0 1162935152 62201424 18006848
08:22:27 2281 8192000000 10042934560 10428731392 1082688816 11.673 100 86 160 1 1168099120 68280240 17130064
08:23:30 6288 8192000000 10055797952 10446086144 1088581824 11.729 100 70 133 2 1168817824 66915904 13320096
08:24:37 1077 8192000000 10059401392 10446278656 1091921840 11.761 100 91 170 1 1179556528 70306432 17328256
08:25:39 1007 8192000000 10066453456 10454802432 1098712960 11.825 100 98 193 0 1188543888 66760032 23070896
08:26:41 1367 8192000000 10072753520 10463514624 1098548128 11.824 100 88 162 2 1195776320 79603168 17625024
08:27:43 1255 8192000000 10052659648 10442633216 1105557648 11.890 100 91 166 1 1173234976 51826208 15851120
08:28:45 3930 8192000000 10027646880 10415214592 1085319168 11.698 99 77 148 1 1149488592 48337024 15832400
08:29:51 991 8192000000 10067262672 10453848064 1097026848 11.809 100 91 167 1 1188740160 74504912 17208400
08:30:53 1461 8192000000 10035202944 10431819776 1079473072 11.642 99 73 140 1 1160454992 66719040 14262880
08:31:55 1900 8192000000 10043221200 10425417728 1078953744 11.638 100 82 146 0 1159874752 65677296 15243712
08:32:58 8371 8192000000 10020003344 10400858112 1053537744 11.395 98 64 124 0 1139697360 73960656 12198960
08:34:07 7105 8192000000 10000616112 10390114304 1059419744 11.451 100 56 108 1 1116041600 45939536 10682320
08:35:14 7136 8192000000 9981564112 10361888768 1006907088 10.945 96 55 103 0 1069106800 50974352 11225360
08:36:22 1048 8192000000 10004342608 10379821056 1052721904 11.387 100 94 175 1 1127510640 56956848 17831888
08:37:25 24870 8192000000 10007227536 10388398080 1046402048 11.326 99 67 129 1 1119603680 60867072 12334560
08:38:59 2111 8192000000 10033531472 10414460928 1067687328 11.530 100 82 159 1 1158456448 74370384 16398736
08:40:06 1163 8192000000 10075829040 10456551424 1089560320 11.738 100 91 174 0 1193338400 86901280 16876800
08:41:11 8978 8192000000 10121521072 10517684224 1101237088 11.849 100 64 124 0 1232538832 118510480 12791264
08:42:25 899 8192000000 10112838192 10496040960 1106188368 11.896 100 92 169 3 1229566368 104727760 18650240
08:43:33 2554 8192000000 10167526896 10566078464 1120412736 12.031 100 72 136 1 1279281920 144808416 14060768
08:44:43 7655 8192000000 10203827632 10602860544 1129112704 12.113 100 68 132 0 1317958176 174088816 14756656
08:45:55 1301 8192000000 10239246320 10645532672 1141804960 12.233 100 75 142 0 1356065584 198624544 15636080
08:47:00 1294 8192000000 10251689488 10658238464 1146045328 12.272 100 97 181 4 1362548144 194945728 21557088
08:48:04 1615 8192000000 10294839456 10696777728 1162058480 12.423 100 92 178 0 1404937680 223495872 19383328
08:49:08 1827 8192000000 10330165376 10741977088 1178801584 12.579 100 89 175 2 1443697856 244321552 20574720
08:50:12 753 8192000000 10336335312 10743603200 1181858640 12.608 100 90 172 1 1447919312 246350928 19709744
08:51:16 1661 8192000000 10393639040 10803945472 1184776096 12.635 100 93 180 0 1505736256 300185952 20774208
08:52:21 1567 8192000000 10417655808 10854363136 1186367408 12.650 100 82 162 0 1524885952 318979200 19539344
08:53:26 1345 8192000000 10447990192 10860826624 1196368912 12.743 100 85 168 1 1559423232 341836928 21217392
08:54:30 1294 8192000000 10427264896 10896252928 1189731648 12.681 100 84 164 0 1537286176 326387840 21166688
08:55:34 821 8192000000 10478434544 10944425984 1198023344 12.758 100 98 187 1 1586741696 366228160 22490192
08:56:38 873 8192000000 10505931680 10916040704 1213372576 12.900 100 97 194 1 1611197760 375875072 21950112
08:57:43 800 8192000000 10532679072 10950270976 1212484416 12.892 100 94 184 0 1633210832 398983696 21742720
08:58:46 608 8192000000 10586530224 11008159744 1212873520 12.896 100 81 156 2 1690857968 459056816 18927632
08:59:52 3262 8192000000 10571819344 11049209856 1212294224 12.890 100 73 138 1 1685760224 455345392 18120608
09:01:03 937 8192000000 10611917664 11051835392 1205665504 12.829 100 92 182 1 1713220032 485917008 21637520
09:02:06 4375 8192000000 10599611104 11064229888 1200774672 12.784 100 53 106 0 1703930688 488642976 14513040
09:03:22 5208 8192000000 10526172528 11087245312 1193741728 12.718 100 52 104 0 1637946368 429582192 14622448
09:04:35 10696 8192000000 10449758384 10964934656 1194100272 12.722 100 38 75 0 1550354432 346069472 10184688
09:05:47 25263 8192000000 10379438976 10855071744 1134884288 12.167 97 26 52 0 1465538336 324015504 6638544
09:08:03 35709 8192000000 10136690256 10688069632 1149566432 12.305 100 12 24 0 1272075664 119644528 2864704
09:09:41 46326 8192000000 10050148080 10486484992 1105469824 11.890 96 32 53 1 1145748112 35836160 4442128
09:11:36 18588 8192000000 10095253328 10522525696 1137641072 12.193 100 53 100 1 1186526544 38629920 10255552
09:13:13 49637 8192000000 10073925456 10546307072 1105558176 11.890 97 11 22 0 1171769760 64211056 2000528
09:15:03 39912 8192000000 10072797872 10522681344 1123206688 12.057 99 27 51 1 1162614624 34521472 4886464
09:16:56 73215 8192000000 10120569824 10581422080 1136527040 12.183 100 19 34 0 1216763040 76548960 3687040
09:19:10 60417 8192000000 10086631824 10548076544 1118792208 12.016 98 17 34 0 1192538720 70102768 3643744
09:21:32 59238 8192000000 10090595936 10552123392 1119286736 12.020 98 51 100 2 1214740048 83312048 12141264
09:23:35 65692 8192000000 10155745776 10633592832 1116525488 11.994 99 25 50 0 1236916608 114937360 5453760
09:25:52 40786 8192000000 10090765232 10536243200 1125221776 12.076 100 16 30 0 1208617840 80478432 2917632
09:27:39 68183 8192000000 10051509392 10541838336 1127528416 12.098 100 13 26 0 1183682336 53060896 3093024
09:30:19 45196 8192000000 10058600800 10505641984 1101618048 11.853 98 42 75 2 1130110624 20465104 8027472
09:32:06 60409 8192000000 10045728880 10516344832 1112780176 11.959 99 11 22 0 1157498000 42374800 2343024
09:34:11 34669 8192000000 10065729952 10516987904 1118724016 12.015 99 26 52 0 1140573600 15485008 6364576
09:35:46 42288 8192000000 10151157472 10602549248 1143015296 12.244 100 29 59 1 1229861104 80404096 6441712
09:37:30 69316 8192000000 10109662080 10604130304 1136003168 12.178 99 19 38 0 1251412144 111298800 4110176
09:39:49 67208 8192000000 10064089312 10565312512 1098985568 11.828 97 29 58 0 1190206096 85309696 5910832
09:42:02 36794 8192000000 10099663920 10538524672 1102762368 11.864 97 47 88 0 1157883360 46123616 8997376
09:43:46 26223 8192000000 10100157312 10555523072 1123097424 12.056 99 32 62 1 1185127872 56231056 5799392
09:45:27 7889 8192000000 10083643792 10503237632 1123789456 12.063 99 56 100 0 1163585664 29626144 10170064
09:46:35 50807 8192000000 10082040096 10534539264 1138218992 12.199 100 40 80 0 1222557376 76146608 8191776
09:48:27 74973 8192000000 10081749920 10521956352 1135469952 12.173 99 43 83 0 1174980736 31174144 8336640
09:50:44 70359 8192000000 10068103152 10546888704 1137061888 12.188 99 16 32 0 1164423376 23987664 3373824
09:52:56 103106 8192000000 10137686736 10607607808 1125055440 12.075 97 12 24 0 1192240464 64867952 2317072
09:55:44 70533 8192000000 10109668672 10569973760 1140351600 12.219 99 23 45 0 1217391440 72208736 4831104
09:58:00 32917 8192000000 10116557904 10565681152 1140906528 12.224 99 41 82 0 1202097200 52128240 9062432
09:59:42 16863 8192000000 10107568464 10547486720 1107994080 11.913 98 30 60 0 1195919200 81252416 6672704
10:01:06 11300 8192000000 10077996496 10494357504 1129561504 12.117 100 75 139 0 1174379456 29513760 15304192
10:02:18 43059 8192000000 10128959664 10566709248 1110832400 11.940 99 40 81 0 1229399360 110218848 8348112
10:04:02 46320 8192000000 10154524272 10580877312 1113503312 11.966 98 30 57 0 1196102256 77170256 5428688
10:05:50 120829 8192000000 10055425888 10579632128 1098212640 11.821 97 16 32 1 1185373088 83823952 3336496
10:08:51 30951 8192000000 10073726048 10504761344 1128944592 12.111 100 53 100 2 1176463824 37599024 9920208
10:10:22 58518 8192000000 10091230160 10549043200 1128356016 12.106 99 20 41 2 1176941376 44990368 3594992
10:12:21 84819 8192000000 10162041360 10660196352 1126262768 12.086 98 16 32 0 1232932384 103008704 3660912
10:16:31 32014 8192000000 10086756384 10537467904 1124591328 12.070 98 24 44 0 1177540432 48426080 4523024
10:18:15 22983 8192000000 10135625280 10575462400 1127636192 12.099 98 37 74 0 1213625328 78515424 7473712
10:19:41 31081 8192000000 10131556480 10584285184 1102204064 11.859 97 37 71 0 1198412720 88362480 7846176
10:21:14 25510 8192000000 10174686976 10606821376 1121787760 12.044 99 36 70 0 1247226144 117580208 7858176
10:22:50 32569 8192000000 10094319024 10545221632 1123679136 12.062 99 27 52 0 1199076832 69510432 5887264
10:24:26 31661 8192000000 10090438992 10530144256 1115797024 11.987 99 36 73 0 1173763472 50367760 7598688
10:25:58 23376 8192000000 10153794000 10606649344 1107378944 11.908 98 43 84 0 1221275392 105225136 8671312
10:27:24 107595 8192000000 10131990352 10597564416 1055697280 11.415 93 25 48 0 1106747488 46560032 4490176
10:30:23 75879 8192000000 10022590240 10461712384 1046753072 11.330 93 25 46 1 1063380944 11496928 5130944
10:32:42 38079 8192000000 10112456832 10569846784 1126871408 12.092 100 28 56 0 1231829968 98981184 5977376
10:34:26 59649 8192000000 10137758400 10614853632 1138727968 12.204 100 15 30 1 1253591648 110931792 3931888
10:36:28 70582 8192000000 9015736112 9270648832 167931344 2.008 15 5 9 0 203348864 34208656 1208864

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 12-01-2023 10:39:49 to 12-01-2023 10:40:06.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 124 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 12-01-2023 10:40:07 to 12-01-2023 10:40:09.

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          212285
MON$OLDEST_ACTIVE               212286
MON$OLDEST_SNAPSHOT             212286
MON$NEXT_TRANSACTION            212286
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                       2046304
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             766
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2887 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2887 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2887 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2887 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 ---
--- end of diff output ---

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

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