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

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2023-01-23 10:37:25
DTS_END                         2023-01-23 10:37:25.9390
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/52596
TRN_ID                          215815
ATT_ID                          582
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: 9125.03 26795 1095004
customer order: creation 166.33 515 19960
customer order: refuse 14.55 397 1746
order to supplier: creation 43.99 1462 5279
order to supplier: removal 3.44 177923 413
invoice (draft): creation 43.28 3283 5193
invoice (draft): removal 51.58 1063 6190
invoice accept: apply 41.51 27269 4981
invoice accept: cancel 46.88 1240 5625
customer reserve: creation 3852.38 259 462286
customer reserve: removal 4571.90 104 548628
realization accept: apply 81.22 316 9746
realization accept: cancel 81.69 178 9803
payment from customer: creation 57.13 192 6856
payment from customer: removal 5.67 138 680
payment to supplier: creation 47.58 227 5710
payment to supplier: removal 3.33 127 400
service: total inventory turnovers 3.72 4431 446
service: total monetary turnovers 7.81 342 937
service: aggregate perf. data 1.00 14577 120
service: refresh index statistics 0.04 301859 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 23-01-2023 10:39:18 to 23-01-2023 10:39:20.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8940
TEST_TIME 2 6252
TEST_TIME 3 6081
TEST_TIME 4 6799
TEST_TIME 5 7150
TEST_TIME 6 7452
TEST_TIME 7 7617
TEST_TIME 8 7509
TEST_TIME 9 7341
TEST_TIME 10 7341
TEST_TIME 11 7646
TEST_TIME 12 7668
TEST_TIME 13 7951
TEST_TIME 14 7892
TEST_TIME 15 8080
TEST_TIME 16 8175
TEST_TIME 17 8019
TEST_TIME 18 8005
TEST_TIME 19 8353
TEST_TIME 20 8220
TEST_TIME 21 8408
TEST_TIME 22 8411
TEST_TIME 23 8382
TEST_TIME 24 8509
TEST_TIME 25 8419
TEST_TIME 26 8516
TEST_TIME 27 8554
TEST_TIME 28 8572
TEST_TIME 29 8649
TEST_TIME 30 8790
TEST_TIME 31 8674
TEST_TIME 32 9143
TEST_TIME 33 9604
TEST_TIME 34 9968
TEST_TIME 35 10412
TEST_TIME 36 10366
TEST_TIME 37 11035
TEST_TIME 38 11011
TEST_TIME 39 10943
TEST_TIME 40 10953
TEST_TIME 41 10915
TEST_TIME 42 10797
TEST_TIME 43 10698
TEST_TIME 44 10613
TEST_TIME 45 10496
TEST_TIME 46 10347
TEST_TIME 47 10307
TEST_TIME 48 10210
TEST_TIME 49 10148
TEST_TIME 50 10084
TEST_TIME 51 10024
TEST_TIME 52 10034
TEST_TIME 53 9987
TEST_TIME 54 10038
TEST_TIME 55 9918
TEST_TIME 56 9803
TEST_TIME 57 9913
TEST_TIME 58 9840
TEST_TIME 59 9743
TEST_TIME 60 9808
TEST_TIME 61 9966
TEST_TIME 62 10055
TEST_TIME 63 10034
TEST_TIME 64 10091
TEST_TIME 65 10033
TEST_TIME 66 10071
TEST_TIME 67 10062
TEST_TIME 68 10070
TEST_TIME 69 10146
TEST_TIME 70 10012
TEST_TIME 71 9909
TEST_TIME 72 9847
TEST_TIME 73 9791
TEST_TIME 74 9747
TEST_TIME 75 9683
TEST_TIME 76 9668
TEST_TIME 77 9632
TEST_TIME 78 9574
TEST_TIME 79 9505
TEST_TIME 80 9508
TEST_TIME 81 9453
TEST_TIME 82 9365
TEST_TIME 83 9444
TEST_TIME 84 9385
TEST_TIME 85 9337
TEST_TIME 86 9332
TEST_TIME 87 9347
TEST_TIME 88 9407
TEST_TIME 89 9447
TEST_TIME 90 9487
TEST_TIME 91 9481
TEST_TIME 92 9477
TEST_TIME 93 9470
TEST_TIME 94 9462
TEST_TIME 95 9424
TEST_TIME 96 9377
TEST_TIME 97 9372
TEST_TIME 98 9358
TEST_TIME 99 9294
TEST_TIME 100 9250
TEST_TIME 101 9195
TEST_TIME 102 9193
TEST_TIME 103 9248
TEST_TIME 104 9230
TEST_TIME 105 9224
TEST_TIME 106 9211
TEST_TIME 107 9233
TEST_TIME 108 9228
TEST_TIME 109 9229
TEST_TIME 110 9281
TEST_TIME 111 9364
TEST_TIME 112 9346
TEST_TIME 113 9416
TEST_TIME 114 9369
TEST_TIME 115 9370
TEST_TIME 116 9318
TEST_TIME 117 9280
TEST_TIME 118 9249
TEST_TIME 119 9213
TEST_TIME 120 9153

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 23-01-2023 10:39:21 to 23-01-2023 10:39:25.

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 1521071 1521071 0 0.00 0 16573 14 0 0
doc_list_biud 1521064 1521064 0 0.00 0 26011 44 0 0
sp_add_invoice_to_stock 5092 4981 111 2.17 43 201761 27270 0 111
sp_cancel_adding_invoice 5625 5625 0 0.00 6 95624 1241 0 0
sp_cancel_client_order 1746 1746 0 0.00 6 8425 397 0 0
sp_cancel_customer_reserve 548628 548628 0 0.00 1 23676 105 0 0
sp_cancel_pay_from_customer 680 680 0 0.00 4 8592 138 0 0
sp_cancel_pay_to_supplier 400 400 0 0.00 3 1654 128 0 0
sp_cancel_supplier_invoice 6206 6190 16 0.25 2 26016 1064 0 16
sp_cancel_supplier_order 413 413 0 0.00 29 1339194 177923 0 0
sp_cancel_write_off 9803 9803 0 0.00 2 10830 179 0 0
sp_client_order 19960 19960 0 0.00 6 19737 516 0 0
sp_customer_reserve 462286 462286 0 0.00 1 80571 259 0 0
sp_fill_shopping_cart 30428 30428 0 0.00 1 15426 86 0 0
sp_fill_shopping_cart_clo_res 462261 462261 0 0.00 0 22575 20 0 0
sp_get_clo_for_invoice 4937 4937 0 0.00 0 27560 308 0 0
sp_kill_cost_storno 16508 16508 0 0.00 0 6948 79 0 0
sp_kill_qstorno_ret_qs2qd 1113662 1113662 0 0.00 0 26010 46 0 0
sp_kill_qty_storno 587086 587086 0 0.00 0 26011 105 0 0
sp_lock_selected_doc 20293 20293 0 0.00 0 7100 7 0 0
sp_make_cost_storno 27247 27247 0 0.00 0 11736 86 0 0
sp_multiply_rows_for_pdistr 27247 27247 0 0.00 0 14148 46 0 0
sp_multiply_rows_for_qdistr 19960 19960 0 0.00 0 19554 206 0 0
sp_pay_from_customer 6856 6856 0 0.00 5 11098 193 0 0
sp_pay_to_supplier 5710 5710 0 0.00 6 14187 227 0 0
sp_payment_common 12566 12566 0 0.00 2 5248 49 0 0
sp_qd_handle_on_cancel_clo 1746 1746 0 0.00 0 8415 313 0 0
sp_qd_handle_on_invoice_upd_sts 10560 10560 0 0.00 0 23905 548 0 0
sp_qd_handle_on_reserve_upd_sts 9746 9746 0 0.00 0 17910 74 0 0
sp_reserve_write_off 9746 9746 0 0.00 8 17938 317 0 0
sp_supplier_invoice 5193 5193 0 0.00 10 98706 3284 0 0
sp_supplier_order 5279 5279 0 0.00 7 54292 1462 0 0
srv_aggregate_perf_data 120 120 0 0.00 2491 135187 14578 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 864 116749 35084 0 0
srv_find_qd_qs_mism 1047264 1047264 0 0.00 0 16235 10 0 0
srv_make_invnt_saldo 446 446 0 0.00 80 67450 4432 0 0
srv_make_money_saldo 937 937 0 0.00 1 9393 342 0 0
srv_recalc_idx_stat 5 5 0 0.00 32187 428409 301859 0 0
t$perf-abend:sp_add_invoice_to_stock 111 111 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 16 16 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4984 4984 0 0.00 0 21220 994 0 0
t$perf-norm:sp_cancel_adding_invoice 384 384 0 0.00 1 8818 482 0 0
t$perf-norm:sp_cancel_client_order 1746 1746 0 0.00 0 1167 30 0 0
t$perf-norm:sp_cancel_customer_reserve 642 642 0 0.00 0 3026 35 0 0
t$perf-norm:sp_cancel_pay_from_customer 680 680 0 0.00 0 963 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 400 400 0 0.00 0 951 16 0 0
t$perf-norm:sp_cancel_supplier_invoice 360 360 0 0.00 0 1239 39 0 0
t$perf-norm:sp_cancel_supplier_order 413 413 0 0.00 0 92397 7793 0 0
t$perf-norm:sp_cancel_write_off 988 988 0 0.00 0 5234 25 0 0
t$perf-norm:sp_client_order 19960 19960 0 0.00 0 6817 40 0 0
t$perf-norm:sp_customer_reserve 990 990 0 0.00 0 1041 17 0 0
t$perf-norm:sp_pay_from_customer 6856 6856 0 0.00 0 3389 20 0 0
t$perf-norm:sp_pay_to_supplier 5710 5710 0 0.00 0 2598 17 0 0
t$perf-norm:sp_reserve_write_off 9746 9746 0 0.00 0 6647 25 0 0
t$perf-norm:sp_supplier_invoice 5193 5193 0 0.00 0 3265 32 0 0
t$perf-norm:sp_supplier_order 5279 5279 0 0.00 0 6845 36 0 0
t$perf-norm:srv_aggregate_perf_data 120 120 0 0.00 0 1188 22 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 36 5 0 0
t$perf-norm:srv_make_invnt_saldo 446 446 0 0.00 0 1281 18 0 0
t$perf-norm:srv_make_money_saldo 937 937 0 0.00 0 4235 14 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 4 184 42 0 0
v_add_invoice_to_stock 10090 9868 222 2.20 0 10962 32 0 222
v_all_customers 36362 36362 0 0.00 0 166 4 0 0
v_all_suppliers 29385 29385 0 0.00 0 69 5 0 0
v_all_wares 39920 39920 0 0.00 0 111 4 0 0
v_cancel_adding_invoice 768 768 0 0.00 0 1498 18 0 0
v_cancel_client_order 3492 3492 0 0.00 0 3296 13 0 0
v_cancel_customer_prepayment 1360 1360 0 0.00 0 8511 17 0 0
v_cancel_customer_reserve 1284 1284 0 0.00 0 2349 16 0 0
v_cancel_payment_to_supplier 800 800 0 0.00 0 536 12 0 0
v_cancel_supplier_invoice 752 720 32 4.25 0 3381 37 0 32
v_cancel_supplier_order 826 826 0 0.00 0 1483 14 0 0
v_cancel_write_off 1976 1976 0 0.00 0 3340 26 0 0
v_max_id_clo_ord 5279 5279 0 0.00 0 242 5 0 0
v_max_id_clo_res 990 990 0 0.00 0 3130 19 0 0
v_max_id_ord_sup 5189 5189 0 0.00 0 2453 6 0 0
v_max_non_paid_invoice 1485 1485 0 0.00 0 130 9 0 0
v_max_non_paid_realizn 3588 3588 0 0.00 0 3332 10 0 0
v_min_id_clo_ord 5279 5279 0 0.00 0 3621 10 0 0
v_min_id_clo_res 990 990 0 0.00 0 1225 9 0 0
v_min_id_ord_sup 5189 5189 0 0.00 0 7868 11 0 0
v_min_non_paid_invoice 5710 5710 0 0.00 0 5205 19 0 0
v_min_non_paid_realizn 6856 6856 0 0.00 0 4104 17 0 0
v_our_firm 10094 10094 0 0.00 0 54 5 0 0
v_reserve_write_off 19492 19492 0 0.00 0 11573 15 0 0
x_lock_depdocs_on_canc_invoice 11455 11455 0 0.00 0 24660 126 0 0
x_lock_depdocs_on_canc_sup_ord 413 413 0 0.00 0 5729 398 0 0
x_make_qstorno_1000_1200 5279 5279 0 0.00 2 54270 1281 0 0
x_make_qstorno_1200_2000 5193 5193 0 0.00 5 83245 3056 0 0
x_make_qstorno_2100_3300 462285 462285 0 0.00 0 80545 225 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 23-01-2023 10:39:26 to 23-01-2023 10:39:30.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
08:07:29 913 8192000000 8922331008 8977801216 13695312 0.166 4 3 5 0 13928320 134992 98016
08:08:32 26643 8192000000 9732145536 10029072384 799117936 8.887 100 90 181 0 829158720 7881056 22159728
08:10:06 22032 8192000000 9758874992 10065084416 835927872 9.259 100 87 174 0 868809008 12127200 20753936
08:11:30 14030 8192000000 9797184864 10116636672 864788512 9.548 100 78 154 0 903458976 20525856 18144608
08:12:52 7009 8192000000 9873480160 10204979200 916664320 10.063 100 84 168 1 986537760 50872784 19000656
08:14:02 4987 8192000000 9930627888 10275495936 960247744 10.491 100 89 176 0 1041213104 62486736 18478624
08:15:11 2956 8192000000 9979550624 10337075200 997107392 10.850 100 85 170 0 1087917872 73199040 17611440
08:16:16 2015 8192000000 9998888528 10357207040 1020727664 11.079 100 90 174 1 1116259584 78235248 17296672
08:17:20 878 8192000000 10020586912 10393403392 1044701008 11.310 100 99 186 0 1137917424 74567168 18649248
08:18:23 1060 8192000000 10046604768 10419572736 1058432080 11.441 100 97 191 0 1160613840 82785456 19396304
08:19:27 1408 8192000000 10057353264 10436308992 1070992144 11.562 100 89 171 0 1183043088 95477904 16573040
08:20:32 1273 8192000000 10044337104 10423144448 1073571440 11.586 100 94 185 0 1161322064 69552736 18197888
08:21:35 1091 8192000000 10057706240 10444058624 1084823136 11.693 100 94 178 1 1176585008 73203920 18557952
08:22:37 1074 8192000000 10059587472 10446536704 1091617408 11.758 100 95 172 5 1183120240 72808544 18694288
08:23:40 1122 8192000000 10068199984 10459033600 1089933328 11.742 100 95 180 3 1190340192 81582800 18824064
08:24:44 1251 8192000000 10068799392 10455257088 1099371424 11.832 100 98 187 1 1195260368 75648912 20240032
08:25:47 1213 8192000000 10062451280 10449166336 1094637264 11.787 100 96 182 1 1185828192 72193568 18997360
08:26:50 1427 8192000000 10061800048 10454249472 1098145856 11.820 100 91 165 2 1183452448 68648032 16658560
08:27:53 1114 8192000000 10069421488 10459025408 1088524240 11.729 100 91 177 0 1191063616 84527808 18011568
08:28:55 1123 8192000000 10057023072 10446839808 1082996960 11.676 99 90 176 0 1180233376 79204880 18031536
08:29:58 1120 8192000000 10032307248 10419261440 1086420944 11.709 100 90 164 2 1152211552 48419280 17371328
08:31:00 2320 8192000000 10025895120 10405097472 1066053376 11.514 100 82 150 4 1140699376 61490400 13155600
08:32:04 2298 8192000000 10019598976 10408783872 1073445552 11.585 100 70 129 1 1141757424 54766320 13545552
08:33:08 848 8192000000 10012572960 10386280448 1053696928 11.396 100 92 176 2 1133823024 62248656 17877440
08:34:11 853 8192000000 10033184464 10417004544 1064814288 11.503 100 84 160 5 1151280544 71043104 15423152
08:35:12 1238 8192000000 10006287168 10381086720 1052729984 11.387 100 96 184 3 1132837440 61489968 18617488
08:36:14 3164 8192000000 10003800480 10390503424 1052226496 11.382 100 64 122 1 1125381008 60972688 12181824
08:37:18 1438 8192000000 10009353472 10387873792 1055318144 11.412 100 80 143 5 1132313824 62920432 14075248
08:38:29 1446 8192000000 10034329952 10413670400 1075178544 11.602 100 86 151 3 1153762992 63284000 15300448
08:39:36 14131 8192000000 10032945920 10419134464 1079282864 11.641 100 66 127 2 1151324208 59252960 12788384
08:40:54 1297 8192000000 10068001760 10452180992 1091971456 11.761 100 78 146 1 1188992336 83398448 13622432
08:41:58 1096 8192000000 10109874928 10496352256 1106676048 11.901 100 96 183 3 1229093472 102727088 19690336
08:43:02 4146 8192000000 10097765376 10500431872 1118012912 12.008 100 67 126 2 1219764176 88171248 13580016
08:44:17 1581 8192000000 10143195440 10537857024 1129894208 12.120 100 93 176 2 1253290480 104964080 18432192
08:45:23 11461 8192000000 10168708256 10578632704 1140857920 12.224 100 69 134 0 1281165408 126198416 14109072
08:46:40 1821 8192000000 10197962912 10597097472 1150939136 12.318 100 87 169 0 1310077424 141201552 17936736
08:47:48 1393 8192000000 10204066368 10612281344 1160448256 12.407 100 96 180 1 1320728592 141928656 18351680
08:48:51 1738 8192000000 10224617136 10628308992 1169319008 12.490 100 89 167 1 1337977728 149234384 19424336
08:49:56 1217 8192000000 10279552752 10684919808 1179443312 12.585 100 95 183 1 1393262944 193065232 20754400
08:51:00 6726 8192000000 10273020688 10685755392 1183428096 12.622 100 74 144 0 1382663520 183283872 15951552
08:52:09 903 8192000000 10315643424 10727489536 1189109216 12.675 100 95 185 0 1425270480 215548384 20612880
08:53:12 853 8192000000 10337668864 10743672832 1168038528 12.478 99 97 194 3 1451184144 261042080 22103536
08:54:17 1730 8192000000 10359501872 10776592384 1176986144 12.562 100 91 175 0 1464754480 267995008 19773328
08:55:24 861 8192000000 10352248704 10791522304 1180388160 12.594 100 94 183 1 1462921040 260631392 21901488
08:56:28 1087 8192000000 10400464592 10853249024 1191607600 12.698 100 88 176 0 1511563824 298818800 21137424
08:57:35 1153 8192000000 10389893712 10814795776 1196016960 12.739 100 96 189 1 1497536624 279037712 22481952
08:58:42 1158 8192000000 10418424672 10853416960 1208842752 12.858 100 90 178 1 1526113984 296666592 20604640
08:59:47 920 8192000000 10434309360 10852413440 1202851792 12.803 100 88 174 1 1537486816 313958608 20676416
09:00:53 881 8192000000 10476775616 10896031744 1204592960 12.819 100 97 188 0 1580034464 351648576 23792928
09:01:57 17040 8192000000 10485866784 10937229312 1197320800 12.751 99 54 106 0 1603713824 392697504 13695520
09:03:20 1706 8192000000 10513995552 10961764352 1200309008 12.779 100 71 134 0 1616672416 398639088 17724320
09:04:31 4883 8192000000 10529135840 10949808128 1201317856 12.789 100 65 128 1 1627255952 409753232 16184864
09:05:46 2265 8192000000 10533911648 10997415936 1193386304 12.715 100 77 141 2 1634156992 421764448 19006240
09:06:51 3965 8192000000 10560417328 11004796928 1186848080 12.654 100 63 120 0 1655373072 452944864 15580128
09:07:59 10919 8192000000 10569924608 11010371584 1176715696 12.560 99 53 102 0 1654469904 463750368 14003840
09:09:12 5720 8192000000 10506592400 10995650560 1177533008 12.567 100 56 112 0 1616524784 423531136 15460640
09:10:23 1820 8192000000 10458156752 10965323776 1173200336 12.527 100 56 107 1 1558501056 371648576 13652144
09:11:26 22100 8192000000 10340089136 10843602944 1161428288 12.417 99 37 73 0 1454930976 284919184 8583504
09:12:49 30191 8192000000 10256767600 10712657920 1158417744 12.388 100 29 55 1 1363478704 198985696 6075264
09:14:20 43256 8192000000 10105631952 10628382720 1123203936 12.057 98 17 34 0 1270371088 143283344 3883808
09:16:24 93892 8192000000 10047423936 10519244800 1087525472 11.719 96 29 56 0 1138158016 45689408 4943136
09:19:06 72059 8192000000 10059513728 10501279744 1078205760 11.630 96 24 50 0 1143801408 60297120 5298528
09:21:21 116747 8192000000 10099228288 10558509056 1102708368 11.863 98 22 45 0 1194936448 87011520 5216560
09:24:19 107905 8192000000 10060385472 10504167424 1078009200 11.628 97 51 101 0 1140375056 51181408 11184448
09:27:08 19563 8192000000 10098079584 10534539264 1098496928 11.823 98 34 68 0 1193298592 87524752 7276912
09:29:04 19945 8192000000 10169751744 10606772224 1128079952 12.103 100 53 106 1 1264338288 124985472 11272864
09:30:25 31024 8192000000 10135099104 10604244992 1129598992 12.118 100 16 31 1 1232498352 98871856 4027504
09:32:07 31502 8192000000 10114467184 10563239936 1124804608 12.072 100 16 28 1 1215361072 87772144 2784320
09:33:39 73416 8192000000 10150503120 10692530176 1138029680 12.197 100 13 26 0 1276814432 135486384 3298368
09:35:53 35848 8192000000 10189818880 10629648384 1132659344 12.146 99 21 42 0 1268953792 131863760 4430688
09:37:40 113108 8192000000 10085860400 10529910784 1118000384 12.008 98 13 26 0 1223083408 102284896 2798128
09:40:38 68420 8192000000 10071824144 10560462848 1125587504 12.080 99 34 66 0 1196657376 62809920 8259952
09:42:47 10365 8192000000 10103157152 10534723584 1143100672 12.245 100 39 76 2 1191771856 40304704 8366480
09:44:01 101588 8192000000 10122376336 10610896896 1114436704 11.974 97 10 20 0 1151560992 35534848 1589440
09:47:12 80031 8192000000 10082377072 10538872832 1152748016 12.335 100 32 62 2 1193370384 33281760 7340608
09:49:36 28700 8192000000 10110373504 10564513792 1135777696 12.176 98 39 78 0 1190004704 46195232 8031776
09:51:14 58098 8192000000 10093675776 10574000128 1141764688 12.232 99 9 18 0 1193342576 49509792 2068096
09:53:13 51609 8192000000 10086485776 10548035584 1137235840 12.190 99 12 24 0 1183270352 43374960 2659552
09:55:07 61639 8192000000 10108941248 10560049152 1148530288 12.296 99 32 64 0 1211013824 55036496 7447040
09:57:23 73331 8192000000 10103060928 10586144768 1128236288 12.105 97 19 38 0 1200082224 67386544 4459392
09:59:36 69913 8192000000 10080700880 10624962560 1146707600 12.279 99 8 16 0 1215877168 67404176 1765392
10:01:55 43333 8192000000 10134178288 10580328448 1132359392 12.144 98 30 60 0 1218233232 79714368 6159472
10:03:43 64277 8192000000 10162037520 10630066176 1144973104 12.262 99 27 54 1 1271019008 120051040 5994864
10:05:53 43377 8192000000 10094814544 10577186816 1096200176 11.802 95 41 80 1 1192120576 87615088 8305312
10:08:17 47413 8192000000 10077745808 10549055488 1083573152 11.682 95 33 61 1 1148849120 58853472 6422496
10:10:50 13807 8192000000 10105924256 10534694912 1129255856 12.114 100 44 88 0 1192389648 54620688 8513104
10:12:08 98858 8192000000 10103117328 10549014528 1118506544 12.013 100 41 82 0 1225389824 98316032 8567248
10:15:04 80841 8192000000 10078448128 10538811392 1132101792 12.141 100 23 46 0 1181247424 44406688 4738944
10:17:25 74563 8192000000 10087462304 10545430528 1065165232 11.506 94 14 28 1 1126255824 58412736 2677856
10:19:46 34831 8192000000 10076741200 10536845312 1088483056 11.728 97 22 44 0 1150816560 57257408 5076096
10:21:21 44189 8192000000 10097205184 10537373696 1077413824 11.623 95 27 54 0 1147087808 63728224 5945760
10:23:06 32820 8192000000 10170342192 10610556928 1124643104 12.071 99 39 78 2 1245272704 112027536 8602064
10:24:39 32277 8192000000 10171620192 10609717248 1148677104 12.297 100 36 72 0 1273521712 116961504 7883104
10:26:16 28877 8192000000 10180998944 10615959552 1143360208 12.247 100 32 64 0 1263011264 112834752 6816304
10:27:59 93112 8192000000 10129596832 10575036416 1152387344 12.332 100 21 42 0 1219103152 62580544 4135264
10:30:39 57769 8192000000 10096172368 10514665472 1117461824 12.003 97 28 53 1 1150744528 28118112 5164592
10:32:38 89342 8192000000 10092342304 10537963520 1126387152 12.087 99 31 62 0 1186917824 54664880 5865792
10:35:10 55753 8192000000 10070775296 10548326400 1132524704 12.145 100 19 38 0 1198077984 61782912 3770368

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 23-01-2023 10:39:32 to 23-01-2023 10:39:48.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 111 v_add_invoice_to_stock 335544517
except 16 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 23-01-2023 10:39:49 to 23-01-2023 10:39:51.

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          215546
MON$OLDEST_ACTIVE               216789
MON$OLDEST_SNAPSHOT             216789
MON$NEXT_TRANSACTION            216789
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                       2076640
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.3.2890 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2890 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2890 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2890 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'

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