Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 21.05.2022 13: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 15894256 kB
MemAvailable 31447640 kB
Buffers 0 kB
Cached 15834856 kB
SwapCached 2588 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.2
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-05-21 13:37:19
DTS_END                         2022-05-21 13:37:19.4060
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/35246
TRN_ID                          213344
ATT_ID                          627
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: 8428.02 31150 1011362
customer order: creation 158.88 515 19065
customer order: refuse 13.43 485 1612
order to supplier: creation 43.20 1464 5184
order to supplier: removal 3.23 182780 388
invoice (draft): creation 42.45 3969 5094
invoice (draft): removal 47.90 1152 5748
invoice accept: apply 38.68 26073 4642
invoice accept: cancel 42.46 1412 5095
customer reserve: creation 3566.45 242 427974
customer reserve: removal 4200.01 110 504001
realization accept: apply 79.33 331 9520
realization accept: cancel 71.05 185 8526
payment from customer: creation 55.78 185 6693
payment from customer: removal 5.09 170 611
payment to supplier: creation 45.37 215 5444
payment to supplier: removal 2.99 152 359
service: total inventory turnovers 3.22 5597 386
service: total monetary turnovers 7.69 443 923
service: aggregate perf. data 0.78 20794 93
service: refresh index statistics 0.03 376724 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 21-05-2022 13:39:39 to 21-05-2022 13:39:41.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7706
TEST_TIME 2 8285
TEST_TIME 3 6634
TEST_TIME 4 6603
TEST_TIME 5 6730
TEST_TIME 6 6691
TEST_TIME 7 6675
TEST_TIME 8 6539
TEST_TIME 9 6605
TEST_TIME 10 6647
TEST_TIME 11 6753
TEST_TIME 12 6803
TEST_TIME 13 7126
TEST_TIME 14 7279
TEST_TIME 15 7261
TEST_TIME 16 7317
TEST_TIME 17 7387
TEST_TIME 18 7645
TEST_TIME 19 7816
TEST_TIME 20 8120
TEST_TIME 21 8311
TEST_TIME 22 8305
TEST_TIME 23 8230
TEST_TIME 24 8264
TEST_TIME 25 8358
TEST_TIME 26 8509
TEST_TIME 27 8691
TEST_TIME 28 8699
TEST_TIME 29 8637
TEST_TIME 30 8871
TEST_TIME 31 8999
TEST_TIME 32 9134
TEST_TIME 33 9483
TEST_TIME 34 9539
TEST_TIME 35 9569
TEST_TIME 36 9499
TEST_TIME 37 9668
TEST_TIME 38 10131
TEST_TIME 39 9988
TEST_TIME 40 9807
TEST_TIME 41 9763
TEST_TIME 42 9824
TEST_TIME 43 9856
TEST_TIME 44 9959
TEST_TIME 45 9901
TEST_TIME 46 9771
TEST_TIME 47 9807
TEST_TIME 48 9653
TEST_TIME 49 9565
TEST_TIME 50 9513
TEST_TIME 51 9429
TEST_TIME 52 9337
TEST_TIME 53 9272
TEST_TIME 54 9164
TEST_TIME 55 9285
TEST_TIME 56 9266
TEST_TIME 57 9278
TEST_TIME 58 9171
TEST_TIME 59 9070
TEST_TIME 60 8949
TEST_TIME 61 8879
TEST_TIME 62 8891
TEST_TIME 63 8847
TEST_TIME 64 8857
TEST_TIME 65 8761
TEST_TIME 66 8711
TEST_TIME 67 8655
TEST_TIME 68 8652
TEST_TIME 69 8651
TEST_TIME 70 8654
TEST_TIME 71 8598
TEST_TIME 72 8581
TEST_TIME 73 8618
TEST_TIME 74 8617
TEST_TIME 75 8564
TEST_TIME 76 8488
TEST_TIME 77 8513
TEST_TIME 78 8591
TEST_TIME 79 8578
TEST_TIME 80 8690
TEST_TIME 81 8730
TEST_TIME 82 8743
TEST_TIME 83 8783
TEST_TIME 84 8724
TEST_TIME 85 8704
TEST_TIME 86 8679
TEST_TIME 87 8630
TEST_TIME 88 8649
TEST_TIME 89 8589
TEST_TIME 90 8580
TEST_TIME 91 8610
TEST_TIME 92 8573
TEST_TIME 93 8500
TEST_TIME 94 8485
TEST_TIME 95 8481
TEST_TIME 96 8494
TEST_TIME 97 8498
TEST_TIME 98 8515
TEST_TIME 99 8494
TEST_TIME 100 8503
TEST_TIME 101 8487
TEST_TIME 102 8485
TEST_TIME 103 8460
TEST_TIME 104 8611
TEST_TIME 105 8640
TEST_TIME 106 8701
TEST_TIME 107 8735
TEST_TIME 108 8728
TEST_TIME 109 8687
TEST_TIME 110 8633
TEST_TIME 111 8684
TEST_TIME 112 8666
TEST_TIME 113 8620
TEST_TIME 114 8578
TEST_TIME 115 8582
TEST_TIME 116 8536
TEST_TIME 117 8501
TEST_TIME 118 8505
TEST_TIME 119 8514
TEST_TIME 120 8451

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 21-05-2022 13:39:42 to 21-05-2022 13:39:46.

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 1405530 1405530 0 0.00 0 21148 14 0 0
doc_list_biud 1405529 1405529 0 0.00 0 57343 47 0 0
sp_add_invoice_to_stock 4748 4642 106 2.23 82 209396 26074 0 106
sp_cancel_adding_invoice 5095 5095 0 0.00 8 72575 1412 0 0
sp_cancel_client_order 1612 1612 0 0.00 5 12428 486 0 0
sp_cancel_customer_reserve 504001 504001 0 0.00 1 57346 110 0 0
sp_cancel_pay_from_customer 611 611 0 0.00 3 10421 171 0 0
sp_cancel_pay_to_supplier 359 359 0 0.00 4 5758 153 0 0
sp_cancel_supplier_invoice 5758 5748 10 0.17 3 27386 1152 0 10
sp_cancel_supplier_order 388 388 0 0.00 7 1326554 182781 0 0
sp_cancel_write_off 8526 8526 0 0.00 2 17024 185 0 0
sp_client_order 19065 19065 0 0.00 5 28151 516 0 0
sp_customer_reserve 427974 427974 0 0.00 1 43920 243 0 0
sp_fill_shopping_cart 29333 29333 0 0.00 1 26001 104 0 0
sp_fill_shopping_cart_clo_res 427942 427942 0 0.00 0 12348 22 0 0
sp_get_clo_for_invoice 4599 4599 0 0.00 0 24094 358 0 0
sp_kill_cost_storno 14591 14591 0 0.00 0 21142 93 0 0
sp_kill_qstorno_ret_qs2qd 1022664 1022664 0 0.00 0 57336 49 0 0
sp_kill_qty_storno 539488 539488 0 0.00 0 57341 113 0 0
sp_lock_selected_doc 19373 19373 0 0.00 0 10851 9 0 0
sp_make_cost_storno 26255 26255 0 0.00 0 19656 93 0 0
sp_multiply_rows_for_pdistr 26255 26255 0 0.00 0 7281 40 0 0
sp_multiply_rows_for_qdistr 19065 19065 0 0.00 0 16870 191 0 0
sp_pay_from_customer 6693 6693 0 0.00 6 9794 186 0 0
sp_pay_to_supplier 5444 5444 0 0.00 7 14764 215 0 0
sp_payment_common 12137 12137 0 0.00 2 9568 52 0 0
sp_qd_handle_on_cancel_clo 1612 1612 0 0.00 0 12322 398 0 0
sp_qd_handle_on_invoice_upd_sts 9693 9693 0 0.00 0 18320 640 0 0
sp_qd_handle_on_reserve_upd_sts 9520 9520 0 0.00 0 10389 72 0 0
sp_reserve_write_off 9520 9520 0 0.00 9 19692 332 0 0
sp_supplier_invoice 5094 5094 0 0.00 10 115152 3969 0 0
sp_supplier_order 5184 5184 0 0.00 7 32598 1465 0 0
srv_aggregate_perf_data 93 93 0 0.00 2835 109874 20795 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 679 181253 35900 0 0
srv_find_qd_qs_mism 965965 965965 0 0.00 0 16968 9 0 0
srv_make_invnt_saldo 386 386 0 0.00 115 70420 5597 0 0
srv_make_money_saldo 923 923 0 0.00 2 14684 443 0 0
srv_recalc_idx_stat 4 4 0 0.00 81656 707868 376724 0 0
t$perf-abend:sp_add_invoice_to_stock 106 106 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4642 4642 0 0.00 1 9505 1157 0 0
t$perf-norm:sp_cancel_adding_invoice 344 344 0 0.00 1 6109 619 0 0
t$perf-norm:sp_cancel_client_order 1612 1612 0 0.00 0 4223 36 0 0
t$perf-norm:sp_cancel_customer_reserve 630 630 0 0.00 0 3798 38 0 0
t$perf-norm:sp_cancel_pay_from_customer 611 611 0 0.00 0 1257 18 0 0
t$perf-norm:sp_cancel_pay_to_supplier 359 359 0 0.00 0 1112 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 410 410 0 0.00 0 897 47 0 0
t$perf-norm:sp_cancel_supplier_order 388 388 0 0.00 0 117247 8794 0 0
t$perf-norm:sp_cancel_write_off 901 901 0 0.00 0 1595 21 0 0
t$perf-norm:sp_client_order 19065 19065 0 0.00 0 10220 45 0 0
t$perf-norm:sp_customer_reserve 886 886 0 0.00 0 1030 17 0 0
t$perf-norm:sp_pay_from_customer 6693 6693 0 0.00 0 7314 25 0 0
t$perf-norm:sp_pay_to_supplier 5444 5444 0 0.00 0 4464 24 0 0
t$perf-norm:sp_reserve_write_off 9520 9520 0 0.00 0 7411 29 0 0
t$perf-norm:sp_supplier_invoice 5094 5094 0 0.00 0 8161 40 0 0
t$perf-norm:sp_supplier_order 5184 5184 0 0.00 0 6216 43 0 0
t$perf-norm:srv_aggregate_perf_data 93 93 0 0.00 0 2735 53 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 585 14 0 0
t$perf-norm:srv_make_invnt_saldo 386 386 0 0.00 0 440 16 0 0
t$perf-norm:srv_make_money_saldo 923 923 0 0.00 0 1124 8 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 4 209 68 0 0
v_add_invoice_to_stock 9408 9196 212 2.25 0 12921 27 0 212
v_all_customers 34586 34586 0 0.00 0 213 4 0 0
v_all_suppliers 28512 28512 0 0.00 0 76 5 0 0
v_all_wares 38130 38130 0 0.00 0 63 4 0 0
v_cancel_adding_invoice 688 688 0 0.00 0 1762 18 0 0
v_cancel_client_order 3224 3224 0 0.00 0 3971 13 0 0
v_cancel_customer_prepayment 1222 1222 0 0.00 0 2218 17 0 0
v_cancel_customer_reserve 1260 1260 0 0.00 0 2283 14 0 0
v_cancel_payment_to_supplier 718 718 0 0.00 0 2498 17 0 0
v_cancel_supplier_invoice 840 820 20 2.38 0 2764 31 0 20
v_cancel_supplier_order 776 776 0 0.00 0 275 10 0 0
v_cancel_write_off 1802 1802 0 0.00 0 5973 32 0 0
v_max_id_clo_ord 5184 5184 0 0.00 0 1148 6 0 0
v_max_id_clo_res 886 886 0 0.00 0 2802 18 0 0
v_max_id_ord_sup 5084 5084 0 0.00 0 4571 9 0 0
v_max_non_paid_invoice 1456 1456 0 0.00 0 3860 11 0 0
v_max_non_paid_realizn 3682 3682 0 0.00 0 1443 8 0 0
v_min_id_clo_ord 5184 5184 0 0.00 0 4468 10 0 0
v_min_id_clo_res 886 886 0 0.00 0 185 8 0 0
v_min_id_ord_sup 5084 5084 0 0.00 0 9608 15 0 0
v_min_non_paid_invoice 5444 5444 0 0.00 0 7932 17 0 0
v_min_non_paid_realizn 6693 6693 0 0.00 0 9550 20 0 0
v_our_firm 9566 9566 0 0.00 0 84 5 0 0
v_reserve_write_off 19040 19040 0 0.00 0 4741 12 0 0
x_lock_depdocs_on_canc_invoice 10433 10433 0 0.00 0 17177 145 0 0
x_lock_depdocs_on_canc_sup_ord 388 388 0 0.00 0 13085 497 0 0
x_make_qstorno_1000_1200 5184 5184 0 0.00 3 32583 1244 0 0
x_make_qstorno_1200_2000 5094 5094 0 0.00 4 111723 3688 0 0
x_make_qstorno_2100_3300 427974 427974 0 0.00 0 36316 207 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 21-05-2022 13:39:47 to 21-05-2022 13:39:51.

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:23 4 8192000000 8800243472 8812093440 5871472 0.071 2 2 3 0 6036752 103904 61376
11:08:24 30119 8192000000 9728745808 10031992832 810254000 9.000 100 86 173 0 838726592 7415552 21057040
11:10:01 22833 8192000000 9777785536 10088259584 850281184 9.403 100 86 172 0 882710288 12014000 20415104
11:11:27 15478 8192000000 9813705664 10132762624 879430928 9.694 100 94 188 0 920820480 20069776 21319776
11:12:44 7494 8192000000 9853080288 10187988992 912908288 10.026 100 74 148 0 966156576 36065776 17182512
11:13:54 3657 8192000000 9913843472 10256572416 951780064 10.409 100 80 160 1 1027102016 57528992 17792960
11:15:00 2459 8192000000 9966829840 10319085568 995323648 10.833 100 90 172 2 1082845072 69996352 17525072
11:16:06 1141 8192000000 10010546272 10370928640 1017704576 11.050 100 93 183 0 1126806672 90987152 18114944
11:17:11 1765 8192000000 9997546608 10367094784 1043744496 11.301 100 95 180 0 1121781280 59300960 18735824
11:18:14 922 8192000000 10024482352 10398208000 1054561424 11.404 100 96 188 0 1144122480 70452944 19108112
11:19:17 1090 8192000000 10031982848 10409312256 1066628896 11.520 100 96 185 0 1148600912 62581648 19390368
11:20:19 1069 8192000000 10037429600 10424090624 1073526480 11.586 100 91 172 1 1159404064 67587184 18290400
11:21:21 1382 8192000000 10061314848 10450464768 1079494688 11.643 100 85 168 2 1180947632 84813696 16639248
11:22:24 5549 8192000000 10045826752 10434134016 1088690240 11.730 100 81 152 2 1166131120 62489664 14951216
11:23:31 3350 8192000000 10039821872 10426195968 1087205408 11.716 100 81 143 0 1162160976 61377008 13578560
11:24:35 2515 8192000000 10042322416 10436640768 1090380384 11.746 100 74 139 2 1160576704 55111392 15084928
11:25:38 1143 8192000000 10065461904 10454601728 1094334768 11.784 100 97 193 0 1186539376 72650480 19554128
11:26:41 1059 8192000000 10072867808 10466545664 1092276432 11.764 100 96 179 2 1194995424 83683920 19035072
11:27:44 1266 8192000000 10053141472 10443542528 1093764064 11.778 100 88 160 1 1173924640 66191360 13969216
11:28:47 1329 8192000000 10062829456 10449760256 1086241328 11.707 100 98 182 4 1183810416 78711232 18857856
11:29:51 1443 8192000000 10033787808 10416144384 1079031616 11.638 100 89 167 0 1145442528 51226432 15184480
11:30:53 840 8192000000 9995546256 10377543680 1050273856 11.363 98 80 157 1 1118009440 50883184 16852400
11:31:55 1431 8192000000 10000417648 10389540864 1062291696 11.478 100 80 146 4 1121814480 43061888 16460896
11:32:58 3702 8192000000 9974350816 10349473792 1035990576 11.226 100 74 142 2 1097257664 47092640 14174448
11:34:03 4636 8192000000 9960542432 10337017856 1021843408 11.090 99 66 130 1 1078865216 44210832 12810976
11:35:08 1205 8192000000 9984470848 10361470976 1031123360 11.179 98 97 184 0 1107058320 56506064 19428896
11:36:10 1044 8192000000 9979059328 10353532928 1037648480 11.242 100 92 176 0 1099315920 43334624 18332816
11:37:12 1217 8192000000 9987080736 10363125760 1048658192 11.348 100 87 170 1 1113399424 47375840 17365392
11:38:19 1198 8192000000 10012576960 10397466624 1067834768 11.531 100 83 146 1 1138451968 54949008 15668192
11:39:25 4070 8192000000 10057951984 10444660736 1082014320 11.667 100 84 165 0 1183614192 83791184 17808688
11:40:37 10223 8192000000 10085290608 10471317504 1095654256 11.796 100 74 132 4 1193034720 83652656 13727808
11:41:52 7095 8192000000 10088245648 10489430016 1109829264 11.931 100 68 131 0 1207625200 83354240 14441696
11:43:04 11330 8192000000 10150115776 10541051904 1125053696 12.075 100 86 160 1 1259788400 118628032 16106672
11:44:22 3523 8192000000 10139550320 10544005120 1136318112 12.181 100 75 137 1 1250990064 100137312 14534640
11:45:37 1105 8192000000 10178764336 10575040512 1145832400 12.270 100 92 175 0 1289238000 125419424 17986176
11:46:42 1636 8192000000 10174574096 10577985536 1147952464 12.290 100 81 154 1 1293058064 128205872 16899728
11:47:47 833 8192000000 10236516576 10633400320 1155694528 12.363 100 96 189 1 1347778512 170162720 21921264
11:48:53 1296 8192000000 10217333776 10624737280 1151122800 12.320 100 86 167 0 1330168288 160494592 18550896
11:49:59 1185 8192000000 10238427472 10641903616 1161107488 12.414 100 93 172 1 1352709792 170565984 21036320
11:51:04 674 8192000000 10236370016 10642178048 1167323472 12.472 100 96 186 0 1348252208 160271824 20656912
11:52:07 1066 8192000000 10257731184 10661179392 1162468240 12.426 100 95 188 0 1373282160 190086256 20727664
11:53:14 1147 8192000000 10284288864 10686877696 1173048736 12.525 100 95 187 1 1394750048 202062688 19638624
11:54:18 947 8192000000 10314899088 10736058368 1177408432 12.566 100 94 185 1 1427109616 228349072 21352112
11:55:23 1057 8192000000 10316073648 10739867648 1176298048 12.556 100 95 186 2 1421865984 224352688 21215248
11:56:28 954 8192000000 10285828864 10728103936 1172860160 12.524 100 90 175 2 1392130320 198424416 20845744
11:57:32 1536 8192000000 10297790064 10734964736 1157737056 12.382 99 94 181 0 1401246352 222023104 21486192
11:58:36 2555 8192000000 10289404992 10716278784 1159595040 12.399 99 75 148 2 1411107936 233714160 17798736
11:59:43 2204 8192000000 10314395312 10730762240 1159605936 12.400 100 66 119 3 1421995296 247710272 14679088
12:00:57 4880 8192000000 10344871200 10775076864 1148092496 12.292 99 64 124 0 1449316784 285400272 15824016
12:02:05 761 8192000000 10360708976 10779693056 1167112400 12.470 100 100 196 1 1466428912 276319072 22997440
12:03:08 871 8192000000 10397444304 10822205440 1174983200 12.543 100 90 181 3 1501882800 306172096 20727504
12:04:12 8854 8192000000 10347067712 10812280832 1172726720 12.522 100 50 98 0 1449801712 264675376 12399616
12:05:23 7154 8192000000 10395614272 10827689984 1170064528 12.497 100 62 122 2 1500859360 316162256 14632576
12:06:44 2761 8192000000 10402374080 10849959936 1163199056 12.433 100 67 132 1 1505733024 326815440 15718528
12:07:48 5501 8192000000 10391605216 10862452736 1170053936 12.497 100 47 94 1 1509239504 327466800 11718768
12:09:04 1864 8192000000 10368984048 10808762368 1159372624 12.397 100 70 141 0 1468870528 292891296 16606608
12:10:10 6515 8192000000 10354179232 10806349824 1154614960 12.353 100 51 102 0 1469686144 302238528 12832656
12:11:19 8129 8192000000 10319813648 10739515392 1153745984 12.345 100 51 99 0 1422187888 257065168 11376736
12:12:31 4298 8192000000 10335428304 10763776000 1143101952 12.245 99 52 101 1 1425194016 270077568 12014496
12:13:36 12575 8192000000 10352648976 10817900544 1150881840 12.318 100 38 74 2 1449781584 289428560 9471184
12:14:50 2687 8192000000 10331846528 10806272000 1154725712 12.354 100 51 102 1 1426710576 259809056 12175808
12:15:54 27801 8192000000 10262816928 10686443520 1161507936 12.417 100 43 76 2 1364308432 194347040 8453456
12:17:50 12373 8192000000 10248306944 10682957824 1132607888 12.146 98 33 64 3 1351290496 211189696 7492912
12:19:40 18190 8192000000 10228649376 10732077056 1129726352 12.119 100 23 44 1 1331470320 196049792 5694176
12:22:13 17347 8192000000 10104975312 10548359168 1134780944 12.166 100 43 86 3 1203840752 59460192 9599616
12:23:36 36841 8192000000 10084886416 10530054144 1125641664 12.080 98 19 38 0 1211302848 81109856 4551328
12:25:24 79147 8192000000 10154424384 10589753344 1141181760 12.227 99 55 103 0 1239691568 87148304 11361504
12:27:58 49276 8192000000 10157818752 10615394304 1146353152 12.275 99 22 44 0 1249524464 98249472 4921840
12:29:50 40633 8192000000 10199763520 10656186368 1147389424 12.285 99 18 36 0 1296176320 144934848 3852048
12:31:52 45216 8192000000 10138890912 10666196992 1155511328 12.361 100 13 26 0 1259123024 100366496 3245200
12:34:08 82590 8192000000 10065977456 10539868160 1123962480 12.064 99 11 22 0 1168356416 42131088 2262848
12:36:37 113149 8192000000 10085114368 10546106368 1133156032 12.151 100 19 38 1 1197218608 59943616 4118960
12:39:47 132312 8192000000 10072398336 10555019264 1090034240 11.743 95 20 40 0 1190297712 95922944 4340528
12:43:16 32484 8192000000 10098273680 10546778112 1140104560 12.217 100 20 40 1 1216528608 72400320 4023728
12:44:51 119074 8192000000 10093406464 10611208192 1142427600 12.238 100 7 14 0 1201689536 58042144 1219792
12:47:51 45586 8192000000 10053814592 10501087232 1114488880 11.975 98 14 26 0 1143400896 26477792 2434224
12:49:37 75161 8192000000 10104857472 10548854784 1081822496 11.665 94 24 48 1 1150943792 64015200 5106096
12:51:55 39514 8192000000 10133642848 10577833984 1134795776 12.167 99 19 38 2 1211955760 72888752 4271232
12:53:48 48977 8192000000 10229445456 10690785280 1145138064 12.264 99 38 76 2 1305916512 152517888 8260560
12:55:57 82293 8192000000 10132483104 10666131456 1151456016 12.323 100 22 45 1 1277211744 120653504 5102224
12:58:27 30994 8192000000 10110367744 10545618944 1105611808 11.891 97 37 73 1 1168589376 55313584 7663984
13:00:03 54045 8192000000 10110410176 10588581888 1123719392 12.062 99 17 34 0 1195106032 68095712 3290928
13:01:57 122290 8192000000 10079811072 10538774528 1097751456 11.816 97 33 66 0 1143858464 38398848 7708160
13:05:14 71369 8192000000 10077497872 10537086976 1108996688 11.923 98 26 52 0 1169592112 55035280 5560144
13:07:27 66836 8192000000 10036780320 10536828928 1091379008 11.756 96 15 30 0 1161145552 66753104 3013440
13:10:08 51133 8192000000 10082997056 10527522816 1117489696 12.003 99 12 24 0 1173529520 53226672 2813152
13:12:00 67530 8192000000 10071404944 10514386944 1113086576 11.962 99 12 22 0 1192372128 77179104 2106448
13:14:09 88997 8192000000 10054299920 10527973376 1065459792 11.509 95 10 20 2 1113379648 45831680 2088176
13:16:38 28767 8192000000 10130566432 10555891712 1122578048 12.051 99 51 103 1 1214866624 81104368 11184208
13:18:08 34886 8192000000 10132647728 10566045696 1134144528 12.160 100 26 52 1 1244220064 104865200 5210336
13:19:43 45080 8192000000 10190102000 10626297856 1129710992 12.119 99 25 50 0 1249745392 114590896 5443504
13:21:30 181251 8192000000 10075674832 10602356736 1074806848 11.598 95 10 20 0 1135587072 58632112 2148112
13:25:31 37238 8192000000 10120547968 10551537664 1120417856 12.031 99 30 61 0 1208631696 82056384 6157456
13:27:09 105469 8192000000 10080597104 10585513984 1120804032 12.035 100 36 72 0 1221868016 93279760 7784224
13:29:56 99577 8192000000 10047056704 10489143296 1081980224 11.666 97 15 23 2 1127125760 42557968 2587568
13:32:38 115836 8192000000 10115200160 10579050496 1107153264 11.905 98 16 32 1 1172210128 61887344 3169520
13:35:36 54904 8192000000 10108491536 10557935616 1110735584 11.939 98 28 55 2 1202398640 85411888 6251168

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 21-05-2022 13:39:53 to 21-05-2022 13:40:09.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 106 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 21-05-2022 13:40:09 to 21-05-2022 13:40:11.

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          210829
MON$OLDEST_ACTIVE               213489
MON$OLDEST_SNAPSHOT             213489
MON$NEXT_TRANSACTION            213489
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                       2111552
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             763
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2767 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2767 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2767 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2767 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 ---
:120: 
:121: mscs-test-161.budzdorov.ru    Sat May 21 13:37:21 2022
:122:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:123: 
:124: 
:125: mscs-test-161.budzdorov.ru    Sat May 21 13:37:21 2022
:126:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:127: 
:128: 
:129: mscs-test-161.budzdorov.ru    Sat May 21 13:37:34 2022
:130:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:131: 
--- end of diff output ---

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

21.05.22 13:40:13. 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-05-21 13:40:15 No severe PSQL-related problems occured
21.05.22 13:40:16. End of report.