Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 27.06.2022 07:49
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 15858248 kB
MemAvailable 31229116 kB
Buffers 0 kB
Cached 15875720 kB
SwapCached 2504 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-06-27 07:46:48
DTS_END                         2022-06-27 07:46:48.0650
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/35096
TRN_ID                          221195
ATT_ID                          660
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: 8931.99 33647 1071839
customer order: creation 162.21 468 19465
customer order: refuse 14.38 410 1725
order to supplier: creation 44.44 1348 5333
order to supplier: removal 3.47 166662 416
invoice (draft): creation 45.56 3086 5467
invoice (draft): removal 50.97 987 6116
invoice accept: apply 40.96 25301 4915
invoice accept: cancel 45.78 1174 5494
customer reserve: creation 3771.15 242 452538
customer reserve: removal 4464.22 102 535706
realization accept: apply 81.47 264 9776
realization accept: cancel 79.91 159 9589
payment from customer: creation 58.38 176 7005
payment from customer: removal 5.97 119 716
payment to supplier: creation 46.88 207 5625
payment to supplier: removal 3.42 119 410
service: total inventory turnovers 4.03 3731 484
service: total monetary turnovers 7.79 348 935
service: aggregate perf. data 1.00 13100 120
service: refresh index statistics 0.03 454930 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 27-06-2022 07:49:59 to 27-06-2022 07:50:01.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4034
TEST_TIME 2 5075
TEST_TIME 3 5032
TEST_TIME 4 5279
TEST_TIME 5 5025
TEST_TIME 6 5506
TEST_TIME 7 5421
TEST_TIME 8 5364
TEST_TIME 9 5621
TEST_TIME 10 5980
TEST_TIME 11 6576
TEST_TIME 12 6507
TEST_TIME 13 6811
TEST_TIME 14 6852
TEST_TIME 15 6957
TEST_TIME 16 6953
TEST_TIME 17 7187
TEST_TIME 18 7425
TEST_TIME 19 7670
TEST_TIME 20 7734
TEST_TIME 21 8121
TEST_TIME 22 8125
TEST_TIME 23 8139
TEST_TIME 24 8055
TEST_TIME 25 8224
TEST_TIME 26 8533
TEST_TIME 27 8707
TEST_TIME 28 8799
TEST_TIME 29 9212
TEST_TIME 30 9430
TEST_TIME 31 9619
TEST_TIME 32 10276
TEST_TIME 33 10187
TEST_TIME 34 10304
TEST_TIME 35 10572
TEST_TIME 36 10954
TEST_TIME 37 10906
TEST_TIME 38 10783
TEST_TIME 39 10618
TEST_TIME 40 10554
TEST_TIME 41 10484
TEST_TIME 42 10409
TEST_TIME 43 10405
TEST_TIME 44 10487
TEST_TIME 45 10293
TEST_TIME 46 10116
TEST_TIME 47 9982
TEST_TIME 48 9856
TEST_TIME 49 9810
TEST_TIME 50 9729
TEST_TIME 51 9698
TEST_TIME 52 9651
TEST_TIME 53 9595
TEST_TIME 54 9565
TEST_TIME 55 9595
TEST_TIME 56 9524
TEST_TIME 57 9527
TEST_TIME 58 9474
TEST_TIME 59 9380
TEST_TIME 60 9375
TEST_TIME 61 9278
TEST_TIME 62 9278
TEST_TIME 63 9330
TEST_TIME 64 9359
TEST_TIME 65 9372
TEST_TIME 66 9349
TEST_TIME 67 9340
TEST_TIME 68 9412
TEST_TIME 69 9359
TEST_TIME 70 9369
TEST_TIME 71 9459
TEST_TIME 72 9540
TEST_TIME 73 9553
TEST_TIME 74 9526
TEST_TIME 75 9408
TEST_TIME 76 9362
TEST_TIME 77 9309
TEST_TIME 78 9277
TEST_TIME 79 9194
TEST_TIME 80 9157
TEST_TIME 81 9086
TEST_TIME 82 9156
TEST_TIME 83 9127
TEST_TIME 84 9101
TEST_TIME 85 9074
TEST_TIME 86 9064
TEST_TIME 87 9029
TEST_TIME 88 9045
TEST_TIME 89 9047
TEST_TIME 90 9071
TEST_TIME 91 9036
TEST_TIME 92 9029
TEST_TIME 93 9093
TEST_TIME 94 9103
TEST_TIME 95 9101
TEST_TIME 96 9091
TEST_TIME 97 9160
TEST_TIME 98 9120
TEST_TIME 99 9040
TEST_TIME 100 9008
TEST_TIME 101 8990
TEST_TIME 102 8943
TEST_TIME 103 8885
TEST_TIME 104 8876
TEST_TIME 105 8847
TEST_TIME 106 8805
TEST_TIME 107 8817
TEST_TIME 108 8794
TEST_TIME 109 8818
TEST_TIME 110 8803
TEST_TIME 111 8808
TEST_TIME 112 8787
TEST_TIME 113 8813
TEST_TIME 114 8860
TEST_TIME 115 8971
TEST_TIME 116 9015
TEST_TIME 117 9071
TEST_TIME 118 9041
TEST_TIME 119 8975
TEST_TIME 120 8953

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 27-06-2022 07:50:02 to 27-06-2022 07:50:06.

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 1489189 1489189 0 0.00 0 22873 13 0 0
doc_list_biud 1489175 1489175 0 0.00 0 42587 43 0 0
sp_add_invoice_to_stock 4991 4915 76 1.52 25 222714 25302 0 76
sp_cancel_adding_invoice 5494 5494 0 0.00 6 101431 1174 0 0
sp_cancel_client_order 1725 1725 0 0.00 6 8578 410 0 0
sp_cancel_customer_reserve 535706 535706 0 0.00 1 42590 103 0 0
sp_cancel_pay_from_customer 716 716 0 0.00 4 5320 120 0 0
sp_cancel_pay_to_supplier 410 410 0 0.00 3 2721 119 0 0
sp_cancel_supplier_invoice 6123 6116 7 0.11 2 24835 987 0 7
sp_cancel_supplier_order 416 416 0 0.00 11 1569939 166662 0 0
sp_cancel_write_off 9589 9589 0 0.00 2 14555 159 0 0
sp_client_order 19465 19465 0 0.00 5 39727 468 0 0
sp_customer_reserve 452538 452538 0 0.00 0 95735 243 0 0
sp_fill_shopping_cart 30255 30255 0 0.00 1 18430 82 0 0
sp_fill_shopping_cart_clo_res 452502 452502 0 0.00 0 20373 18 0 0
sp_get_clo_for_invoice 4872 4872 0 0.00 0 26862 288 0 0
sp_kill_cost_storno 16209 16209 0 0.00 0 9049 74 0 0
sp_kill_qstorno_ret_qs2qd 1087533 1087533 0 0.00 0 42471 45 0 0
sp_kill_qty_storno 573694 573694 0 0.00 0 42587 103 0 0
sp_lock_selected_doc 20364 20364 0 0.00 0 9265 8 0 0
sp_make_cost_storno 27278 27278 0 0.00 0 13054 74 0 0
sp_multiply_rows_for_pdistr 27278 27278 0 0.00 0 8105 39 0 0
sp_multiply_rows_for_qdistr 19463 19463 0 0.00 0 38198 175 0 0
sp_pay_from_customer 7005 7005 0 0.00 6 9882 177 0 0
sp_pay_to_supplier 5625 5625 0 0.00 6 13063 207 0 0
sp_payment_common 12630 12630 0 0.00 2 7551 48 0 0
sp_qd_handle_on_cancel_clo 1725 1725 0 0.00 0 8565 336 0 0
sp_qd_handle_on_invoice_upd_sts 10366 10366 0 0.00 0 18752 507 0 0
sp_qd_handle_on_reserve_upd_sts 9776 9776 0 0.00 0 21006 58 0 0
sp_reserve_write_off 9776 9776 0 0.00 8 21065 265 0 0
sp_supplier_invoice 5467 5467 0 0.00 9 88525 3087 0 0
sp_supplier_order 5333 5333 0 0.00 7 32699 1349 0 0
srv_aggregate_perf_data 120 120 0 0.00 2184 163917 13100 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 796 129486 36175 0 0
srv_find_qd_qs_mism 1024575 1024575 0 0.00 0 22871 10 0 0
srv_make_invnt_saldo 484 484 0 0.00 97 55683 3731 0 0
srv_make_money_saldo 935 935 0 0.00 1 10885 349 0 0
srv_recalc_idx_stat 4 4 0 0.00 408413 568168 454931 0 0
t$perf-abend:sp_add_invoice_to_stock 76 76 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4917 4917 0 0.00 1 12410 894 0 0
t$perf-norm:sp_cancel_adding_invoice 398 398 0 0.00 1 7017 452 0 0
t$perf-norm:sp_cancel_client_order 1725 1725 0 0.00 0 5901 34 0 0
t$perf-norm:sp_cancel_customer_reserve 694 694 0 0.00 0 1832 26 0 0
t$perf-norm:sp_cancel_pay_from_customer 716 716 0 0.00 0 786 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 410 410 0 0.00 0 324 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 399 399 0 0.00 0 1021 36 0 0
t$perf-norm:sp_cancel_supplier_order 416 416 0 0.00 1 93274 7189 0 0
t$perf-norm:sp_cancel_write_off 960 960 0 0.00 0 528 16 0 0
t$perf-norm:sp_client_order 19467 19467 0 0.00 0 8578 32 0 0
t$perf-norm:sp_customer_reserve 978 978 0 0.00 0 262 12 0 0
t$perf-norm:sp_pay_from_customer 7005 7005 0 0.00 0 5155 16 0 0
t$perf-norm:sp_pay_to_supplier 5625 5625 0 0.00 0 2837 18 0 0
t$perf-norm:sp_reserve_write_off 9777 9777 0 0.00 0 14478 23 0 0
t$perf-norm:sp_supplier_invoice 5467 5467 0 0.00 0 1902 28 0 0
t$perf-norm:sp_supplier_order 5333 5333 0 0.00 0 6793 35 0 0
t$perf-norm:srv_aggregate_perf_data 120 120 0 0.00 0 719 19 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 40 4 0 0
t$perf-norm:srv_make_invnt_saldo 484 484 0 0.00 0 1116 25 0 0
t$perf-norm:srv_make_money_saldo 935 935 0 0.00 0 1654 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 208 75 0 0
v_add_invoice_to_stock 9892 9740 152 1.53 0 7436 25 0 152
v_all_customers 35709 35709 0 0.00 0 97 4 0 0
v_all_suppliers 29744 29744 0 0.00 0 97 4 0 0
v_all_wares 38926 38926 0 0.00 0 98 4 0 0
v_cancel_adding_invoice 796 796 0 0.00 0 5132 21 0 0
v_cancel_client_order 3450 3450 0 0.00 0 5253 11 0 0
v_cancel_customer_prepayment 1432 1432 0 0.00 0 2687 11 0 0
v_cancel_customer_reserve 1388 1388 0 0.00 0 1181 11 0 0
v_cancel_payment_to_supplier 820 820 0 0.00 0 1655 13 0 0
v_cancel_supplier_invoice 812 798 14 1.72 0 570 20 0 14
v_cancel_supplier_order 832 832 0 0.00 0 3012 20 0 0
v_cancel_write_off 1920 1920 0 0.00 0 5975 26 0 0
v_max_id_clo_ord 5328 5328 0 0.00 0 423 5 0 0
v_max_id_clo_res 978 978 0 0.00 0 1664 17 0 0
v_max_id_ord_sup 5463 5463 0 0.00 0 6785 8 0 0
v_max_non_paid_invoice 1543 1543 0 0.00 0 360 7 0 0
v_max_non_paid_realizn 3802 3802 0 0.00 0 1253 7 0 0
v_min_id_clo_ord 5327 5327 0 0.00 0 715 7 0 0
v_min_id_clo_res 978 978 0 0.00 0 2121 10 0 0
v_min_id_ord_sup 5463 5463 0 0.00 0 6997 18 0 0
v_min_non_paid_invoice 5625 5625 0 0.00 0 5356 18 0 0
v_min_non_paid_realizn 7005 7005 0 0.00 1 6508 24 0 0
v_our_firm 9616 9616 0 0.00 0 75 4 0 0
v_reserve_write_off 19552 19552 0 0.00 0 5130 11 0 0
x_lock_depdocs_on_canc_invoice 11211 11211 0 0.00 0 11434 122 0 0
x_lock_depdocs_on_canc_sup_ord 416 416 0 0.00 0 9406 361 0 0
x_make_qstorno_1000_1200 5333 5333 0 0.00 3 32682 1189 0 0
x_make_qstorno_1200_2000 5467 5467 0 0.00 4 84016 2849 0 0
x_make_qstorno_2100_3300 452537 452537 0 0.00 0 95684 213 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 27-06-2022 07:50:06 to 27-06-2022 07:50:10.

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:16:51 929 8192000000 8921754672 8977039360 3900128 0.047 2 1 2 0 3997744 61840 35776
05:17:53 35696 8192000000 9736366800 10035875840 811340640 9.011 100 89 178 0 840113056 8153760 20618656
05:19:42 11322 8192000000 9766903792 10076352512 840344240 9.303 100 88 176 0 875312752 14431936 20536576
05:20:57 14566 8192000000 9799256736 10120327168 868331456 9.583 100 87 172 0 913176400 25413840 19431104
05:22:17 7750 8192000000 9867696016 10197803008 909481664 9.992 100 79 158 0 976779248 50241904 17055680
05:23:29 3540 8192000000 9915808096 10258223104 952087264 10.412 100 90 174 4 1032105824 61840336 18178224
05:24:37 1281 8192000000 9972221472 10328637440 992481952 10.806 100 92 177 1 1092463776 82547440 17434384
05:25:41 1062 8192000000 9979982832 10338783232 1017099824 11.044 100 91 175 0 1099120320 64471968 17548528
05:26:44 963 8192000000 10013238528 10376753152 1035951200 11.226 100 95 180 2 1130746800 77176448 17619152
05:27:45 1417 8192000000 10014074304 10387808256 1068717872 11.540 100 95 188 2 1144274944 56539408 19017664
05:28:48 7374 8192000000 10033870256 10410950656 1068734656 11.540 100 95 180 1 1156878464 70233472 17910336
05:29:57 1086 8192000000 10071789824 10456133632 1079041264 11.638 100 94 181 2 1193054704 95812128 18201312
05:31:00 1202 8192000000 10039468656 10426265600 1085553792 11.700 100 93 176 0 1161208064 57995200 17659072
05:32:02 1200 8192000000 10060097888 10447249408 1085213232 11.697 100 96 183 2 1179487040 75644304 18629504
05:33:05 908 8192000000 10059158112 10448076800 1090905504 11.751 100 94 178 0 1182433456 73051392 18476560
05:34:07 1065 8192000000 10047547568 10435788800 1099839808 11.836 100 91 169 0 1172011856 54221584 17950464
05:35:08 1027 8192000000 10064438208 10451927040 1103072784 11.867 100 88 163 5 1185977760 65219552 17685424
05:36:10 1017 8192000000 10078730608 10470199296 1104704096 11.882 100 94 177 2 1198900480 75685152 18511232
05:37:12 1183 8192000000 10071519904 10462515200 1103033728 11.866 100 95 185 2 1191917520 70961280 17922512
05:38:14 1159 8192000000 10058033440 10446929920 1106292880 11.897 100 90 175 1 1175830176 51248160 18289136
05:39:16 1067 8192000000 10030999168 10423967744 1082876768 11.675 100 69 134 1 1154904768 57855232 14172768
05:40:18 8378 8192000000 10002323872 10393980928 1067634544 11.529 99 57 111 1 1138781184 59914208 11232432
05:41:29 1071 8192000000 9964491856 10336931840 1033214032 11.199 100 91 159 6 1086360000 36955952 16190016
05:42:31 1147 8192000000 9983003504 10353573888 1044098576 11.304 100 91 177 2 1100058176 36857680 19101920
05:43:32 2884 8192000000 9966174560 10341183488 1008211952 10.958 98 66 125 1 1094946624 74068544 12666128
05:44:37 1720 8192000000 9974778864 10349223936 1017487776 11.048 100 77 151 1 1095318944 63001376 14829792
05:45:39 1082 8192000000 9986276192 10361155584 1038843696 11.254 100 93 179 0 1106362880 48582432 18936752
05:46:41 2163 8192000000 9978153056 10351689728 1026803056 11.138 100 79 148 3 1101300560 60183984 14313520
05:47:50 10269 8192000000 10009838784 10386935808 1051117344 11.371 100 74 135 1 1126058352 62047648 12893360
05:49:05 2002 8192000000 10051409936 10436132864 1077338720 11.622 100 76 147 1 1174725280 81325264 16061296
05:50:11 1374 8192000000 10076075104 10461073408 1088051232 11.724 100 75 143 1 1194812496 91595776 15165488
05:51:18 20412 8192000000 10109764992 10502139904 1102962544 11.866 100 74 138 1 1228180880 110099072 15119264
05:52:40 1116 8192000000 10142127120 10531483648 1107704720 11.911 100 99 185 1 1259998992 132977872 19316400
05:53:45 12751 8192000000 10178633088 10586861568 1123041376 12.056 100 64 126 0 1297818416 159858544 14918496
05:55:08 1597 8192000000 10202945808 10609000448 1138710224 12.203 100 90 175 0 1318462320 159207248 20544848
05:56:13 2477 8192000000 10262653072 10670305280 1142213248 12.236 100 82 158 1 1373331008 213439776 17677984
05:57:19 5559 8192000000 10274290368 10687578112 1164465120 12.445 100 78 142 3 1385000464 205013328 15522016
05:58:29 780 8192000000 10312054048 10718470144 1172422368 12.519 100 96 185 1 1423406480 230147360 20836752
05:59:34 1107 8192000000 10336956112 10740391936 1186420480 12.650 100 96 189 2 1453835744 245458208 21957056
06:00:40 928 8192000000 10384606144 10787651584 1191244064 12.695 100 98 182 1 1497123056 283427888 22451104
06:01:45 1014 8192000000 10412895360 10818109440 1187956736 12.664 100 95 189 1 1520387168 309537824 22892608
06:02:48 1377 8192000000 10447879424 10866782208 1190680800 12.690 100 95 187 2 1549688032 337145120 21862112
06:03:54 1220 8192000000 10451310240 10875310080 1179476608 12.585 100 88 166 0 1557838512 358765360 19596544
06:04:59 1586 8192000000 10463223776 10895491072 1185165680 12.638 100 91 181 0 1573729552 366450544 22113328
06:06:05 942 8192000000 10437498800 10872049664 1186342256 12.649 100 96 190 0 1549461744 339666304 23453184
06:07:10 1037 8192000000 10436146624 10887204864 1186317952 12.649 100 95 183 3 1546740784 339193728 21229104
06:08:15 4816 8192000000 10442294096 10872242176 1193435504 12.715 100 59 112 1 1544712128 338447776 12828848
06:09:27 2479 8192000000 10485119376 10924400640 1196278832 12.742 100 55 110 1 1587278032 376621616 14377584
06:10:45 1240 8192000000 10507907712 10931736576 1171862384 12.514 99 68 130 1 1615263792 427041360 16360048
06:11:53 2487 8192000000 10502366080 10952089600 1170476512 12.501 100 73 145 2 1608790512 421023296 17290704
06:12:58 12630 8192000000 10427096880 10901135360 1165649328 12.456 100 40 76 0 1543566336 367682416 10234592
06:14:15 9083 8192000000 10460435856 10931421184 1165849696 12.458 100 46 92 0 1570785680 393539488 11396496
06:15:25 29368 8192000000 10395598128 10858102784 1143752784 12.251 98 37 74 0 1483814176 330335744 9725648
06:16:59 12077 8192000000 10367716784 10828529664 1134500176 12.164 97 31 62 0 1457702224 314964432 8237616
06:18:25 10420 8192000000 10246774384 10772692992 1144341920 12.256 100 41 82 0 1348857008 194463328 10051760
06:19:36 18680 8192000000 10215652912 10674049024 1109918496 11.932 98 22 44 0 1290471760 175362224 5191040
06:21:24 24269 8192000000 10183016224 10704416768 1118253152 12.010 99 13 26 0 1316688832 195292592 3143088
06:22:56 104104 8192000000 10047061472 10471804928 1096268416 11.802 97 20 38 2 1163904640 62319312 5316912
06:26:11 31599 8192000000 10091230656 10544005120 1123134688 12.057 99 32 63 0 1172619984 43042096 6443200
06:27:47 73911 8192000000 10073927696 10567770112 1133419440 12.154 99 13 26 0 1201662480 66070128 2172912
06:30:52 126337 8192000000 10064108624 10498490368 1106451776 11.899 97 25 47 1 1132750944 21434544 4864624
06:34:29 39702 8192000000 10081779472 10524274688 1129685040 12.118 98 57 109 3 1171525152 29688912 12151200
06:36:10 28340 8192000000 10130901504 10565623808 1133730752 12.157 99 35 70 0 1204585040 63468144 7386144
06:37:42 39955 8192000000 10127972336 10579181568 1105858272 11.893 97 39 78 0 1211349840 96697008 8794560
06:39:22 49067 8192000000 10121540864 10569527296 1122240464 12.048 99 34 68 0 1228562112 98456016 7865632
06:41:13 52374 8192000000 10096805184 10562887680 1131543472 12.136 100 16 32 0 1215468208 80272720 3652016
06:43:05 77631 8192000000 10075862464 10553692160 1120798704 12.035 98 17 34 1 1189410528 64777840 3833984
06:45:24 85433 8192000000 10084527440 10556178432 1104882448 11.884 97 20 41 0 1157553696 48327504 4343744
06:47:58 129484 8192000000 10106485872 10577317888 1093280112 11.774 96 19 38 0 1157834720 60422128 4132480
06:51:08 50224 8192000000 10107361360 10575278080 1119179344 12.019 98 28 56 0 1184639344 59880688 5579312
06:53:10 66970 8192000000 10102565520 10558820352 1083247728 11.678 95 22 44 0 1172999472 84729744 5022000
06:55:31 10311 8192000000 10109214560 10541195264 1131664384 12.137 100 56 104 2 1195271648 52919648 10687616
06:56:49 79837 8192000000 10129538400 10629382144 1093358112 11.775 96 15 30 0 1210647792 114454304 2835376
06:59:09 68798 8192000000 10075181712 10540503040 1123972544 12.065 99 16 32 0 1181891072 54817888 3100640
07:01:19 91855 8192000000 10072929856 10534588416 1119775456 12.025 98 35 70 0 1173457264 46178976 7502832
07:04:18 67244 8192000000 10087679664 10544689152 1096252512 11.802 97 16 32 0 1162150672 62808432 3089728
07:06:37 55623 8192000000 10135588432 10597666816 1115828832 11.988 99 13 27 0 1219021440 100317296 2875312
07:09:29 12156 8192000000 10056234512 10513649664 1111234944 11.944 98 47 86 0 1149654384 27156400 11263040
07:10:42 45986 8192000000 10088015200 10535227392 1128115568 12.104 100 25 50 0 1182203040 48741520 5345952
07:13:10 66751 8192000000 10074450336 10540752896 1138540656 12.202 100 33 63 0 1176018784 30814256 6663872
07:15:19 45446 8192000000 10093896976 10570526720 1123207680 12.057 99 43 86 1 1193690336 61730560 8752096
07:17:09 39095 8192000000 10108803872 10549895168 1143179936 12.245 100 37 74 0 1187357952 36984400 7193616
07:18:49 86831 8192000000 10120736480 10601132032 1150567056 12.315 100 22 42 0 1215086480 60419344 4100080
07:21:17 22874 8192000000 10103937632 10553589760 1115866496 11.988 97 58 109 1 1176079200 49209536 11003168
07:22:44 101327 8192000000 10085888160 10587922432 1117093232 12.000 97 29 58 0 1219736608 96373440 6269936
07:25:25 44353 8192000000 10063661840 10504130560 1128087648 12.103 99 45 82 2 1158313952 22049152 8177152
07:27:10 53832 8192000000 10061058912 10526150656 1124792288 12.072 99 14 27 0 1153206880 25824640 2589952
07:29:04 37597 8192000000 10071161232 10545127424 1137227472 12.189 100 8 15 0 1176608400 37970304 1410624
07:30:47 58085 8192000000 10102253680 10551156736 1118809200 12.016 97 22 42 1 1181473456 58359568 4304688
07:32:45 23296 8192000000 10148513664 10593169408 1132365760 12.144 99 34 68 0 1230224800 89987120 7871920
07:34:15 60168 8192000000 10195175072 10665725952 1134047760 12.160 98 28 55 0 1259411264 118246208 7117296
07:36:38 27292 8192000000 10234825792 10668941312 1147555328 12.287 100 47 93 1 1323517024 165339328 10622368
07:38:07 8212 8192000000 10263067936 10697216000 1157280032 12.378 100 50 91 0 1347649360 180433712 9935616
07:39:16 56456 8192000000 10249193200 10751676416 1117724656 12.005 98 46 92 0 1358940176 230162096 11053424
07:41:20 72119 8192000000 10135761808 10619715584 1093570224 11.777 97 11 22 0 1217603920 121375440 2658256
07:44:02 44309 8192000000 10091318160 10525589504 1088526000 11.729 97 40 80 0 1153554928 57383312 7645616
07:45:47 65706 8192000000 9917816160 10349723648 971831936 10.605 87 36 71 0 1039105008 59884480 7388592

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 27-06-2022 07:50:12 to 27-06-2022 07:50:28.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 76 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 27-06-2022 07:50:29 to 27-06-2022 07:50:31.

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          217616
MON$OLDEST_ACTIVE               221437
MON$OLDEST_SNAPSHOT             221437
MON$NEXT_TRANSACTION            221437
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                       2079776
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             779
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2790 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2790 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2790 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2790 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    Mon Jun 27 07:47:51 2022
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Mon Jun 27 07:47:51 2022
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:13: 
--- end of diff output ---

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

27.06.22 07:50:32. 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-06-27 07:50:34 No severe PSQL-related problems occured
27.06.22 07:50:35. End of report.