Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 08.05.2022 04:39
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 16126092 kB
MemAvailable 31487084 kB
Buffers 20 kB
Cached 15549468 kB
SwapCached 2560 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-05-08 04:37:19
DTS_END                         2022-05-08 04:37:19.8060
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/57152
TRN_ID                          216649
ATT_ID                          663
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: 9017.78 25024 1082133
customer order: creation 165.58 485 19870
customer order: refuse 14.71 387 1765
order to supplier: creation 45.59 1378 5471
order to supplier: removal 3.27 176983 392
invoice (draft): creation 45.58 3126 5469
invoice (draft): removal 49.73 1043 5968
invoice accept: apply 42.50 28283 5100
invoice accept: cancel 44.69 1230 5363
customer reserve: creation 3928.77 271 471452
customer reserve: removal 4399.90 102 527988
realization accept: apply 81.35 295 9762
realization accept: cancel 70.38 180 8445
payment from customer: creation 57.43 182 6891
payment from customer: removal 5.24 133 629
payment to supplier: creation 47.43 213 5691
payment to supplier: removal 3.53 124 424
service: total inventory turnovers 3.98 4342 477
service: total monetary turnovers 7.28 427 873
service: aggregate perf. data 0.83 17973 99
service: refresh index statistics 0.03 263321 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 08-05-2022 04:39:32 to 08-05-2022 04:39:34.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 10397
TEST_TIME 2 7686
TEST_TIME 3 6763
TEST_TIME 4 6437
TEST_TIME 5 6329
TEST_TIME 6 6625
TEST_TIME 7 7011
TEST_TIME 8 7004
TEST_TIME 9 6927
TEST_TIME 10 6777
TEST_TIME 11 7125
TEST_TIME 12 7158
TEST_TIME 13 7168
TEST_TIME 14 7136
TEST_TIME 15 7164
TEST_TIME 16 7120
TEST_TIME 17 7565
TEST_TIME 18 7616
TEST_TIME 19 7509
TEST_TIME 20 7496
TEST_TIME 21 7826
TEST_TIME 22 8110
TEST_TIME 23 8195
TEST_TIME 24 8144
TEST_TIME 25 8519
TEST_TIME 26 8358
TEST_TIME 27 8715
TEST_TIME 28 8745
TEST_TIME 29 8708
TEST_TIME 30 9156
TEST_TIME 31 9579
TEST_TIME 32 10049
TEST_TIME 33 10183
TEST_TIME 34 10712
TEST_TIME 35 11098
TEST_TIME 36 11298
TEST_TIME 37 11241
TEST_TIME 38 11098
TEST_TIME 39 10914
TEST_TIME 40 10697
TEST_TIME 41 10885
TEST_TIME 42 10801
TEST_TIME 43 10727
TEST_TIME 44 10665
TEST_TIME 45 10605
TEST_TIME 46 10439
TEST_TIME 47 10320
TEST_TIME 48 10269
TEST_TIME 49 10240
TEST_TIME 50 10292
TEST_TIME 51 10203
TEST_TIME 52 10096
TEST_TIME 53 10132
TEST_TIME 54 10021
TEST_TIME 55 9949
TEST_TIME 56 9824
TEST_TIME 57 9762
TEST_TIME 58 9724
TEST_TIME 59 9657
TEST_TIME 60 9562
TEST_TIME 61 9460
TEST_TIME 62 9574
TEST_TIME 63 9578
TEST_TIME 64 9528
TEST_TIME 65 9540
TEST_TIME 66 9508
TEST_TIME 67 9589
TEST_TIME 68 9719
TEST_TIME 69 9819
TEST_TIME 70 9815
TEST_TIME 71 9836
TEST_TIME 72 9820
TEST_TIME 73 9783
TEST_TIME 74 9720
TEST_TIME 75 9671
TEST_TIME 76 9599
TEST_TIME 77 9559
TEST_TIME 78 9583
TEST_TIME 79 9544
TEST_TIME 80 9529
TEST_TIME 81 9465
TEST_TIME 82 9418
TEST_TIME 83 9444
TEST_TIME 84 9388
TEST_TIME 85 9361
TEST_TIME 86 9396
TEST_TIME 87 9400
TEST_TIME 88 9409
TEST_TIME 89 9400
TEST_TIME 90 9419
TEST_TIME 91 9447
TEST_TIME 92 9427
TEST_TIME 93 9539
TEST_TIME 94 9499
TEST_TIME 95 9507
TEST_TIME 96 9435
TEST_TIME 97 9395
TEST_TIME 98 9332
TEST_TIME 99 9341
TEST_TIME 100 9280
TEST_TIME 101 9229
TEST_TIME 102 9217
TEST_TIME 103 9226
TEST_TIME 104 9260
TEST_TIME 105 9236
TEST_TIME 106 9216
TEST_TIME 107 9211
TEST_TIME 108 9222
TEST_TIME 109 9218
TEST_TIME 110 9258
TEST_TIME 111 9266
TEST_TIME 112 9232
TEST_TIME 113 9276
TEST_TIME 114 9266
TEST_TIME 115 9255
TEST_TIME 116 9195
TEST_TIME 117 9174
TEST_TIME 118 9148
TEST_TIME 119 9103
TEST_TIME 120 9045

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 08-05-2022 04:39:35 to 08-05-2022 04:39:39.

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 1515285 1515285 0 0.00 0 33860 15 0 0
doc_list_biud 1515267 1515267 0 0.00 0 32917 41 0 0
sp_add_invoice_to_stock 5214 5100 114 2.18 52 243949 28284 0 114
sp_cancel_adding_invoice 5363 5363 0 0.00 6 72724 1230 0 0
sp_cancel_client_order 1765 1765 0 0.00 6 9319 387 0 0
sp_cancel_customer_reserve 527988 527988 0 0.00 1 32920 103 0 0
sp_cancel_pay_from_customer 629 629 0 0.00 3 4418 134 0 0
sp_cancel_pay_to_supplier 424 424 0 0.00 3 4843 125 0 0
sp_cancel_supplier_invoice 5983 5968 15 0.25 2 22705 1044 0 15
sp_cancel_supplier_order 392 392 0 0.00 22 1615695 176983 0 0
sp_cancel_write_off 8445 8445 0 0.00 2 19736 180 0 0
sp_client_order 19870 19870 0 0.00 6 59607 485 0 0
sp_customer_reserve 471452 471452 0 0.00 1 87354 272 0 0
sp_fill_shopping_cart 30799 30799 0 0.00 2 18099 86 0 0
sp_fill_shopping_cart_clo_res 471415 471415 0 0.00 0 18337 22 0 0
sp_get_clo_for_invoice 5051 5051 0 0.00 0 35901 277 0 0
sp_kill_cost_storno 14861 14861 0 0.00 0 33850 75 0 0
sp_kill_qstorno_ret_qs2qd 1070781 1070781 0 0.00 0 32906 44 0 0
sp_kill_qty_storno 564730 564730 0 0.00 0 32917 101 0 0
sp_lock_selected_doc 20450 20450 0 0.00 0 11006 10 0 0
sp_make_cost_storno 27394 27394 0 0.00 0 32723 85 0 0
sp_multiply_rows_for_pdistr 27391 27391 0 0.00 0 8100 41 0 0
sp_multiply_rows_for_qdistr 19870 19870 0 0.00 0 11215 184 0 0
sp_pay_from_customer 6891 6891 0 0.00 6 12136 182 0 0
sp_pay_to_supplier 5691 5691 0 0.00 7 32767 213 0 0
sp_payment_common 12581 12581 0 0.00 2 8945 50 0 0
sp_qd_handle_on_cancel_clo 1765 1765 0 0.00 0 7510 314 0 0
sp_qd_handle_on_invoice_upd_sts 10411 10411 0 0.00 0 18837 529 0 0
sp_qd_handle_on_reserve_upd_sts 9761 9761 0 0.00 0 17649 65 0 0
sp_reserve_write_off 9762 9762 0 0.00 8 17669 296 0 0
sp_supplier_invoice 5469 5469 0 0.00 10 93832 3127 0 0
sp_supplier_order 5471 5471 0 0.00 7 56800 1378 0 0
srv_aggregate_perf_data 99 99 0 0.00 2298 227407 17973 0 0
srv_fill_mon_cache_memory 73 73 0 0.00 736 135876 33055 0 0
srv_find_qd_qs_mism 1033204 1033204 0 0.00 0 14235 12 0 0
srv_make_invnt_saldo 477 477 0 0.00 58 190744 4343 0 0
srv_make_money_saldo 873 873 0 0.00 1 56260 427 0 0
srv_recalc_idx_stat 4 4 0 0.00 77501 448075 263321 0 0
t$perf-abend:sp_add_invoice_to_stock 114 114 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 15 15 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5105 5105 0 0.00 1 13767 966 0 0
t$perf-norm:sp_cancel_adding_invoice 403 403 0 0.00 2 7521 491 0 0
t$perf-norm:sp_cancel_client_order 1765 1765 0 0.00 0 3815 31 0 0
t$perf-norm:sp_cancel_customer_reserve 635 635 0 0.00 0 1695 33 0 0
t$perf-norm:sp_cancel_pay_from_customer 629 629 0 0.00 0 3774 24 0 0
t$perf-norm:sp_cancel_pay_to_supplier 424 424 0 0.00 0 3949 25 0 0
t$perf-norm:sp_cancel_supplier_invoice 377 377 0 0.00 0 760 36 0 0
t$perf-norm:sp_cancel_supplier_order 392 392 0 0.00 0 100396 7559 0 0
t$perf-norm:sp_cancel_write_off 1020 1020 0 0.00 0 5931 25 0 0
t$perf-norm:sp_client_order 19870 19870 0 0.00 0 10604 39 0 0
t$perf-norm:sp_customer_reserve 927 927 0 0.00 0 1631 17 0 0
t$perf-norm:sp_pay_from_customer 6891 6891 0 0.00 0 9100 19 0 0
t$perf-norm:sp_pay_to_supplier 5691 5691 0 0.00 0 4114 19 0 0
t$perf-norm:sp_reserve_write_off 9762 9762 0 0.00 0 6619 26 0 0
t$perf-norm:sp_supplier_invoice 5469 5469 0 0.00 0 5636 33 0 0
t$perf-norm:sp_supplier_order 5471 5471 0 0.00 0 8735 36 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 690 24 0 0
t$perf-norm:srv_fill_mon_cache_memory 73 73 0 0.00 0 99 5 0 0
t$perf-norm:srv_make_invnt_saldo 477 477 0 0.00 0 801 16 0 0
t$perf-norm:srv_make_money_saldo 874 874 0 0.00 0 1822 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 4 183 53 0 0
v_add_invoice_to_stock 10315 10087 228 2.21 0 6144 26 0 228
v_all_customers 36220 36220 0 0.00 0 101 4 0 0
v_all_suppliers 30084 30084 0 0.00 0 82 4 0 0
v_all_wares 39737 39737 0 0.00 0 66 4 0 0
v_cancel_adding_invoice 806 806 0 0.00 0 402 12 0 0
v_cancel_client_order 3530 3530 0 0.00 0 8591 13 0 0
v_cancel_customer_prepayment 1258 1258 0 0.00 0 3651 17 0 0
v_cancel_customer_reserve 1270 1270 0 0.00 0 862 12 0 0
v_cancel_payment_to_supplier 848 848 0 0.00 0 4834 18 0 0
v_cancel_supplier_invoice 784 754 30 3.82 0 2345 29 0 30
v_cancel_supplier_order 784 784 0 0.00 0 784 11 0 0
v_cancel_write_off 2040 2040 0 0.00 0 3730 27 0 0
v_max_id_clo_ord 5468 5468 0 0.00 0 420 5 0 0
v_max_id_clo_res 927 927 0 0.00 0 1744 11 0 0
v_max_id_ord_sup 5461 5461 0 0.00 0 3708 6 0 0
v_max_non_paid_invoice 1577 1577 0 0.00 0 821 8 0 0
v_max_non_paid_realizn 3683 3683 0 0.00 0 2752 8 0 0
v_min_id_clo_ord 5468 5468 0 0.00 0 2813 9 0 0
v_min_id_clo_res 927 927 0 0.00 0 7492 33 0 0
v_min_id_ord_sup 5461 5461 0 0.00 0 9313 14 0 0
v_min_non_paid_invoice 5690 5690 0 0.00 0 6564 22 0 0
v_min_non_paid_realizn 6891 6891 0 0.00 0 5419 17 0 0
v_our_firm 9932 9932 0 0.00 0 100 5 0 0
v_reserve_write_off 19520 19520 0 0.00 0 7148 11 0 0
x_lock_depdocs_on_canc_invoice 10954 10954 0 0.00 0 12173 128 0 0
x_lock_depdocs_on_canc_sup_ord 392 392 0 0.00 0 11419 383 0 0
x_make_qstorno_1000_1200 5471 5471 0 0.00 2 56787 1192 0 0
x_make_qstorno_1200_2000 5469 5469 0 0.00 5 89417 2911 0 0
x_make_qstorno_2100_3300 471452 471452 0 0.00 0 87350 237 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 08-05-2022 04:39:40 to 08-05-2022 04:39:44.

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
02:07:21 3 8192000000 8799425904 8810848256 5303120 0.064 2 1 2 0 5402544 63648 35776
02:08:22 28359 8192000000 9705018352 10000953344 783055248 8.724 100 90 180 1 814355120 8010352 23289520
02:09:54 16308 8192000000 9747986144 10053459968 821561872 9.114 100 91 181 1 856581344 12664944 22354528
02:11:12 14862 8192000000 9784779104 10101071872 849506016 9.395 100 81 162 0 890647072 21582064 19558992
02:12:31 9208 8192000000 9861442528 10194587648 911349808 10.011 100 79 158 0 972217968 43163552 17704608
02:13:43 4666 8192000000 9904280848 10247397376 952958240 10.420 100 80 157 0 1020292016 51240096 16093680
02:14:51 2411 8192000000 9980172160 10331303936 996638720 10.846 100 96 189 0 1092805696 76598224 19568752
02:15:55 1913 8192000000 9960562704 10324836352 1017616992 11.049 100 83 159 1 1083466864 50239280 15610592
02:17:00 1058 8192000000 10018027968 10386305024 1049382160 11.355 100 97 185 1 1136279984 68786272 18111552
02:18:03 1216 8192000000 10047711600 10423443456 1063434128 11.489 100 92 180 1 1162524144 81131072 17958944
02:19:06 1121 8192000000 10059583728 10441027584 1073371952 11.584 100 97 188 1 1182223456 90038544 18812960
02:20:20 950 8192000000 10033343248 10413408256 1071479824 11.566 100 98 191 2 1155325280 64966912 18878544
02:21:22 1181 8192000000 10078875120 10460872704 1082075104 11.667 100 89 172 0 1196435840 98216496 16144240
02:22:25 1438 8192000000 10051809520 10434891776 1081934032 11.666 100 97 190 3 1172305520 71657504 18713984
02:23:27 4021 8192000000 10042761872 10427289600 1087732544 11.721 100 83 157 0 1162374640 59546928 15095168
02:24:32 1786 8192000000 10070603392 10453725184 1090766272 11.750 100 95 181 1 1190423888 81565408 18092208
02:25:35 1183 8192000000 10069724784 10454904832 1096052624 11.800 100 96 183 2 1184665376 69523568 19089184
02:26:37 3300 8192000000 10048276448 10434154496 1091382384 11.756 100 91 173 2 1164036112 55027024 17626704
02:27:41 864 8192000000 10067161072 10456928256 1099926368 11.837 100 86 168 2 1188196016 70299040 17970608
02:28:45 1085 8192000000 10043609600 10433323008 1091275648 11.755 100 84 159 1 1169866896 63585200 15006048
02:29:46 883 8192000000 10057265616 10449313792 1085715888 11.702 100 89 176 0 1177039488 73288576 18035024
02:30:53 1518 8192000000 10034124224 10422833152 1076910544 11.618 100 79 153 1 1150868576 56881440 17076592
02:31:55 1315 8192000000 10000608208 10378469376 1040832480 11.273 99 85 161 1 1126551232 69980704 15738048
02:32:57 1039 8192000000 9992625856 10364747776 1033104736 11.198 99 93 177 2 1115443632 63047696 19291200
02:33:59 1303 8192000000 10011774640 10385879040 1043816080 11.301 100 97 177 0 1134028080 72351472 17860528
02:35:01 1109 8192000000 9959091008 10321330176 1000514608 10.884 100 94 177 1 1078875536 59934544 18426384
02:36:03 1111 8192000000 9976620896 10352144384 1035639152 11.223 100 86 164 2 1097902160 45002000 17261008
02:37:05 2243 8192000000 9992775648 10373947392 1043180864 11.295 100 94 174 0 1120712992 58850992 18681136
02:38:12 4287 8192000000 10004168784 10385010688 1068263824 11.535 100 77 134 1 1124748096 43148576 13335696
02:39:24 1029 8192000000 10032891440 10419187712 1079506448 11.643 100 78 145 0 1148374992 53786560 15081984
02:40:30 6831 8192000000 10059219712 10448519168 1093303952 11.774 100 76 144 1 1175394096 67666912 14423232
02:41:42 890 8192000000 10078989984 10468036608 1109630256 11.929 100 89 148 3 1197823712 73987984 14205472
02:42:45 1276 8192000000 10118625568 10505252864 1118267392 12.011 100 84 158 1 1234392384 99014272 17110720
02:43:49 2803 8192000000 10136458672 10539626496 1133269296 12.152 100 78 144 3 1253486128 105171456 15045376
02:44:58 1309 8192000000 10178206160 10575167488 1151732048 12.326 100 95 178 2 1297536176 125555168 20248960
02:46:03 1774 8192000000 10200013152 10595528704 1154209728 12.349 100 90 171 1 1313889376 141197664 18481984
02:47:08 1294 8192000000 10262846784 10660614144 1167770288 12.476 100 95 188 2 1374296896 185519680 21006928
02:48:14 1276 8192000000 10289824496 10686926848 1176071104 12.554 100 94 182 4 1393903408 197222336 20609968
02:49:19 1700 8192000000 10300096544 10705006592 1180450592 12.594 100 93 185 0 1413770656 212939424 20380640
02:50:25 1861 8192000000 10329818432 10743091200 1179603024 12.586 100 95 183 2 1438540624 237284720 21652880
02:51:32 728 8192000000 10356593648 10756902912 1182608336 12.615 100 92 173 1 1464053312 262145488 19299488
02:52:38 1565 8192000000 10406478752 10814701568 1190063680 12.684 100 93 182 2 1512456480 301494592 20898208
02:53:43 729 8192000000 10416737280 10841804800 1185285360 12.639 100 97 191 1 1525003248 316013040 23704848
02:54:46 1264 8192000000 10452361616 10868580352 1199824592 12.775 100 94 185 0 1560157200 338911200 21421408
02:55:51 980 8192000000 10483689296 10888425472 1195641952 12.736 99 96 191 2 1592455584 374784752 22028880
02:56:57 1056 8192000000 10534179536 10942382080 1189065712 12.675 99 98 194 0 1637069632 424206016 23797904
02:58:01 928 8192000000 10523084096 10983288832 1200103984 12.777 99 98 190 1 1628241840 405224016 22913840
02:59:04 1015 8192000000 10558384384 10998132736 1212461152 12.892 100 95 183 0 1662710592 428161504 22087936
03:00:08 829 8192000000 10560628208 10973716480 1209606736 12.865 100 98 192 0 1662510032 430056320 22846976
03:01:11 897 8192000000 10593728704 11023482880 1204579440 12.819 100 100 194 0 1696317296 467539984 24197872
03:02:13 2129 8192000000 10601460688 11068313600 1200095360 12.777 100 75 149 0 1705745872 487143408 18507104
03:03:17 1814 8192000000 10603508976 11044024320 1193225680 12.713 100 75 147 3 1699793760 488789568 17778512
03:04:21 3998 8192000000 10611117360 11063115776 1180925152 12.599 100 73 142 1 1715641600 515990320 18726128
03:05:29 8905 8192000000 10602819024 11064016896 1177650848 12.568 100 53 102 0 1699233344 507404176 14178320
03:06:39 12123 8192000000 10584476352 11085762560 1175606240 12.549 100 45 90 0 1698306192 509937136 12762816
03:07:54 25073 8192000000 10517417632 11057303552 1155903568 12.365 99 34 68 0 1663420736 497525712 9991456
03:09:21 9604 8192000000 10431413568 10949046272 1169786256 12.495 100 34 66 0 1555444528 376945744 8712528
03:10:37 29646 8192000000 10371949872 10842054656 1162873600 12.430 100 20 40 0 1485134400 316352816 5907984
03:12:16 44616 8192000000 10117738160 10648776704 1149012896 12.300 99 13 26 0 1277310352 125572688 2724768
03:14:02 61002 8192000000 10124431216 10574295040 1137999200 12.197 98 19 37 1 1182470944 40001232 4470512
03:16:04 125798 8192000000 10142401744 10583629824 1111237728 11.944 97 37 74 0 1215850304 96437472 8175104
03:19:14 135876 8192000000 10078137792 10545045504 1090489648 11.747 96 18 34 0 1140772160 46888016 3394496
03:22:34 69056 8192000000 10048256224 10486628352 1107883744 11.912 98 19 38 0 1132693248 21404160 3405344
03:24:46 20871 8192000000 10112932416 10547552256 1122280832 12.049 100 41 80 1 1199292048 68657936 8353280
03:26:08 24614 8192000000 10145078080 10571530240 1127100336 12.094 100 68 122 4 1232700000 93341872 12257792
03:27:36 44883 8192000000 10091936336 10558222336 1123266304 12.058 99 12 24 0 1183201152 57568112 2366736
03:30:12 23762 8192000000 10090410128 10580848640 1134848848 12.167 100 13 26 0 1211945024 73969248 3126928
03:31:36 60303 8192000000 10059293152 10541666304 1122968048 12.055 99 16 32 0 1145446816 18604160 3874608
03:33:48 18198 8192000000 10068656336 10520743936 1131303184 12.134 100 46 82 1 1171355536 32011664 8040688
03:35:16 119705 8192000000 10030611792 10511601664 1063071472 11.486 95 17 34 0 1113044640 46984624 2988544
03:38:16 85377 8192000000 10094686800 10561806336 1097526832 11.814 97 11 22 0 1171572064 71856912 2188320
03:40:42 30249 8192000000 10113753872 10553561088 1143594384 12.249 100 37 74 2 1219128368 67440848 8093136
03:42:13 56159 8192000000 10174834880 10642776064 1142948864 12.243 99 33 66 1 1288351168 138029440 7372864
03:44:17 60320 8192000000 10158092064 10662580224 1141562608 12.230 99 31 60 1 1296485264 147627328 7295328
03:46:22 35634 8192000000 10105213104 10572582912 1134486832 12.164 99 21 43 1 1213053376 74137360 4429184
03:48:01 43793 8192000000 10108764448 10583539712 1134269472 12.162 99 31 61 0 1206858480 66096848 6492160
03:49:45 49619 8192000000 10105672464 10541096960 1105978848 11.894 97 34 68 0 1184003920 70902336 7122736
03:51:36 71626 8192000000 10077742512 10529185792 1120005168 12.027 99 27 54 1 1174349216 48929584 5414464
03:54:00 22067 8192000000 10131420960 10563244032 1109669504 11.929 98 54 106 0 1205910816 85097888 11143424
03:55:24 64024 8192000000 10069966896 10558509056 1113637728 11.967 99 18 35 0 1206218560 88564672 4016160
03:58:09 50128 8192000000 10074684224 10535137280 1121832816 12.044 100 11 22 0 1195808368 71322912 2652640
04:00:00 55981 8192000000 10064569792 10543575040 1108977808 11.923 99 6 12 0 1168415664 58100464 1337392
04:02:01 42571 8192000000 10083352400 10535284736 1079618976 11.644 95 31 62 2 1160773264 74617120 6537168
04:03:45 30507 8192000000 10095712720 10528403456 1116047312 11.990 99 47 94 1 1171370096 45382992 9939792
04:05:18 39170 8192000000 10111260896 10583752704 1121201840 12.038 99 27 54 1 1205576880 78179200 6195840
04:06:58 22198 8192000000 10081129120 10518880256 1116911776 11.998 99 44 82 1 1183840928 58723952 8205200
04:08:22 48427 8192000000 10159253120 10648637440 1121243120 12.039 98 35 70 0 1249754976 121186048 7325808
04:10:12 58822 8192000000 10038221584 10476052480 1077131392 11.620 95 19 36 2 1121500784 41086336 3283056
04:12:12 5351 8192000000 10091625824 10511089664 1120038768 12.027 100 64 116 3 1175242080 44099120 11104192
04:13:18 84568 8192000000 10048717392 10478157824 1097778000 11.817 98 46 89 1 1166053008 59643056 8631952
04:15:47 17192 8192000000 10129404928 10559819776 1129329472 12.115 100 59 116 0 1210279296 68621440 12328384
04:18:18 95638 8192000000 10102015904 10607636480 1125250288 12.077 99 10 20 0 1209008240 81407632 2350320
04:20:54 86520 8192000000 10049749264 10508312576 1120339888 12.030 99 8 16 0 1145834288 23912064 1582336
04:23:24 16314 8192000000 10070326560 10495086592 1119747696 12.025 100 44 83 0 1170127984 41570080 8810208
04:25:01 38783 8192000000 10089805584 10527850496 1118023904 12.008 99 30 60 2 1167142704 42706400 6412400
04:26:42 24899 8192000000 10116544480 10555138048 1125138368 12.076 100 33 66 3 1216080448 84476352 6465728
04:28:12 67705 8192000000 10083526496 10528206848 1110087280 11.933 98 24 48 2 1183761776 68772464 4902032
04:30:25 50766 8192000000 10079353520 10570936320 1102331440 11.860 98 27 53 1 1178147920 70745088 5071392
04:32:23 20144 8192000000 10096263888 10533478400 1113879328 11.969 99 42 84 2 1182069792 60032736 8157728
04:33:48 79547 8192000000 10070924880 10540294144 1115832304 11.988 99 31 58 2 1159043232 36741872 6469056
04:36:08 80286 8192000000 9685279776 10101895168 769384384 8.585 68 4 8 0 805583872 35397536 801952

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 08-05-2022 04:39:46 to 08-05-2022 04:40:02.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 114 v_add_invoice_to_stock 335544517
except 15 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 08-05-2022 04:40:03 to 08-05-2022 04:40:05.

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          218400
MON$OLDEST_ACTIVE               218738
MON$OLDEST_SNAPSHOT             218738
MON$NEXT_TRANSACTION            218738
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                       2083040
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             773
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2763 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2763 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2763 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2763 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 ---
:77: 
:78: mscs-test-161.budzdorov.ru    Sun May  8 04:37:40 2022
:79:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:80: 
:81: 
:82: mscs-test-161.budzdorov.ru    Sun May  8 04:37:48 2022
:83:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:84: 
:85: 
:86: mscs-test-161.budzdorov.ru    Sun May  8 04:37:57 2022
:87:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:88: 
--- end of diff output ---

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

08.05.22 04:40:06. 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-05-08 04:40:08 No severe PSQL-related problems occured
08.05.22 04:40:10. End of report.