Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 19.04.2022 13:38
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 16524788 kB
MemAvailable 31517680 kB
Buffers 0 kB
Cached 15221952 kB
SwapCached 2712 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-04-19 13:37:12
DTS_END                         2022-04-19 13:37:12.4120
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/57500
TRN_ID                          216042
ATT_ID                          583
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: 8667.99 23043 1040159
customer order: creation 165.80 464 19896
customer order: refuse 13.52 375 1622
order to supplier: creation 44.75 1350 5370
order to supplier: removal 3.32 169455 398
invoice (draft): creation 43.62 3059 5234
invoice (draft): removal 48.97 1025 5876
invoice accept: apply 39.99 24761 4799
invoice accept: cancel 43.16 1149 5179
customer reserve: creation 3715.45 235 445854
customer reserve: removal 4276.80 103 513216
realization accept: apply 80.30 239 9636
realization accept: cancel 69.65 167 8358
payment from customer: creation 56.55 178 6786
payment from customer: removal 5.43 124 652
payment to supplier: creation 45.48 179 5458
payment to supplier: removal 3.20 102 384
service: total inventory turnovers 3.92 3696 470
service: total monetary turnovers 7.18 341 862
service: aggregate perf. data 0.87 15655 104
service: refresh index statistics 0.04 238207 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 19-04-2022 13:39:12 to 19-04-2022 13:39:14.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8260
TEST_TIME 2 8293
TEST_TIME 3 7184
TEST_TIME 4 6577
TEST_TIME 5 6250
TEST_TIME 6 6573
TEST_TIME 7 6373
TEST_TIME 8 6465
TEST_TIME 9 6479
TEST_TIME 10 6619
TEST_TIME 11 6736
TEST_TIME 12 6975
TEST_TIME 13 7129
TEST_TIME 14 7472
TEST_TIME 15 7470
TEST_TIME 16 7395
TEST_TIME 17 7423
TEST_TIME 18 7516
TEST_TIME 19 7866
TEST_TIME 20 8226
TEST_TIME 21 8870
TEST_TIME 22 8861
TEST_TIME 23 8701
TEST_TIME 24 8580
TEST_TIME 25 8395
TEST_TIME 26 8986
TEST_TIME 27 9315
TEST_TIME 28 9275
TEST_TIME 29 10029
TEST_TIME 30 10581
TEST_TIME 31 11028
TEST_TIME 32 11468
TEST_TIME 33 11320
TEST_TIME 34 11109
TEST_TIME 35 10828
TEST_TIME 36 10715
TEST_TIME 37 10588
TEST_TIME 38 10382
TEST_TIME 39 10312
TEST_TIME 40 10217
TEST_TIME 41 10175
TEST_TIME 42 10029
TEST_TIME 43 9876
TEST_TIME 44 9987
TEST_TIME 45 9944
TEST_TIME 46 9767
TEST_TIME 47 9704
TEST_TIME 48 9572
TEST_TIME 49 9454
TEST_TIME 50 9480
TEST_TIME 51 9411
TEST_TIME 52 9387
TEST_TIME 53 9282
TEST_TIME 54 9211
TEST_TIME 55 9208
TEST_TIME 56 9161
TEST_TIME 57 9178
TEST_TIME 58 9133
TEST_TIME 59 9195
TEST_TIME 60 9222
TEST_TIME 61 9146
TEST_TIME 62 9159
TEST_TIME 63 9221
TEST_TIME 64 9277
TEST_TIME 65 9286
TEST_TIME 66 9213
TEST_TIME 67 9249
TEST_TIME 68 9303
TEST_TIME 69 9408
TEST_TIME 70 9348
TEST_TIME 71 9219
TEST_TIME 72 9161
TEST_TIME 73 9118
TEST_TIME 74 9064
TEST_TIME 75 9046
TEST_TIME 76 8963
TEST_TIME 77 8940
TEST_TIME 78 8876
TEST_TIME 79 8942
TEST_TIME 80 8909
TEST_TIME 81 8880
TEST_TIME 82 8849
TEST_TIME 83 8888
TEST_TIME 84 8852
TEST_TIME 85 8864
TEST_TIME 86 8950
TEST_TIME 87 8908
TEST_TIME 88 8984
TEST_TIME 89 8974
TEST_TIME 90 8965
TEST_TIME 91 9001
TEST_TIME 92 8918
TEST_TIME 93 8920
TEST_TIME 94 8865
TEST_TIME 95 8859
TEST_TIME 96 8807
TEST_TIME 97 8802
TEST_TIME 98 8772
TEST_TIME 99 8760
TEST_TIME 100 8750
TEST_TIME 101 8730
TEST_TIME 102 8712
TEST_TIME 103 8694
TEST_TIME 104 8699
TEST_TIME 105 8710
TEST_TIME 106 8736
TEST_TIME 107 8830
TEST_TIME 108 8941
TEST_TIME 109 8944
TEST_TIME 110 8917
TEST_TIME 111 8916
TEST_TIME 112 8881
TEST_TIME 113 8826
TEST_TIME 114 8881
TEST_TIME 115 8842
TEST_TIME 116 8806
TEST_TIME 117 8761
TEST_TIME 118 8786
TEST_TIME 119 8751
TEST_TIME 120 8688

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 19-04-2022 13:39:15 to 19-04-2022 13:39:19.

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 1450698 1450698 0 0.00 0 24406 13 0 0
doc_list_biud 1450689 1450689 0 0.00 0 121476 42 0 0
sp_add_invoice_to_stock 4982 4799 183 3.67 82 197478 24762 0 183
sp_cancel_adding_invoice 5179 5179 0 0.00 6 86779 1150 0 0
sp_cancel_client_order 1622 1622 0 0.00 5 9568 376 0 0
sp_cancel_customer_reserve 513216 513216 0 0.00 1 31825 103 0 0
sp_cancel_pay_from_customer 652 652 0 0.00 4 4143 124 0 0
sp_cancel_pay_to_supplier 384 384 0 0.00 4 2659 103 0 0
sp_cancel_supplier_invoice 5888 5876 12 0.20 2 121482 1026 0 12
sp_cancel_supplier_order 398 398 0 0.00 9 1422100 169455 0 0
sp_cancel_write_off 8358 8358 0 0.00 2 9250 167 0 0
sp_client_order 19896 19896 0 0.00 6 46310 465 0 0
sp_customer_reserve 445854 445854 0 0.00 1 109739 236 0 0
sp_fill_shopping_cart 30484 30484 0 0.00 1 33613 85 0 0
sp_fill_shopping_cart_clo_res 445823 445823 0 0.00 0 22873 19 0 0
sp_get_clo_for_invoice 4757 4757 0 0.00 0 33046 366 0 0
sp_kill_cost_storno 14573 14573 0 0.00 0 9245 71 0 0
sp_kill_qstorno_ret_qs2qd 1041064 1041064 0 0.00 0 121474 46 0 0
sp_kill_qty_storno 549039 549039 0 0.00 0 121476 103 0 0
sp_lock_selected_doc 19750 19750 0 0.00 0 3365 5 0 0
sp_make_cost_storno 26635 26635 0 0.00 0 24345 77 0 0
sp_multiply_rows_for_pdistr 26634 26634 0 0.00 0 11390 38 0 0
sp_multiply_rows_for_qdistr 19895 19895 0 0.00 0 46249 197 0 0
sp_pay_from_customer 6786 6786 0 0.00 5 14894 178 0 0
sp_pay_to_supplier 5458 5458 0 0.00 6 12731 180 0 0
sp_payment_common 12244 12244 0 0.00 2 10030 46 0 0
sp_qd_handle_on_cancel_clo 1622 1622 0 0.00 0 9558 318 0 0
sp_qd_handle_on_invoice_upd_sts 9934 9934 0 0.00 0 17968 490 0 0
sp_qd_handle_on_reserve_upd_sts 9635 9635 0 0.00 0 10561 49 0 0
sp_reserve_write_off 9636 9636 0 0.00 8 14505 240 0 0
sp_supplier_invoice 5234 5234 0 0.00 9 118448 3060 0 0
sp_supplier_order 5370 5370 0 0.00 7 64123 1351 0 0
srv_aggregate_perf_data 104 104 0 0.00 2240 174041 15655 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 660 195405 39915 0 0
srv_find_qd_qs_mism 993242 993242 0 0.00 0 13346 10 0 0
srv_make_invnt_saldo 470 470 0 0.00 59 94175 3696 0 0
srv_make_money_saldo 862 862 0 0.00 1 8795 341 0 0
srv_recalc_idx_stat 5 5 0 0.00 68725 444483 238208 0 0
t$perf-abend:sp_add_invoice_to_stock 183 183 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4802 4802 0 0.00 1 9893 836 0 0
t$perf-norm:sp_cancel_adding_invoice 332 332 0 0.00 1 6276 424 0 0
t$perf-norm:sp_cancel_client_order 1622 1622 0 0.00 0 9806 29 0 0
t$perf-norm:sp_cancel_customer_reserve 628 628 0 0.00 0 869 24 0 0
t$perf-norm:sp_cancel_pay_from_customer 652 652 0 0.00 0 4615 22 0 0
t$perf-norm:sp_cancel_pay_to_supplier 384 384 0 0.00 0 186 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 393 393 0 0.00 0 1078 30 0 0
t$perf-norm:sp_cancel_supplier_order 398 398 0 0.00 0 79979 6850 0 0
t$perf-norm:sp_cancel_write_off 955 955 0 0.00 0 3765 19 0 0
t$perf-norm:sp_client_order 19898 19898 0 0.00 0 16642 34 0 0
t$perf-norm:sp_customer_reserve 1001 1001 0 0.00 0 12876 28 0 0
t$perf-norm:sp_pay_from_customer 6786 6786 0 0.00 0 16416 22 0 0
t$perf-norm:sp_pay_to_supplier 5458 5458 0 0.00 0 8448 20 0 0
t$perf-norm:sp_reserve_write_off 9636 9636 0 0.00 0 12592 25 0 0
t$perf-norm:sp_supplier_invoice 5234 5234 0 0.00 0 6829 33 0 0
t$perf-norm:sp_supplier_order 5370 5370 0 0.00 0 10183 29 0 0
t$perf-norm:srv_aggregate_perf_data 104 104 0 0.00 0 204 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 49 3 0 0
t$perf-norm:srv_make_invnt_saldo 470 470 0 0.00 0 659 9 0 0
t$perf-norm:srv_make_money_saldo 862 862 0 0.00 0 1285 5 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 4 283 69 0 0
v_add_invoice_to_stock 9867 9501 366 3.70 0 6153 23 0 366
v_all_customers 35792 35792 0 0.00 0 148 3 0 0
v_all_suppliers 29190 29190 0 0.00 0 67 4 0 0
v_all_wares 39783 39783 0 0.00 0 62 3 0 0
v_cancel_adding_invoice 664 664 0 0.00 0 142 11 0 0
v_cancel_client_order 3244 3244 0 0.00 0 778 7 0 0
v_cancel_customer_prepayment 1304 1304 0 0.00 0 4081 15 0 0
v_cancel_customer_reserve 1256 1256 0 0.00 0 1080 10 0 0
v_cancel_payment_to_supplier 768 768 0 0.00 0 250 9 0 0
v_cancel_supplier_invoice 810 786 24 2.96 0 2186 24 0 24
v_cancel_supplier_order 796 796 0 0.00 0 1270 11 0 0
v_cancel_write_off 1910 1910 0 0.00 0 2431 22 0 0
v_max_id_clo_ord 5366 5366 0 0.00 0 4823 7 0 0
v_max_id_clo_res 1001 1001 0 0.00 0 5166 22 0 0
v_max_id_ord_sup 5224 5224 0 0.00 0 5612 9 0 0
v_max_non_paid_invoice 1453 1453 0 0.00 0 519 7 0 0
v_max_non_paid_realizn 3765 3765 0 0.00 0 4918 9 0 0
v_min_id_clo_ord 5366 5366 0 0.00 0 11358 11 0 0
v_min_id_clo_res 1001 1001 0 0.00 0 4663 13 0 0
v_min_id_ord_sup 5224 5224 0 0.00 0 3789 8 0 0
v_min_non_paid_invoice 5458 5458 0 0.00 0 8614 17 0 0
v_min_non_paid_realizn 6786 6786 0 0.00 0 10027 20 0 0
v_our_firm 10030 10030 0 0.00 0 83 4 0 0
v_reserve_write_off 19270 19270 0 0.00 0 4898 8 0 0
x_lock_depdocs_on_canc_invoice 10662 10662 0 0.00 0 13889 122 0 0
x_lock_depdocs_on_canc_sup_ord 398 398 0 0.00 0 23282 380 0 0
x_make_qstorno_1000_1200 5370 5370 0 0.00 2 64103 1182 0 0
x_make_qstorno_1200_2000 5234 5234 0 0.00 3 118442 2819 0 0
x_make_qstorno_2100_3300 445852 445852 0 0.00 0 109736 205 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 19-04-2022 13:39:20 to 19-04-2022 13:39:24.

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:16 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:17 37464 8192000000 9720488992 10018201600 794378480 8.839 100 90 180 0 828720864 12101856 22240528
11:10:02 20598 8192000000 9757980624 10066370560 834675968 9.246 100 86 172 0 868227840 13259904 20291968
11:11:27 12623 8192000000 9800698288 10123046912 866426448 9.564 100 83 165 2 908300656 23409696 18464512
11:12:48 9067 8192000000 9867039760 10204160000 919130944 10.088 100 79 158 0 981314544 45039792 17143808
11:14:00 4419 8192000000 9926955760 10273792000 963053136 10.519 100 82 160 0 1044811952 64787936 16970880
11:15:09 1717 8192000000 9966955408 10320265216 998214880 10.861 100 90 177 0 1085713424 69624432 17874112
11:16:13 1455 8192000000 10002646576 10367635456 1021547232 11.087 100 92 181 2 1126036592 86487024 18002336
11:17:17 1296 8192000000 10016587664 10391330816 1042752464 11.291 100 93 177 1 1139393824 78527376 18113984
11:18:20 1128 8192000000 10051301744 10425384960 1061777392 11.473 100 95 186 0 1167289904 86861264 18651248
11:19:22 3726 8192000000 10017336832 10389811200 1066854576 11.522 100 90 168 5 1131644384 49123712 15666096
11:20:26 947 8192000000 10032477216 10412453888 1075367440 11.603 100 93 182 1 1156303344 62754656 18181248
11:21:28 11940 8192000000 10039757296 10425348096 1074927728 11.599 100 77 150 0 1163921984 73522832 15471424
11:22:45 919 8192000000 10045157296 10431741952 1082107152 11.668 100 93 183 0 1168694416 68033152 18554112
11:23:47 1305 8192000000 10040288448 10428186624 1091222000 11.754 100 93 165 2 1164398464 54974192 18202272
11:25:01 827 8192000000 10047040672 10441990144 1095650512 11.796 100 76 145 3 1166710880 57185376 13874992
11:26:03 1136 8192000000 10090278448 10485301248 1093195376 11.773 100 90 173 1 1206190448 96442512 16552560
11:27:05 1214 8192000000 10055739152 10443661312 1094112288 11.782 100 93 184 0 1172808560 60246800 18449472
11:28:08 1261 8192000000 10060558848 10451546112 1091056496 11.753 100 96 177 1 1183132480 74794944 17281040
11:29:10 1342 8192000000 10040098576 10431229952 1091005376 11.752 100 96 192 2 1166088064 54647872 20434816
11:30:12 1406 8192000000 10034306304 10420060160 1081166336 11.659 100 95 179 2 1158297696 57450320 19681040
11:31:14 924 8192000000 9982357632 10356346880 1029105088 11.160 99 85 161 1 1106364144 60724928 16534128
11:32:16 1050 8192000000 10006810928 10380111872 1039659040 11.261 100 93 177 2 1127748864 68437584 19652240
11:33:18 1126 8192000000 10013630768 10388660224 1036866848 11.235 100 95 181 1 1133557104 78294768 18395488
11:34:21 1089 8192000000 9976158400 10349297664 1019152784 11.064 100 92 177 4 1097942528 59882512 18907232
11:35:23 1179 8192000000 10007966224 10384617472 1037844512 11.244 100 90 172 2 1129441712 74646848 16950352
11:36:24 1192 8192000000 9976644224 10358837248 1039472048 11.260 99 85 159 3 1095223264 38858112 16893104
11:37:27 9177 8192000000 10002686240 10384269312 1058941952 11.446 100 81 153 1 1125707680 50999376 15766352
11:38:58 6468 8192000000 10039191248 10427850752 1082373600 11.670 100 71 140 0 1159684560 63207792 14103168
11:40:07 1221 8192000000 10059730704 10441506816 1094184032 11.782 100 98 188 2 1181624128 66539680 20900416
11:41:24 4943 8192000000 10124414992 10518507520 1106954128 11.904 100 74 144 1 1239220992 116577472 15689392
11:42:35 706 8192000000 10139671808 10522587136 1113690448 11.967 99 91 172 1 1258029040 126053920 18284672
11:43:44 1200 8192000000 10178662192 10574508032 1136752800 12.185 100 91 172 1 1294781728 138976496 19052432
11:44:51 5186 8192000000 10218141776 10613141504 1142718224 12.241 100 75 140 2 1331971616 173170720 16082672
11:46:01 938 8192000000 10248289600 10644238336 1163978992 12.441 100 89 163 0 1366398880 182823552 19596336
11:47:04 1087 8192000000 10333320176 10730110976 1176086784 12.554 100 96 190 1 1441820640 244653264 21080592
11:48:09 1090 8192000000 10343672208 10745212928 1180691024 12.597 100 97 187 1 1456847472 253380032 22776416
11:49:12 654 8192000000 10364731536 10782420992 1186500208 12.651 100 99 194 0 1474303568 265230000 22573360
11:50:18 1480 8192000000 10392359376 10826915840 1198806864 12.765 100 91 180 0 1512363008 291896896 21659248
11:51:25 1990 8192000000 10390983104 10817785856 1193509120 12.716 100 86 166 0 1501152688 287194752 20448816
11:52:32 1130 8192000000 10436140512 10843475968 1185989504 12.646 100 96 188 0 1541655712 334602320 21063888
11:53:38 2366 8192000000 10497416912 10910253056 1205783408 12.830 100 82 162 0 1606508608 381636544 19088656
11:54:43 1074 8192000000 10530607152 10951942144 1203310512 12.807 100 95 188 0 1632628736 407022128 22296096
11:55:47 1047 8192000000 10554120960 10979520512 1205250192 12.825 100 92 179 0 1651646544 425645632 20750720
11:56:50 878 8192000000 10506584592 10973245440 1187129248 12.657 100 75 141 0 1616461408 410973968 18358192
11:57:53 1259 8192000000 10504326240 10929045504 1171658320 12.512 100 90 176 0 1609885280 417899760 20327200
11:58:55 14813 8192000000 10406923072 10873360384 1156617280 12.372 98 36 72 0 1519069456 352976944 9475232
12:00:17 36347 8192000000 10500452576 10942722048 1174728064 12.541 100 39 78 0 1567852320 383093072 10031184
12:02:23 11925 8192000000 10547130784 11009929216 1159012928 12.394 100 47 92 1 1667538368 496646016 11879424
12:03:44 18446 8192000000 10503089136 11001835520 1165230592 12.452 100 34 68 0 1601462912 427260320 8972000
12:05:04 12266 8192000000 10472155968 10910777344 1142423920 12.238 99 42 82 0 1553709472 401135264 10150288
12:06:22 19848 8192000000 10281741200 10829332480 1134636928 12.165 99 42 85 0 1414575104 268852480 11085696
12:07:43 33259 8192000000 10234610224 10742026240 1132477344 12.145 100 23 44 1 1372740944 234206576 6057024
12:09:22 75784 8192000000 10046472992 10488061952 1080351744 11.651 95 18 36 0 1146902704 63184384 3366576
12:11:38 67362 8192000000 10067110592 10510831616 1131220048 12.133 100 39 70 1 1180575408 42869840 6485520
12:13:52 195403 8192000000 10121539424 10613882880 1109934384 11.932 97 33 65 0 1198840032 82105136 6800512
12:18:09 58943 8192000000 10113191968 10582151168 1142523296 12.239 100 16 30 1 1233529040 87400320 3605424
12:20:20 59707 8192000000 10098571056 10606964736 1122308736 12.049 98 19 38 1 1211261760 84623152 4329872
12:22:27 92306 8192000000 10068483312 10507190272 1108016688 11.914 97 22 42 1 1144339760 31817408 4505664
12:25:05 32572 8192000000 10074320848 10527961088 1126612112 12.089 99 18 36 1 1166149216 35774016 3763088
12:26:39 24694 8192000000 10127729872 10572525568 1151219856 12.321 100 47 91 0 1229304848 67622304 10462688
12:28:19 41434 8192000000 10070754528 10560753664 1140622640 12.221 100 11 23 0 1195581728 52725840 2233248
12:30:10 71989 8192000000 10075722000 10547236864 1129013968 12.112 99 13 26 1 1174360864 41769568 3577328
12:32:31 79793 8192000000 10102062432 10567966720 1138125552 12.198 99 19 38 0 1179641264 37588320 3927392
12:34:52 47953 8192000000 10086869360 10538872832 1117453472 12.003 97 28 56 1 1160941488 38310720 5177296
12:37:27 46871 8192000000 10088921136 10534445056 1114211888 11.972 98 28 56 0 1142858160 23501632 5144640
12:39:32 21359 8192000000 10122926704 10564546560 1141776112 12.232 100 44 88 1 1218860144 67528240 9555792
12:40:54 33134 8192000000 10142413760 10595807232 1149599888 12.306 100 46 89 0 1239880704 82228400 8052416
12:42:27 29470 8192000000 10062191120 10485403648 1103972368 11.875 97 38 73 1 1137568544 25908256 7687920
12:43:57 72361 8192000000 10133948048 10575593472 1130852288 12.129 98 27 54 0 1233196784 96947296 5397200
12:46:10 90768 8192000000 10094462096 10562490368 1099779360 11.836 96 15 28 1 1164359760 61578912 3001488
12:48:46 49710 8192000000 10043088752 10488659968 1090325600 11.746 96 37 72 0 1114851440 16821376 7704464
12:50:38 48584 8192000000 10052520288 10516099072 1096610736 11.805 97 11 20 0 1139863072 40647776 2604560
12:52:27 59378 8192000000 10064475968 10534567936 1129777792 12.119 100 9 18 0 1159068880 27595584 1695504
12:54:31 90275 8192000000 10087570608 10534105088 1127476240 12.098 99 31 58 0 1185979664 52116576 6386848
12:57:02 47990 8192000000 10057825344 10503299072 1108583536 11.919 98 19 38 1 1152413808 39534960 4295312
12:58:58 50744 8192000000 10118733168 10561007616 1088090704 11.725 95 33 66 0 1176949504 81364784 7494016
13:01:04 28134 8192000000 10104177136 10528493568 1093016224 11.771 97 35 70 0 1172689536 72593616 7079696
13:02:45 78889 8192000000 10080581248 10519298048 1086844272 11.713 97 18 36 0 1163155280 72562816 3748192
13:05:04 47787 8192000000 10065794144 10501496832 1099230000 11.830 98 27 52 1 1185447696 80295840 5921856
13:06:53 34868 8192000000 10136218160 10564739072 1119022768 12.018 99 39 78 0 1211767056 85047936 7696352
13:09:17 84470 8192000000 10075208192 10511949824 1129138128 12.113 99 34 69 1 1189548576 53433216 6977232
13:11:46 69228 8192000000 10042300256 10524401664 1120165584 12.029 99 11 22 1 1169523744 47214624 2143536
13:13:57 68019 8192000000 10053834928 10510991360 1112753024 11.958 98 29 58 1 1152567424 33819232 5995168
13:16:11 57943 8192000000 10130522144 10583502848 1131846944 12.139 99 35 70 0 1198166576 58979728 7339904
13:18:09 20009 8192000000 10156618192 10587643904 1140013376 12.216 100 39 78 1 1244671712 96316112 8342224
13:19:33 29839 8192000000 10184194736 10610421760 1116204352 11.991 97 38 76 3 1250193904 124908032 9081520
13:21:04 44241 8192000000 10196295136 10623688704 1129069904 12.113 98 37 74 1 1279785744 142495808 8220032
13:22:49 57724 8192000000 10244115024 10726256640 1137507792 12.192 98 31 62 1 1331344256 186061184 7775280
13:24:53 70787 8192000000 10098151792 10646499328 1126708768 12.090 99 17 34 0 1244315312 113958288 3648256
13:27:10 50713 8192000000 10136668912 10582884352 1126665040 12.090 98 34 68 2 1198603728 64698960 7239728
13:29:02 132026 8192000000 10108969600 10570637312 1123564544 12.061 99 34 67 0 1220027040 89269680 7192816
13:32:24 55556 8192000000 10068765184 10552061952 1116875424 11.997 98 7 14 0 1157482336 38725520 1881392
13:35:39 64394 8192000000 10050537664 10515435520 1118160496 12.010 99 12 24 0 1147846848 27227136 2459216

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 19-04-2022 13:39:26 to 19-04-2022 13:39:41.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 183 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 19-04-2022 13:39:42 to 19-04-2022 13:39: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          215788
MON$OLDEST_ACTIVE               216730
MON$OLDEST_SNAPSHOT             216730
MON$NEXT_TRANSACTION            216730
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                       2049440
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             770
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2761 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2761 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2761 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2761 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 ---
:360: 
:361: mscs-test-161.budzdorov.ru    Tue Apr 19 13:37:23 2022
:362:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:363: 
:364: 
:365: mscs-test-161.budzdorov.ru    Tue Apr 19 13:37:23 2022
:366:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:367: 
:368: 
:369: mscs-test-161.budzdorov.ru    Tue Apr 19 13:37:24 2022
:370:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:371: 
--- end of diff output ---

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

19.04.22 13:39: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-04-19 13:39:48 No severe PSQL-related problems occured
19.04.22 13:39:49. End of report.