Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 26.07.2022 07:47
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 15615224 kB
MemAvailable 30998632 kB
Buffers 0 kB
Cached 16059560 kB
SwapCached 2552 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-07-26 07:47:16
DTS_END                         2022-07-26 07:47:16.9470
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/52528
TRN_ID                          222716
ATT_ID                          642
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: 8975.43 22572 1077052
customer order: creation 174.01 437 20881
customer order: refuse 14.72 399 1766
order to supplier: creation 45.43 1411 5452
order to supplier: removal 3.20 177161 384
invoice (draft): creation 44.59 3165 5351
invoice (draft): removal 47.61 1043 5713
invoice accept: apply 42.53 25168 5104
invoice accept: cancel 43.60 1326 5232
customer reserve: creation 3966.92 239 476030
customer reserve: removal 4298.12 104 515774
realization accept: apply 84.29 284 10115
realization accept: cancel 80.82 172 9698
payment from customer: creation 59.21 171 7105
payment from customer: removal 5.59 125 671
payment to supplier: creation 48.85 204 5862
payment to supplier: removal 3.38 108 405
service: total inventory turnovers 3.96 3776 475
service: total monetary turnovers 7.53 296 904
service: aggregate perf. data 1.05 14731 126
service: refresh index statistics 0.03 221125 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 26-07-2022 07:48:04 to 26-07-2022 07:48:06.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7001
TEST_TIME 2 6205
TEST_TIME 3 5834
TEST_TIME 4 5921
TEST_TIME 5 5707
TEST_TIME 6 5703
TEST_TIME 7 5698
TEST_TIME 8 5730
TEST_TIME 9 6103
TEST_TIME 10 6286
TEST_TIME 11 6241
TEST_TIME 12 6232
TEST_TIME 13 6535
TEST_TIME 14 6619
TEST_TIME 15 7010
TEST_TIME 16 7112
TEST_TIME 17 7259
TEST_TIME 18 7653
TEST_TIME 19 7952
TEST_TIME 20 8276
TEST_TIME 21 8479
TEST_TIME 22 8471
TEST_TIME 23 8341
TEST_TIME 24 8399
TEST_TIME 25 8380
TEST_TIME 26 8399
TEST_TIME 27 8464
TEST_TIME 28 9126
TEST_TIME 29 9109
TEST_TIME 30 10053
TEST_TIME 31 10236
TEST_TIME 32 10341
TEST_TIME 33 10475
TEST_TIME 34 10823
TEST_TIME 35 10648
TEST_TIME 36 10421
TEST_TIME 37 10379
TEST_TIME 38 10320
TEST_TIME 39 10472
TEST_TIME 40 10356
TEST_TIME 41 10306
TEST_TIME 42 10324
TEST_TIME 43 10267
TEST_TIME 44 10235
TEST_TIME 45 10208
TEST_TIME 46 10074
TEST_TIME 47 10050
TEST_TIME 48 10201
TEST_TIME 49 10133
TEST_TIME 50 10090
TEST_TIME 51 10060
TEST_TIME 52 9979
TEST_TIME 53 9978
TEST_TIME 54 9893
TEST_TIME 55 9756
TEST_TIME 56 9630
TEST_TIME 57 9704
TEST_TIME 58 9571
TEST_TIME 59 9524
TEST_TIME 60 9475
TEST_TIME 61 9443
TEST_TIME 62 9462
TEST_TIME 63 9467
TEST_TIME 64 9365
TEST_TIME 65 9270
TEST_TIME 66 9284
TEST_TIME 67 9252
TEST_TIME 68 9318
TEST_TIME 69 9374
TEST_TIME 70 9371
TEST_TIME 71 9356
TEST_TIME 72 9346
TEST_TIME 73 9370
TEST_TIME 74 9386
TEST_TIME 75 9349
TEST_TIME 76 9344
TEST_TIME 77 9379
TEST_TIME 78 9396
TEST_TIME 79 9448
TEST_TIME 80 9337
TEST_TIME 81 9261
TEST_TIME 82 9192
TEST_TIME 83 9171
TEST_TIME 84 9148
TEST_TIME 85 9105
TEST_TIME 86 9083
TEST_TIME 87 9027
TEST_TIME 88 9012
TEST_TIME 89 8956
TEST_TIME 90 8986
TEST_TIME 91 8947
TEST_TIME 92 8929
TEST_TIME 93 8918
TEST_TIME 94 8927
TEST_TIME 95 8946
TEST_TIME 96 8917
TEST_TIME 97 8906
TEST_TIME 98 8994
TEST_TIME 99 9130
TEST_TIME 100 9215
TEST_TIME 101 9240
TEST_TIME 102 9254
TEST_TIME 103 9201
TEST_TIME 104 9212
TEST_TIME 105 9173
TEST_TIME 106 9149
TEST_TIME 107 9095
TEST_TIME 108 9071
TEST_TIME 109 9020
TEST_TIME 110 8971
TEST_TIME 111 9010
TEST_TIME 112 9026
TEST_TIME 113 9007
TEST_TIME 114 9027
TEST_TIME 115 8997
TEST_TIME 116 8993
TEST_TIME 117 8975
TEST_TIME 118 9030
TEST_TIME 119 9050
TEST_TIME 120 8993

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 26-07-2022 07:48:08 to 26-07-2022 07:48:11.

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 1514855 1514855 0 0.00 0 19527 13 0 0
doc_list_biud 1514852 1514852 0 0.00 0 21202 41 0 0
sp_add_invoice_to_stock 5212 5104 108 2.07 79 197789 25169 0 108
sp_cancel_adding_invoice 5232 5232 0 0.00 7 105412 1327 0 0
sp_cancel_client_order 1766 1766 0 0.00 5 14589 400 0 0
sp_cancel_customer_reserve 515774 515774 0 0.00 1 21208 105 0 0
sp_cancel_pay_from_customer 671 671 0 0.00 4 3576 125 0 0
sp_cancel_pay_to_supplier 405 405 0 0.00 4 2735 108 0 0
sp_cancel_supplier_invoice 5722 5713 9 0.15 3 18670 1044 0 9
sp_cancel_supplier_order 384 384 0 0.00 14 1256460 177162 0 0
sp_cancel_write_off 9698 9698 0 0.00 2 8511 173 0 0
sp_client_order 20881 20881 0 0.00 5 20943 437 0 0
sp_customer_reserve 476030 476030 0 0.00 0 138299 239 0 0
sp_fill_shopping_cart 31670 31670 0 0.00 1 10595 82 0 0
sp_fill_shopping_cart_clo_res 476014 476014 0 0.00 0 18976 20 0 0
sp_get_clo_for_invoice 5077 5077 0 0.00 0 19266 285 0 0
sp_kill_cost_storno 16006 16006 0 0.00 0 10528 77 0 0
sp_kill_qstorno_ret_qs2qd 1047343 1047343 0 0.00 0 21199 46 0 0
sp_kill_qty_storno 553759 553759 0 0.00 0 21201 105 0 0
sp_lock_selected_doc 20956 20956 0 0.00 0 8693 7 0 0
sp_make_cost_storno 28159 28159 0 0.00 0 15284 82 0 0
sp_multiply_rows_for_pdistr 28159 28159 0 0.00 0 8060 37 0 0
sp_multiply_rows_for_qdistr 20881 20881 0 0.00 0 16126 156 0 0
sp_pay_from_customer 7105 7105 0 0.00 5 15300 171 0 0
sp_pay_to_supplier 5862 5862 0 0.00 6 14838 204 0 0
sp_payment_common 12967 12967 0 0.00 2 6384 41 0 0
sp_qd_handle_on_cancel_clo 1766 1766 0 0.00 0 14513 318 0 0
sp_qd_handle_on_invoice_upd_sts 10309 10309 0 0.00 0 17292 526 0 0
sp_qd_handle_on_reserve_upd_sts 10115 10115 0 0.00 0 14788 65 0 0
sp_reserve_write_off 10115 10115 0 0.00 8 16687 285 0 0
sp_supplier_invoice 5351 5351 0 0.00 8 96327 3166 0 0
sp_supplier_order 5452 5452 0 0.00 6 42467 1411 0 0
srv_aggregate_perf_data 126 126 0 0.00 2321 199580 14732 0 0
srv_fill_mon_cache_memory 68 68 0 0.00 887 131027 37257 0 0
srv_find_qd_qs_mism 1027296 1027296 0 0.00 0 19524 10 0 0
srv_make_invnt_saldo 475 475 0 0.00 57 50946 3776 0 0
srv_make_money_saldo 904 904 0 0.00 1 10203 297 0 0
srv_recalc_idx_stat 4 4 0 0.00 106193 392087 221125 0 0
t$perf-abend:sp_add_invoice_to_stock 108 108 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5107 5107 0 0.00 1 18966 857 0 0
t$perf-norm:sp_cancel_adding_invoice 417 417 0 0.00 1 7990 449 0 0
t$perf-norm:sp_cancel_client_order 1766 1766 0 0.00 0 1803 27 0 0
t$perf-norm:sp_cancel_customer_reserve 694 694 0 0.00 0 1034 24 0 0
t$perf-norm:sp_cancel_pay_from_customer 671 671 0 0.00 0 2653 17 0 0
t$perf-norm:sp_cancel_pay_to_supplier 405 405 0 0.00 0 417 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 376 376 0 0.00 0 10410 57 0 0
t$perf-norm:sp_cancel_supplier_order 384 384 0 0.00 0 97001 7401 0 0
t$perf-norm:sp_cancel_write_off 1053 1053 0 0.00 0 635 17 0 0
t$perf-norm:sp_client_order 20881 20881 0 0.00 0 16913 35 0 0
t$perf-norm:sp_customer_reserve 988 988 0 0.00 0 456 12 0 0
t$perf-norm:sp_pay_from_customer 7105 7105 0 0.00 0 9303 20 0 0
t$perf-norm:sp_pay_to_supplier 5862 5862 0 0.00 0 7706 18 0 0
t$perf-norm:sp_reserve_write_off 10115 10115 0 0.00 0 2047 19 0 0
t$perf-norm:sp_supplier_invoice 5352 5352 0 0.00 0 5045 30 0 0
t$perf-norm:sp_supplier_order 5452 5452 0 0.00 0 3487 28 0 0
t$perf-norm:srv_aggregate_perf_data 126 126 0 0.00 0 621 19 0 0
t$perf-norm:srv_fill_mon_cache_memory 68 68 0 0.00 0 934 26 0 0
t$perf-norm:srv_make_invnt_saldo 475 475 0 0.00 0 690 10 0 0
t$perf-norm:srv_make_money_saldo 904 904 0 0.00 0 576 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 124 63 0 0
v_add_invoice_to_stock 10366 10150 216 2.08 0 5820 26 0 216
v_all_customers 38194 38194 0 0.00 0 595 4 0 0
v_all_suppliers 30312 30312 0 0.00 0 141 4 0 0
v_all_wares 41760 41760 0 0.00 0 172 4 0 0
v_cancel_adding_invoice 834 834 0 0.00 0 2055 15 0 0
v_cancel_client_order 3532 3532 0 0.00 0 12695 16 0 0
v_cancel_customer_prepayment 1342 1342 0 0.00 0 3501 17 0 0
v_cancel_customer_reserve 1388 1388 0 0.00 0 1148 11 0 0
v_cancel_payment_to_supplier 810 810 0 0.00 0 1658 13 0 0
v_cancel_supplier_invoice 770 752 18 2.33 0 4516 37 0 18
v_cancel_supplier_order 768 768 0 0.00 0 4513 29 0 0
v_cancel_write_off 2106 2106 0 0.00 0 4160 26 0 0
v_max_id_clo_ord 5452 5452 0 0.00 0 3106 6 0 0
v_max_id_clo_res 988 988 0 0.00 0 2251 14 0 0
v_max_id_ord_sup 5338 5338 0 0.00 0 4190 6 0 0
v_max_non_paid_invoice 1496 1496 0 0.00 0 411 7 0 0
v_max_non_paid_realizn 3671 3671 0 0.00 0 2393 8 0 0
v_min_id_clo_ord 5452 5452 0 0.00 0 2990 9 0 0
v_min_id_clo_res 988 988 0 0.00 0 5796 17 0 0
v_min_id_ord_sup 5338 5338 0 0.00 0 3801 10 0 0
v_min_non_paid_invoice 5862 5862 0 0.00 0 5595 13 0 0
v_min_non_paid_realizn 7105 7105 0 0.00 0 6379 14 0 0
v_our_firm 10434 10434 0 0.00 0 140 4 0 0
v_reserve_write_off 20230 20230 0 0.00 0 12698 14 0 0
x_lock_depdocs_on_canc_invoice 10569 10569 0 0.00 0 20521 145 0 0
x_lock_depdocs_on_canc_sup_ord 384 384 0 0.00 0 19521 422 0 0
x_make_qstorno_1000_1200 5452 5452 0 0.00 2 42446 1251 0 0
x_make_qstorno_1200_2000 5351 5351 0 0.00 4 96261 2930 0 0
x_make_qstorno_2100_3300 476029 476029 0 0.00 0 138297 208 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 26-07-2022 07:48:12 to 26-07-2022 07:48:16.

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
05:17:20 2 8192000000 8796669104 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
05:18:21 27970 8192000000 9737136064 10039062528 816754400 9.066 100 91 182 0 846735632 8235632 21745600
05:20:02 23312 8192000000 9779673952 10093953024 848257376 9.383 100 88 177 0 885402976 17035104 20110496
05:21:29 13960 8192000000 9826859248 10149392384 888012256 9.779 100 79 158 0 928641584 23153760 17475568
05:22:47 10689 8192000000 9879794224 10214117376 920415872 10.100 100 85 170 0 987284080 48606944 18261264
05:24:02 2909 8192000000 9923464528 10270433280 970682592 10.593 100 84 169 0 1046317552 58443808 17191152
05:25:09 1742 8192000000 9973605008 10329833472 995781760 10.838 100 87 165 1 1088062464 76809296 15471408
05:26:14 1805 8192000000 9997587904 10360053760 1017109696 11.044 100 82 161 1 1114181072 80951888 16119488
05:27:17 1023 8192000000 10009587488 10385342464 1041476240 11.279 100 98 185 0 1131972160 72157664 18338256
05:28:20 1035 8192000000 10020992128 10396209152 1057974832 11.437 100 95 184 1 1143860688 67090544 18795312
05:29:23 5055 8192000000 10033278688 10411974656 1062896816 11.484 100 82 152 0 1163896368 86920864 14078688
05:30:31 1186 8192000000 10049093744 10431156224 1070344048 11.555 100 98 192 0 1169061648 79182192 19535408
05:31:33 1493 8192000000 10052281376 10438066176 1078910704 11.637 100 88 172 2 1173841168 78399136 16531328
05:32:36 1224 8192000000 10045064752 10432434176 1083504512 11.681 100 92 178 2 1175056960 74129888 17422560
05:33:38 2685 8192000000 10044443152 10436153344 1087604864 11.720 100 72 134 4 1165190064 64379968 13205232
05:34:42 973 8192000000 10059453008 10449670144 1093990688 11.781 100 92 176 2 1184798704 72247040 18560976
05:35:44 1325 8192000000 10045718640 10445152256 1096584752 11.805 100 71 133 1 1170299712 60848080 12866880
05:36:46 1741 8192000000 10059927440 10449063936 1099076592 11.829 100 77 154 1 1176951440 62739872 15134976
05:37:49 1131 8192000000 10080380352 10475020288 1102453200 11.861 100 96 190 2 1201765936 80613840 18698896
05:38:51 1060 8192000000 10051320128 10442174464 1097981824 11.818 100 87 168 0 1176854816 60565984 18307008
05:39:53 2267 8192000000 10023552512 10417242112 1082185568 11.668 100 69 132 3 1143789184 47827840 13775776
05:40:56 893 8192000000 10008915264 10392866816 1055479904 11.413 100 91 167 0 1132416352 59443472 17492976
05:41:57 1355 8192000000 10007933968 10383970304 1052792176 11.387 100 96 185 3 1134275984 61625280 19858528
05:43:00 1484 8192000000 10036114928 10421571584 1053963216 11.399 99 93 177 3 1155052976 83142384 17947376
05:44:02 1226 8192000000 10003406800 10376462336 1024424784 11.115 99 96 183 3 1124324416 80969776 18929856
05:45:04 1589 8192000000 9983587168 10363998208 1030583728 11.174 100 63 119 2 1101755904 59337872 11834304
05:46:07 1047 8192000000 9997087888 10370330624 1035410496 11.221 100 97 180 3 1122660176 68082832 19166848
05:47:09 1700 8192000000 9941183600 10317602816 1009750752 10.973 97 77 150 1 1057771056 32228448 15791856
05:48:23 5464 8192000000 9980008272 10364461056 1059894208 11.455 100 59 105 1 1099395520 28955312 10546000
05:49:38 894 8192000000 10061686256 10440863744 1076481120 11.614 100 94 181 4 1176852048 81274752 19096176
05:50:44 14412 8192000000 10069783040 10468884480 1083664224 11.682 100 74 142 0 1193766336 94891152 15210960
05:52:05 7007 8192000000 10090904176 10484125696 1101195968 11.849 100 71 137 3 1208842320 92906608 14739744
05:53:16 9623 8192000000 10140265616 10537517056 1119543440 12.023 100 79 154 2 1252558528 116566768 16448320
05:54:32 1269 8192000000 10164548384 10555768832 1130680576 12.128 100 87 166 1 1278381040 130563424 17137040
05:55:39 4156 8192000000 10179151120 10579939328 1137415200 12.191 100 81 153 2 1289713696 136703568 15594928
05:56:47 1175 8192000000 10201130128 10596700160 1143472240 12.248 100 91 178 2 1320382816 156276352 20634224
05:57:51 1370 8192000000 10234817104 10629771264 1152498992 12.333 100 86 163 0 1345478832 175841744 17138096
05:58:56 2260 8192000000 10295715792 10699243520 1169198176 12.489 100 86 165 2 1404557728 216903840 18455712
06:00:03 1580 8192000000 10337508224 10748551168 1179086016 12.582 100 96 185 2 1449865008 250249216 20529776
06:01:09 910 8192000000 10386895248 10793889792 1189769008 12.681 100 99 193 0 1497724144 286064112 21891024
06:02:12 879 8192000000 10404340528 10827173888 1200461728 12.781 100 99 193 0 1510847552 288552208 21833616
06:03:17 2405 8192000000 10428070448 10841661440 1189437536 12.678 100 92 179 1 1539734304 328700736 21596032
06:04:24 1157 8192000000 10461468752 10879602688 1194317648 12.724 100 90 176 0 1562257712 346514256 21425808
06:05:29 1420 8192000000 10425837120 10903277568 1188837904 12.673 100 94 178 2 1533068112 323041376 21188832
06:06:35 1439 8192000000 10399624992 10842226688 1197348432 12.752 100 91 171 3 1508303328 290017152 20937744
06:07:40 2624 8192000000 10460757920 10906132480 1204745904 12.820 100 78 151 0 1563988592 340965280 18277408
06:08:46 1051 8192000000 10445877056 10858455040 1212435296 12.892 100 83 165 1 1550213904 318348048 19430560
06:09:51 7290 8192000000 10480788048 10910617600 1209167056 12.861 100 61 116 0 1588558704 365291344 14100304
06:11:05 12018 8192000000 10522788384 10966200320 1213243344 12.899 100 50 100 1 1633863792 408458848 12161600
06:12:49 9953 8192000000 10547004368 10995531776 1193466912 12.716 100 40 80 0 1657030304 452874240 10689152
06:14:22 13265 8192000000 10542702640 10977177600 1169035888 12.488 100 42 84 0 1644503040 464483248 10983904
06:15:57 40950 8192000000 10424494336 10915573760 1147898656 12.290 99 30 60 0 1531987344 376188992 7899696
06:17:41 36434 8192000000 10222696848 10695192576 1151065712 12.320 99 39 77 2 1345289120 185302368 8921040
06:19:20 4702 8192000000 10213328336 10678128640 1135932160 12.177 99 76 146 2 1310058752 158425008 15701584
06:20:27 4760 8192000000 10181716832 10676346880 1136582208 12.183 99 78 152 0 1271507856 117650912 17274736
06:21:33 21550 8192000000 10191145408 10677940224 1159716208 12.401 100 35 70 0 1311697488 143850832 8130448
06:22:55 32943 8192000000 10224393552 10651729920 1167793632 12.476 100 61 118 0 1313140432 131856928 13489872
06:24:32 56607 8192000000 10222010160 10734567424 1162597760 12.428 100 22 44 0 1348488336 180515312 5375264
06:27:51 98281 8192000000 10151991024 10595500032 1141773616 12.232 99 14 28 0 1258037616 113393664 2870336
06:30:32 85159 8192000000 10086055360 10545709056 1116165552 11.991 98 33 68 0 1195174688 71548736 7460400
06:33:00 34416 8192000000 10104339680 10544078848 1142997808 12.244 100 21 40 1 1195382768 47235056 5149904
06:34:41 102596 8192000000 10096703072 10593484800 1133578384 12.155 99 8 16 0 1201426944 66468272 1380288
06:37:57 70299 8192000000 10069454512 10492104704 1094892080 11.789 96 41 66 2 1125748960 21962512 8894368
06:40:08 83909 8192000000 10075146272 10554609664 1122351392 12.049 99 14 28 0 1193949408 68427008 3171008
06:43:38 131025 8192000000 10063309296 10510331904 1110326960 11.936 98 23 42 1 1180320736 65037248 4956528
06:46:53 26331 8192000000 10109877120 10527129600 1123400560 12.059 99 45 85 1 1183278832 51104288 8773984
06:48:25 14533 8192000000 10091414096 10540908544 1123833248 12.063 99 39 68 2 1189571296 59454112 6283936
06:49:42 42879 8192000000 10097749536 10560921600 1130692544 12.128 99 15 30 0 1194103600 60229040 3182016
06:51:25 113474 8192000000 10099568176 10562723840 1098110304 11.820 96 14 26 0 1147339088 46676272 2552512
06:54:32 65041 8192000000 10059829488 10516365312 1060290240 11.459 94 23 46 1 1136210336 71174352 4745744
06:56:38 33797 8192000000 10074152864 10514927616 1118391520 12.012 99 17 33 0 1171266192 49794224 3080448
06:58:14 17303 8192000000 10098150976 10535964672 1135176176 12.170 100 42 83 1 1183116208 39135216 8804816
06:59:36 35906 8192000000 10089023360 10573258752 1144146768 12.255 100 35 67 1 1203968272 53336176 6485328
07:01:15 57297 8192000000 10073240320 10503229440 1118371952 12.012 98 44 83 1 1165360912 38892992 8095968
07:03:13 14227 8192000000 10122099632 10561613824 1126673200 12.090 99 43 87 0 1207470496 71948384 8848912
07:04:28 129417 8192000000 10072994608 10539810816 1027776800 11.147 91 19 32 0 1070208864 39887568 2544496
07:07:37 97659 8192000000 10056008688 10508595200 1109548928 11.928 99 26 52 0 1143561760 28783248 5229584
07:10:21 10513 8192000000 10081097632 10510172160 1119843296 12.026 100 61 121 0 1182770992 50603312 12324384
07:11:37 57053 8192000000 10028468800 10481262592 1102795424 11.864 99 18 37 1 1159871024 53789248 3286352
07:13:35 67306 8192000000 10046552992 10503958528 1114458528 11.975 100 12 24 0 1151196720 34150512 2587680
07:15:43 84297 8192000000 10084638240 10520543232 1094894160 11.789 98 20 40 0 1148982288 49657568 4430560
07:18:12 14139 8192000000 10086687776 10522595328 1130933616 12.130 99 39 76 0 1184676224 45916208 7826400
07:19:27 30397 8192000000 10147429424 10584457216 1132352080 12.144 99 36 72 0 1231793008 91655312 7785616
07:20:59 18380 8192000000 10188419648 10625216512 1138144096 12.198 99 40 80 1 1267430208 120710416 8575696
07:22:18 22360 8192000000 10225358112 10694168576 1149498496 12.305 99 45 88 1 1340080432 181129392 9452544
07:23:44 24684 8192000000 10257385888 10695671808 1140314144 12.218 98 31 58 0 1327487888 180285488 6888256
07:25:12 74164 8192000000 10217030960 10672435200 1115725392 11.987 97 38 76 0 1311624096 187680304 8218400
07:27:27 53842 8192000000 10137868736 10574114816 1093576768 11.777 96 35 67 4 1152745872 51953952 7215152
07:29:21 23967 8192000000 10083867072 10509430784 1118266752 12.011 99 45 88 0 1161927952 34604096 9057104
07:30:50 119611 8192000000 10072045904 10547040256 1138327808 12.200 100 27 54 1 1214295632 70297296 5670528
07:33:50 59988 8192000000 10062589264 10515050496 1103204672 11.868 97 13 26 0 1163418416 57569168 2644576
07:35:55 80489 8192000000 10106131872 10578579456 1129937024 12.121 99 10 20 0 1205258176 72968096 2353056
07:38:51 49469 8192000000 10066003024 10547744768 1123425056 12.059 99 18 33 0 1166489360 39667056 3397248
07:40:45 35011 8192000000 10106027200 10573828096 1136041120 12.178 99 34 65 0 1180180960 37730720 6409120
07:42:20 64693 8192000000 10118006960 10584702976 1140744896 12.223 99 23 46 1 1204468624 58801568 4922160
07:44:33 87686 8192000000 10090701056 10562383872 1106701440 11.901 97 23 46 0 1206025872 94303232 5021200

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 26-07-2022 07:48:18 to 26-07-2022 07:48:34.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 108 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 26-07-2022 07:48:35 to 26-07-2022 07:48:37.

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          219178
MON$OLDEST_ACTIVE               222857
MON$OLDEST_SNAPSHOT             222857
MON$NEXT_TRANSACTION            222857
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                       2080032
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             777
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2811 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2811 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2811 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2811 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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Tue Jul 26 07:47:19 2022
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Tue Jul 26 07:47:20 2022
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:13: 
:14: 
:15: mscs-test-161.budzdorov.ru    Tue Jul 26 07:47:22 2022
:16:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:17: 
--- end of diff output ---

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

26.07.22 07:48:38. 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-07-26 07:48:40 No severe PSQL-related problems occured
26.07.22 07:48:41. End of report.