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

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-10-03 13:37:19
DTS_END                         2022-10-03 13:37:19.6290
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/53528
TRN_ID                          221621
ATT_ID                          624
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: 8910.64 23804 1069277
customer order: creation 173.71 469 20845
customer order: refuse 15.47 391 1856
order to supplier: creation 46.33 1323 5559
order to supplier: removal 3.17 148421 380
invoice (draft): creation 45.54 3108 5465
invoice (draft): removal 48.16 887 5779
invoice accept: apply 42.02 26158 5042
invoice accept: cancel 43.35 1122 5202
customer reserve: creation 3891.99 251 467039
customer reserve: removal 4307.98 85 516958
realization accept: apply 84.31 265 10117
realization accept: cancel 78.00 156 9360
payment from customer: creation 59.81 174 7177
payment from customer: removal 5.72 124 686
payment to supplier: creation 49.64 215 5957
payment to supplier: removal 3.27 116 392
service: total inventory turnovers 4.21 3573 505
service: total monetary turnovers 6.98 368 838
service: aggregate perf. data 0.97 14658 116
service: refresh index statistics 0.03 274225 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 03-10-2022 13:40:33 to 03-10-2022 13:40:35.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6099
TEST_TIME 2 6594
TEST_TIME 3 6095
TEST_TIME 4 5944
TEST_TIME 5 6305
TEST_TIME 6 5812
TEST_TIME 7 6180
TEST_TIME 8 6397
TEST_TIME 9 6275
TEST_TIME 10 6272
TEST_TIME 11 6336
TEST_TIME 12 6365
TEST_TIME 13 6359
TEST_TIME 14 6558
TEST_TIME 15 6706
TEST_TIME 16 6826
TEST_TIME 17 6995
TEST_TIME 18 7031
TEST_TIME 19 7270
TEST_TIME 20 7671
TEST_TIME 21 7787
TEST_TIME 22 7877
TEST_TIME 23 8061
TEST_TIME 24 8855
TEST_TIME 25 9206
TEST_TIME 26 9347
TEST_TIME 27 9853
TEST_TIME 28 10426
TEST_TIME 29 10476
TEST_TIME 30 10255
TEST_TIME 31 10242
TEST_TIME 32 10283
TEST_TIME 33 10220
TEST_TIME 34 10117
TEST_TIME 35 10065
TEST_TIME 36 9986
TEST_TIME 37 9916
TEST_TIME 38 9775
TEST_TIME 39 9779
TEST_TIME 40 9725
TEST_TIME 41 9959
TEST_TIME 42 9823
TEST_TIME 43 9867
TEST_TIME 44 9869
TEST_TIME 45 10011
TEST_TIME 46 9943
TEST_TIME 47 9897
TEST_TIME 48 9694
TEST_TIME 49 9549
TEST_TIME 50 9600
TEST_TIME 51 9448
TEST_TIME 52 9447
TEST_TIME 53 9465
TEST_TIME 54 9407
TEST_TIME 55 9447
TEST_TIME 56 9409
TEST_TIME 57 9337
TEST_TIME 58 9308
TEST_TIME 59 9228
TEST_TIME 60 9191
TEST_TIME 61 9263
TEST_TIME 62 9236
TEST_TIME 63 9194
TEST_TIME 64 9233
TEST_TIME 65 9260
TEST_TIME 66 9267
TEST_TIME 67 9361
TEST_TIME 68 9424
TEST_TIME 69 9348
TEST_TIME 70 9402
TEST_TIME 71 9423
TEST_TIME 72 9434
TEST_TIME 73 9516
TEST_TIME 74 9518
TEST_TIME 75 9426
TEST_TIME 76 9338
TEST_TIME 77 9311
TEST_TIME 78 9276
TEST_TIME 79 9287
TEST_TIME 80 9284
TEST_TIME 81 9258
TEST_TIME 82 9208
TEST_TIME 83 9177
TEST_TIME 84 9105
TEST_TIME 85 9068
TEST_TIME 86 9103
TEST_TIME 87 9125
TEST_TIME 88 9145
TEST_TIME 89 9149
TEST_TIME 90 9122
TEST_TIME 91 9089
TEST_TIME 92 9101
TEST_TIME 93 9102
TEST_TIME 94 9197
TEST_TIME 95 9272
TEST_TIME 96 9261
TEST_TIME 97 9274
TEST_TIME 98 9309
TEST_TIME 99 9297
TEST_TIME 100 9270
TEST_TIME 101 9210
TEST_TIME 102 9202
TEST_TIME 103 9154
TEST_TIME 104 9114
TEST_TIME 105 9065
TEST_TIME 106 9051
TEST_TIME 107 9089
TEST_TIME 108 9062
TEST_TIME 109 9041
TEST_TIME 110 9033
TEST_TIME 111 9013
TEST_TIME 112 9014
TEST_TIME 113 9022
TEST_TIME 114 9034
TEST_TIME 115 9090
TEST_TIME 116 9074
TEST_TIME 117 9096
TEST_TIME 118 9078
TEST_TIME 119 9011
TEST_TIME 120 8939

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 03-10-2022 13:40:36 to 03-10-2022 13:40:40.

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 1498870 1498870 0 0.00 0 25253 13 0 0
doc_list_biud 1498855 1498855 0 0.00 0 30472 35 0 0
sp_add_invoice_to_stock 5133 5042 91 1.77 46 234315 26158 0 91
sp_cancel_adding_invoice 5202 5202 0 0.00 6 68964 1122 0 0
sp_cancel_client_order 1856 1856 0 0.00 5 18356 392 0 0
sp_cancel_customer_reserve 516958 516958 0 0.00 1 30488 85 0 0
sp_cancel_pay_from_customer 686 686 0 0.00 4 11818 125 0 0
sp_cancel_pay_to_supplier 392 392 0 0.00 4 8081 116 0 0
sp_cancel_supplier_invoice 5783 5779 4 0.06 3 25659 887 0 4
sp_cancel_supplier_order 380 380 0 0.00 11 1331573 148421 0 0
sp_cancel_write_off 9360 9360 0 0.00 2 16995 157 0 0
sp_client_order 20845 20845 0 0.00 6 24482 470 0 0
sp_customer_reserve 467039 467039 0 0.00 1 151396 251 0 0
sp_fill_shopping_cart 31857 31857 0 0.00 1 10679 83 0 0
sp_fill_shopping_cart_clo_res 466996 466996 0 0.00 0 17746 19 0 0
sp_get_clo_for_invoice 4991 4991 0 0.00 0 35477 279 0 0
sp_kill_cost_storno 15640 15640 0 0.00 0 21132 69 0 0
sp_kill_qstorno_ret_qs2qd 1049435 1049435 0 0.00 0 30444 38 0 0
sp_kill_qty_storno 554643 554643 0 0.00 0 30470 87 0 0
sp_lock_selected_doc 20915 20915 0 0.00 0 18055 8 0 0
sp_make_cost_storno 28242 28242 0 0.00 0 10640 77 0 0
sp_multiply_rows_for_pdistr 28242 28242 0 0.00 0 25227 42 0 0
sp_multiply_rows_for_qdistr 20845 20845 0 0.00 0 12231 168 0 0
sp_pay_from_customer 7177 7177 0 0.00 5 11455 175 0 0
sp_pay_to_supplier 5957 5957 0 0.00 6 25257 215 0 0
sp_payment_common 13134 13134 0 0.00 2 8191 47 0 0
sp_qd_handle_on_cancel_clo 1856 1856 0 0.00 0 9891 312 0 0
sp_qd_handle_on_invoice_upd_sts 10193 10193 0 0.00 0 17127 463 0 0
sp_qd_handle_on_reserve_upd_sts 10117 10117 0 0.00 0 10536 55 0 0
sp_reserve_write_off 10117 10117 0 0.00 8 14430 265 0 0
sp_supplier_invoice 5465 5465 0 0.00 7 89792 3108 0 0
sp_supplier_order 5559 5559 0 0.00 9 43172 1324 0 0
srv_aggregate_perf_data 116 116 0 0.00 2361 195421 14659 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 805 196979 40729 0 0
srv_find_qd_qs_mism 1019661 1019661 0 0.00 0 23381 10 0 0
srv_make_invnt_saldo 505 505 0 0.00 79 81978 3574 0 0
srv_make_money_saldo 838 838 0 0.00 1 20547 368 0 0
srv_recalc_idx_stat 4 4 0 0.00 104824 404274 274226 0 0
t$perf-abend:sp_add_invoice_to_stock 91 91 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 4 4 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5049 5049 0 0.00 1 9248 855 0 0
t$perf-norm:sp_cancel_adding_invoice 392 392 0 0.00 1 9377 485 0 0
t$perf-norm:sp_cancel_client_order 1856 1856 0 0.00 0 1955 27 0 0
t$perf-norm:sp_cancel_customer_reserve 674 674 0 0.00 0 2313 29 0 0
t$perf-norm:sp_cancel_pay_from_customer 686 686 0 0.00 0 383 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 392 392 0 0.00 0 1173 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 374 374 0 0.00 0 1754 32 0 0
t$perf-norm:sp_cancel_supplier_order 380 380 0 0.00 0 99483 6545 0 0
t$perf-norm:sp_cancel_write_off 1056 1056 0 0.00 0 3211 23 0 0
t$perf-norm:sp_client_order 20846 20846 0 0.00 0 8372 35 0 0
t$perf-norm:sp_customer_reserve 921 921 0 0.00 0 531 12 0 0
t$perf-norm:sp_pay_from_customer 7177 7177 0 0.00 0 6601 20 0 0
t$perf-norm:sp_pay_to_supplier 5957 5957 0 0.00 0 6045 19 0 0
t$perf-norm:sp_reserve_write_off 10117 10117 0 0.00 0 4979 23 0 0
t$perf-norm:sp_supplier_invoice 5465 5465 0 0.00 0 5828 31 0 0
t$perf-norm:sp_supplier_order 5559 5559 0 0.00 0 5981 31 0 0
t$perf-norm:srv_aggregate_perf_data 116 116 0 0.00 0 613 19 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 364 10 0 0
t$perf-norm:srv_make_invnt_saldo 505 505 0 0.00 0 1086 14 0 0
t$perf-norm:srv_make_money_saldo 838 838 0 0.00 0 416 4 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 4 308 105 0 0
v_add_invoice_to_stock 10158 9976 182 1.79 0 11456 29 0 182
v_all_customers 38409 38409 0 0.00 0 101 4 0 0
v_all_suppliers 30740 30740 0 0.00 0 99 4 0 0
v_all_wares 41684 41684 0 0.00 0 113 4 0 0
v_cancel_adding_invoice 784 784 0 0.00 0 5608 22 0 0
v_cancel_client_order 3712 3712 0 0.00 0 8756 12 0 0
v_cancel_customer_prepayment 1372 1372 0 0.00 0 11651 22 0 0
v_cancel_customer_reserve 1348 1348 0 0.00 1 938 10 0 0
v_cancel_payment_to_supplier 784 784 0 0.00 0 384 10 0 0
v_cancel_supplier_invoice 756 748 8 1.05 0 2540 27 0 8
v_cancel_supplier_order 760 760 0 0.00 0 2932 17 0 0
v_cancel_write_off 2112 2112 0 0.00 0 2763 22 0 0
v_max_id_clo_ord 5557 5557 0 0.00 0 427 5 0 0
v_max_id_clo_res 921 921 0 0.00 0 2419 13 0 0
v_max_id_ord_sup 5455 5455 0 0.00 0 3556 7 0 0
v_max_non_paid_invoice 1599 1599 0 0.00 0 211 7 0 0
v_max_non_paid_realizn 3631 3631 0 0.00 0 745 7 0 0
v_min_id_clo_ord 5557 5557 0 0.00 0 3051 9 0 0
v_min_id_clo_res 921 921 0 0.00 0 2823 11 0 0
v_min_id_ord_sup 5455 5455 0 0.00 0 5410 11 0 0
v_min_non_paid_invoice 5957 5957 0 0.00 0 8184 17 0 0
v_min_non_paid_realizn 7176 7176 0 0.00 0 7663 23 0 0
v_our_firm 10366 10366 0 0.00 0 57 5 0 0
v_reserve_write_off 20234 20234 0 0.00 0 3938 11 0 0
x_lock_depdocs_on_canc_invoice 10607 10607 0 0.00 0 13915 122 0 0
x_lock_depdocs_on_canc_sup_ord 380 380 0 0.00 0 15583 379 0 0
x_make_qstorno_1000_1200 5559 5559 0 0.00 2 43152 1164 0 0
x_make_qstorno_1200_2000 5465 5465 0 0.00 3 89781 2873 0 0
x_make_qstorno_2100_3300 467037 467037 0 0.00 0 151395 220 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 03-10-2022 13:40:41 to 03-10-2022 13:40:45.

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 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
11:08:23 33684 8192000000 9730438192 10030039040 805553248 8.953 100 87 174 0 834584400 7966624 21064528
11:10:05 19034 8192000000 9778088192 10088132608 849186624 9.392 100 86 172 0 881721776 12594176 19940976
11:11:36 20650 8192000000 9807020784 10131505152 878414336 9.684 100 84 170 1 919326096 22046528 18865232
11:13:02 8902 8192000000 9888170400 10222014464 920563904 10.102 100 84 163 0 997772800 59821968 17386928
11:14:16 6759 8192000000 9908896848 10255056896 957585024 10.465 100 80 157 0 1024348240 49969968 16793248
11:15:28 2151 8192000000 9958927312 10311364608 995290000 10.833 100 91 174 1 1079645472 65997472 18358000
11:16:33 1417 8192000000 9988205424 10352795648 1021521968 11.087 100 79 155 1 1108010512 71085744 15402800
11:17:37 779 8192000000 10014784960 10385416192 1040316144 11.268 100 95 182 0 1138303504 78976320 19011040
11:18:40 954 8192000000 10034715536 10412077056 1053629056 11.395 100 97 188 1 1158384320 85557392 19197872
11:19:43 1036 8192000000 10042487952 10417471488 1060376800 11.460 100 97 190 1 1163779248 84389856 19012592
11:20:46 2339 8192000000 10033600144 10411974656 1067027264 11.524 100 85 157 1 1151640112 67516432 17096416
11:21:50 1049 8192000000 10055320448 10439712768 1078832096 11.636 100 92 182 1 1181338208 84098576 18407536
11:22:52 1781 8192000000 10047009952 10434334720 1081030960 11.657 100 78 151 1 1165549648 69115936 15402752
11:23:55 1397 8192000000 10058979904 10445090816 1092707648 11.768 100 91 170 2 1179461568 70297632 16456288
11:24:58 1096 8192000000 10074204352 10460209152 1094732736 11.788 100 94 177 2 1194741840 82279872 17729232
11:26:01 1164 8192000000 10042815824 10430959616 1092636864 11.768 100 95 179 3 1163953952 52205872 19111216
11:27:03 988 8192000000 10033028224 10418614272 1079985808 11.647 99 89 166 2 1160821520 62593216 18242496
11:28:05 1209 8192000000 10027387760 10415370240 1087331216 11.717 100 89 168 2 1153907936 47689712 18887008
11:29:08 1033 8192000000 10051109536 10440314880 1088515456 11.729 100 96 179 1 1171393232 64688784 18188992
11:30:10 1188 8192000000 10022242192 10406068224 1080375312 11.651 99 90 169 1 1145862544 47493088 17994144
11:31:11 1085 8192000000 10050668288 10430988288 1075943040 11.609 100 94 180 0 1175316528 80366848 19006640
11:32:13 1172 8192000000 10047022992 10423599104 1070740848 11.559 100 98 190 0 1166321056 75601968 19978240
11:33:15 1041 8192000000 10010687040 10385793024 1053218464 11.392 99 87 166 1 1134596352 63749552 17628336
11:34:18 7256 8192000000 9961780336 10337509376 1035429696 11.221 100 65 130 0 1093830048 44755728 13644624
11:35:26 7169 8192000000 9995538048 10378051584 1036183152 11.228 99 68 131 1 1114849264 64885968 13780144
11:36:34 1837 8192000000 9980419808 10359640064 1041438080 11.278 99 74 143 2 1100278304 44321344 14518880
11:37:39 44769 8192000000 10007040800 10389405696 1063933456 11.494 100 70 133 0 1122870400 46323856 12613088
11:39:35 1989 8192000000 10019698880 10401075200 1061735648 11.473 99 74 141 0 1145232128 69032208 14464272
11:40:43 19094 8192000000 10058545568 10451943424 1092145200 11.763 100 63 119 1 1173679360 69369392 12164768
11:42:07 1581 8192000000 10093796848 10493931520 1105999680 11.895 100 85 159 2 1211461248 87996320 17465248
11:43:14 1694 8192000000 10113162832 10503335936 1119385040 12.021 100 85 157 2 1226251184 90628848 16237296
11:44:21 973 8192000000 10184679376 10582650880 1140645760 12.222 100 93 177 1 1305280816 144935712 19699344
11:45:24 2294 8192000000 10200636288 10599026688 1151879632 12.327 100 86 161 1 1318610704 149440848 17290224
11:46:34 1575 8192000000 10248778512 10645352448 1164759280 12.448 100 90 165 2 1352119504 170544096 16816128
11:47:39 1954 8192000000 10288866240 10687352832 1172177296 12.517 100 87 170 0 1395562528 204125168 19260064
11:48:44 1492 8192000000 10337755920 10741960704 1177825472 12.570 100 85 164 0 1448561312 252556464 18179376
11:49:48 2687 8192000000 10355987536 10765365248 1180039360 12.591 100 81 159 0 1469227264 270706720 18481184
11:50:59 1173 8192000000 10378664256 10792181760 1188803504 12.672 100 94 186 0 1491278576 279986672 22488400
11:52:04 791 8192000000 10411976624 10825596928 1190013472 12.683 100 99 194 2 1520171744 309131664 21026608
11:53:09 1454 8192000000 10465738832 10872004608 1200698192 12.783 100 91 182 1 1574196464 353231632 20266640
11:54:13 1520 8192000000 10494493040 10908073984 1195942880 12.739 100 97 192 1 1597147232 379020592 22183760
11:55:17 818 8192000000 10515290976 10925563904 1196634096 12.745 100 95 190 1 1621512080 401484496 23393488
11:56:19 1674 8192000000 10509557696 10966421504 1191105088 12.694 100 79 154 0 1614439824 404594448 18740288
11:57:27 987 8192000000 10496575968 10953596928 1180432832 12.594 100 89 174 1 1604779472 403456640 20890000
11:58:31 1538 8192000000 10490739008 10938593280 1178114864 12.573 100 73 142 2 1599043904 403517856 17411184
11:59:36 893 8192000000 10502888816 10938671104 1164756928 12.448 100 95 182 1 1609570048 422824576 21988544
12:00:42 1675 8192000000 10474924032 10962624512 1165002896 12.450 100 67 129 0 1583321024 402036464 16281664
12:01:45 20523 8192000000 10416846512 10902167552 1162887744 12.430 100 31 62 0 1539709552 368417184 8404624
12:03:08 35543 8192000000 10305298896 10774827008 1131584640 12.136 98 19 38 0 1438574272 301869600 5120032
12:04:46 19689 8192000000 10119160480 10653810688 1124705440 12.071 99 31 54 1 1274463696 143513776 6244480
12:06:08 3861 8192000000 10110269968 10534608896 1136707280 12.185 100 49 94 1 1224262256 76901872 10653104
12:07:14 27497 8192000000 10124969248 10563026944 1134301824 12.162 100 39 77 0 1219831680 76788480 8741376
12:08:46 56645 8192000000 10103316368 10570358784 1104613728 11.881 97 13 26 0 1196572048 89317136 2641184
12:10:43 63184 8192000000 10089566768 10551128064 1099112432 11.829 97 11 22 0 1133406960 32395488 1899040
12:12:47 93136 8192000000 10129535408 10553536512 1089146176 11.735 96 25 49 1 1198197760 102922928 6128656
12:15:24 77092 8192000000 10116306784 10540367872 1106040544 11.895 98 29 54 0 1229027488 117424528 5562416
12:17:44 30905 8192000000 10139868144 10600939520 1134031792 12.159 100 43 84 0 1235310752 92860864 8418096
12:19:25 40341 8192000000 10096634592 10524942336 1120324528 12.030 99 44 88 0 1160665200 30993712 9346960
12:21:06 70053 8192000000 10090608368 10599784448 1114350480 11.974 98 13 26 1 1194452624 77621024 2481120
12:23:16 196977 8192000000 10098739600 10551939072 1135536416 12.174 99 31 60 1 1188148992 46154768 6457808
12:27:34 75482 8192000000 10095911664 10548371456 1131889216 12.139 100 32 65 0 1188137312 49338384 6909712
12:29:53 26757 8192000000 10110745504 10543935488 1123893264 12.064 98 44 88 0 1192338880 59132608 9313008
12:31:28 23109 8192000000 10097922080 10540851200 1122381488 12.049 99 42 84 0 1188376032 56482432 9512112
12:32:54 146760 8192000000 10066352176 10535936000 1101658928 11.853 97 22 44 0 1197835312 91443632 4732752
12:36:25 67180 8192000000 10110451136 10572906496 1126859536 12.092 99 22 44 0 1197435552 65769824 4806192
12:38:59 60648 8192000000 10096304608 10544533504 1115118384 11.981 97 17 31 0 1185361328 66860448 3382496
12:41:05 86609 8192000000 10117960320 10560905216 1084788512 11.693 95 21 42 0 1168155408 78822272 4544624
12:43:40 46223 8192000000 10160283328 10611392512 1142889424 12.243 100 37 72 0 1263584992 112356224 8339344
12:45:37 46525 8192000000 10109321536 10539569152 1137362480 12.191 99 37 74 0 1184153824 39351360 7439984
12:47:29 29524 8192000000 10164865024 10638954496 1153497232 12.342 100 35 70 0 1291994064 130989904 7506928
12:48:59 36559 8192000000 10149031424 10613772288 1136925312 12.187 99 24 47 0 1209761264 67387648 5448304
12:50:39 34511 8192000000 10090687456 10525339648 1124397152 12.069 99 34 57 2 1186461808 54883312 7181344
12:52:14 88514 8192000000 10107477200 10543349760 1138832144 12.205 100 48 96 1 1200507904 51812128 9863632
12:54:44 9394 8192000000 10105408384 10548822016 1143908448 12.252 100 39 76 0 1202429488 50926208 7594832
12:55:55 35177 8192000000 10117176448 10576101376 1126240752 12.086 99 28 56 0 1201663440 69888784 5533904
12:57:32 57413 8192000000 10058522384 10539290624 1103006880 11.866 97 21 42 1 1148058480 40185456 4866144
12:59:34 61736 8192000000 10076790176 10525990912 1104562416 11.881 97 21 41 0 1151067312 41270064 5234832
13:01:37 77531 8192000000 10106928352 10552066048 1099519680 11.833 97 17 34 0 1188488240 85208352 3760208
13:03:56 57346 8192000000 10052105728 10485510144 1084108544 11.687 96 15 29 0 1166139936 78828608 3202784
13:06:01 32879 8192000000 10058842976 10495074304 1095121680 11.791 98 22 44 0 1134353744 34511872 4720192
13:07:40 27881 8192000000 10099854576 10535174144 1132018480 12.140 100 51 100 1 1198473568 56157936 10297152
13:09:46 52673 8192000000 10182216544 10653556736 1151068096 12.320 100 27 52 0 1303890848 147075024 5747728
13:11:41 74258 8192000000 10065874144 10494803968 1113206192 11.963 98 16 32 0 1167197744 50757040 3234512
13:13:57 61778 8192000000 10111859312 10557353984 1117826416 12.006 99 40 80 1 1192282944 66453136 8003392
13:16:05 32859 8192000000 10103279200 10540290048 1124641440 12.071 99 45 85 0 1212584544 79796288 8146816
13:18:10 64555 8192000000 10097429984 10572021760 1139602240 12.212 100 21 43 0 1207138160 63051664 4484256
13:20:23 118481 8192000000 10090513040 10561392640 1105483488 11.890 97 13 26 0 1175051104 66872112 2695504
13:23:22 68044 8192000000 10075293632 10570412032 1128660928 12.109 100 21 42 1 1195915264 62482848 4771488
13:25:37 65589 8192000000 10083410832 10537365504 1139303856 12.209 100 19 39 0 1188497056 45426592 3766608
13:27:45 50379 8192000000 10081811696 10521759744 1068383424 11.537 94 24 49 0 1131756048 58182768 5189856
13:29:36 26730 8192000000 10125656448 10560577536 1120456256 12.031 99 32 64 0 1219148336 92076688 6615392
13:31:03 88288 8192000000 10051373360 10561286144 1065556368 11.510 95 20 40 1 1170218080 100523296 4138416
13:33:31 20501 8192000000 10070679424 10502111232 1098627232 11.825 98 44 86 0 1137927664 30346896 8953536
13:34:54 22530 8192000000 10090231248 10531909632 1107131952 11.905 99 46 88 3 1169457888 53813472 8512464
13:36:17 107537 8192000000 9096505184 9379766272 246057024 2.916 22 9 18 0 300024256 52168736 1798496

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 03-10-2022 13:40:47 to 03-10-2022 13:41:03.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 91 v_add_invoice_to_stock 335544517
except 4 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 03-10-2022 13:41:04 to 03-10-2022 13:41:06.

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          222031
MON$OLDEST_ACTIVE               222032
MON$OLDEST_SNAPSHOT             222032
MON$NEXT_TRANSACTION            222032
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                       2035424
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             781
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2854 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2854 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2854 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2854 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
--- end of diff output ---

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

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