Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 21.06.2022 13:37
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 16045580 kB
MemAvailable 31212304 kB
Buffers 0 kB
Cached 15637060 kB
SwapCached 2484 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-21 13:37:16
DTS_END                         2022-06-21 13:37:16.0220
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/59938
TRN_ID                          225546
ATT_ID                          668
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: 9175.27 24391 1101032
customer order: creation 179.54 457 21545
customer order: refuse 15.58 360 1869
order to supplier: creation 46.74 1351 5609
order to supplier: removal 3.21 171650 385
invoice (draft): creation 46.83 2924 5620
invoice (draft): removal 51.48 977 6177
invoice accept: apply 41.97 25798 5036
invoice accept: cancel 46.96 1175 5635
customer reserve: creation 3870.45 248 464454
customer reserve: removal 4566.45 95 547974
realization accept: apply 86.92 279 10430
realization accept: cancel 83.77 155 10052
payment from customer: creation 62.15 168 7458
payment from customer: removal 5.88 127 705
payment to supplier: creation 51.41 191 6169
payment to supplier: removal 3.67 115 440
service: total inventory turnovers 4.07 3933 488
service: total monetary turnovers 7.23 417 867
service: aggregate perf. data 0.95 16161 114
service: refresh index statistics 0.04 261248 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 21-06-2022 13:38:16 to 21-06-2022 13:38:18.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5793
TEST_TIME 2 4337
TEST_TIME 3 4186
TEST_TIME 4 5113
TEST_TIME 5 5724
TEST_TIME 6 6145
TEST_TIME 7 6366
TEST_TIME 8 6227
TEST_TIME 9 6388
TEST_TIME 10 6468
TEST_TIME 11 6503
TEST_TIME 12 6758
TEST_TIME 13 6863
TEST_TIME 14 7251
TEST_TIME 15 7344
TEST_TIME 16 7439
TEST_TIME 17 7600
TEST_TIME 18 7686
TEST_TIME 19 8442
TEST_TIME 20 8446
TEST_TIME 21 8382
TEST_TIME 22 8245
TEST_TIME 23 8224
TEST_TIME 24 8465
TEST_TIME 25 8706
TEST_TIME 26 8867
TEST_TIME 27 8931
TEST_TIME 28 9326
TEST_TIME 29 9824
TEST_TIME 30 10290
TEST_TIME 31 10505
TEST_TIME 32 11014
TEST_TIME 33 11129
TEST_TIME 34 11285
TEST_TIME 35 11129
TEST_TIME 36 11021
TEST_TIME 37 11004
TEST_TIME 38 10868
TEST_TIME 39 10813
TEST_TIME 40 10769
TEST_TIME 41 10566
TEST_TIME 42 10488
TEST_TIME 43 10345
TEST_TIME 44 10230
TEST_TIME 45 10208
TEST_TIME 46 10082
TEST_TIME 47 10103
TEST_TIME 48 10119
TEST_TIME 49 10077
TEST_TIME 50 10010
TEST_TIME 51 9876
TEST_TIME 52 9958
TEST_TIME 53 9967
TEST_TIME 54 9993
TEST_TIME 55 10052
TEST_TIME 56 10092
TEST_TIME 57 10036
TEST_TIME 58 10075
TEST_TIME 59 9965
TEST_TIME 60 10043
TEST_TIME 61 10119
TEST_TIME 62 10200
TEST_TIME 63 10341
TEST_TIME 64 10221
TEST_TIME 65 10127
TEST_TIME 66 10022
TEST_TIME 67 9968
TEST_TIME 68 9910
TEST_TIME 69 9816
TEST_TIME 70 9774
TEST_TIME 71 9777
TEST_TIME 72 9743
TEST_TIME 73 9896
TEST_TIME 74 9883
TEST_TIME 75 9920
TEST_TIME 76 9904
TEST_TIME 77 9840
TEST_TIME 78 9759
TEST_TIME 79 9843
TEST_TIME 80 9840
TEST_TIME 81 9826
TEST_TIME 82 9773
TEST_TIME 83 9711
TEST_TIME 84 9625
TEST_TIME 85 9700
TEST_TIME 86 9680
TEST_TIME 87 9592
TEST_TIME 88 9531
TEST_TIME 89 9459
TEST_TIME 90 9416
TEST_TIME 91 9427
TEST_TIME 92 9407
TEST_TIME 93 9458
TEST_TIME 94 9421
TEST_TIME 95 9409
TEST_TIME 96 9464
TEST_TIME 97 9473
TEST_TIME 98 9494
TEST_TIME 99 9520
TEST_TIME 100 9540
TEST_TIME 101 9494
TEST_TIME 102 9517
TEST_TIME 103 9565
TEST_TIME 104 9525
TEST_TIME 105 9461
TEST_TIME 106 9400
TEST_TIME 107 9386
TEST_TIME 108 9358
TEST_TIME 109 9348
TEST_TIME 110 9301
TEST_TIME 111 9309
TEST_TIME 112 9265
TEST_TIME 113 9265
TEST_TIME 114 9257
TEST_TIME 115 9218
TEST_TIME 116 9257
TEST_TIME 117 9222
TEST_TIME 118 9211
TEST_TIME 119 9249
TEST_TIME 120 9203

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 21-06-2022 13:38:19 to 21-06-2022 13:38:23.

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 1530582 1530582 0 0.00 0 20737 14 0 0
doc_list_biud 1530577 1530577 0 0.00 0 41115 40 0 0
sp_add_invoice_to_stock 5129 5036 93 1.81 38 228737 25799 0 93
sp_cancel_adding_invoice 5635 5635 0 0.00 6 84370 1176 0 0
sp_cancel_client_order 1869 1869 0 0.00 5 13000 361 0 0
sp_cancel_customer_reserve 547974 547974 0 0.00 1 41134 95 0 0
sp_cancel_pay_from_customer 705 705 0 0.00 4 6108 128 0 0
sp_cancel_pay_to_supplier 440 440 0 0.00 4 3571 116 0 0
sp_cancel_supplier_invoice 6182 6177 5 0.08 3 17758 978 0 5
sp_cancel_supplier_order 385 385 0 0.00 16 1301966 171650 0 0
sp_cancel_write_off 10052 10052 0 0.00 2 12662 155 0 0
sp_client_order 21545 21545 0 0.00 6 26123 457 0 0
sp_customer_reserve 464454 464454 0 0.00 1 91008 249 0 0
sp_fill_shopping_cart 32766 32766 0 0.00 2 17063 80 0 0
sp_fill_shopping_cart_clo_res 464431 464431 0 0.00 0 15346 19 0 0
sp_get_clo_for_invoice 5005 5005 0 0.00 0 39023 291 0 0
sp_kill_cost_storno 16832 16832 0 0.00 0 7872 69 0 0
sp_kill_qstorno_ret_qs2qd 1112562 1112562 0 0.00 0 41054 42 0 0
sp_kill_qty_storno 587527 587527 0 0.00 0 41114 95 0 0
sp_lock_selected_doc 21376 21376 0 0.00 0 7564 9 0 0
sp_make_cost_storno 29061 29061 0 0.00 0 14138 75 0 0
sp_multiply_rows_for_pdistr 29061 29061 0 0.00 0 17956 39 0 0
sp_multiply_rows_for_qdistr 21545 21545 0 0.00 0 15502 179 0 0
sp_pay_from_customer 7458 7458 0 0.00 5 8725 168 0 0
sp_pay_to_supplier 6169 6169 0 0.00 6 17975 192 0 0
sp_payment_common 13625 13625 0 0.00 2 6711 42 0 0
sp_qd_handle_on_cancel_clo 1869 1869 0 0.00 0 12989 288 0 0
sp_qd_handle_on_invoice_upd_sts 10640 10640 0 0.00 0 25047 467 0 0
sp_qd_handle_on_reserve_upd_sts 10430 10430 0 0.00 0 18314 64 0 0
sp_reserve_write_off 10430 10430 0 0.00 8 27311 279 0 0
sp_supplier_invoice 5620 5620 0 0.00 9 71127 2924 0 0
sp_supplier_order 5609 5609 0 0.00 8 43803 1352 0 0
srv_aggregate_perf_data 114 114 0 0.00 2642 105952 16161 0 0
srv_fill_mon_cache_memory 71 71 0 0.00 980 128523 34945 0 0
srv_find_qd_qs_mism 1051680 1051680 0 0.00 0 13351 11 0 0
srv_make_invnt_saldo 488 488 0 0.00 82 97538 3933 0 0
srv_make_money_saldo 867 867 0 0.00 1 31076 418 0 0
srv_recalc_idx_stat 5 5 0 0.00 122709 494231 261249 0 0
t$perf-abend:sp_add_invoice_to_stock 93 93 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5046 5046 0 0.00 1 15213 860 0 0
t$perf-norm:sp_cancel_adding_invoice 437 437 0 0.00 1 6532 436 0 0
t$perf-norm:sp_cancel_client_order 1869 1869 0 0.00 0 3917 28 0 0
t$perf-norm:sp_cancel_customer_reserve 697 697 0 0.00 0 1104 22 0 0
t$perf-norm:sp_cancel_pay_from_customer 705 705 0 0.00 0 2649 20 0 0
t$perf-norm:sp_cancel_pay_to_supplier 440 440 0 0.00 0 903 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 383 383 0 0.00 0 4114 42 0 0
t$perf-norm:sp_cancel_supplier_order 385 385 0 0.00 0 93195 8081 0 0
t$perf-norm:sp_cancel_write_off 1027 1027 0 0.00 0 4626 22 0 0
t$perf-norm:sp_client_order 21545 21545 0 0.00 0 4778 32 0 0
t$perf-norm:sp_customer_reserve 1052 1052 0 0.00 0 2919 19 0 0
t$perf-norm:sp_pay_from_customer 7458 7458 0 0.00 0 10799 21 0 0
t$perf-norm:sp_pay_to_supplier 6169 6169 0 0.00 0 3156 20 0 0
t$perf-norm:sp_reserve_write_off 10430 10430 0 0.00 0 7693 22 0 0
t$perf-norm:sp_supplier_invoice 5620 5620 0 0.00 0 5581 29 0 0
t$perf-norm:sp_supplier_order 5609 5609 0 0.00 0 5563 31 0 0
t$perf-norm:srv_aggregate_perf_data 114 114 0 0.00 0 248 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 71 71 0 0.00 0 355 9 0 0
t$perf-norm:srv_make_invnt_saldo 488 488 0 0.00 0 4280 26 0 0
t$perf-norm:srv_make_money_saldo 867 867 0 0.00 0 523 6 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 10 183 60 0 0
v_add_invoice_to_stock 10192 10006 186 1.82 0 5465 25 0 186
v_all_customers 39662 39662 0 0.00 0 131 3 0 0
v_all_suppliers 31922 31922 0 0.00 0 76 4 0 0
v_all_wares 43082 43082 0 0.00 0 129 4 0 0
v_cancel_adding_invoice 874 874 0 0.00 0 2065 19 0 0
v_cancel_client_order 3738 3738 0 0.00 0 1940 10 0 0
v_cancel_customer_prepayment 1410 1410 0 0.00 0 2630 15 0 0
v_cancel_customer_reserve 1394 1394 0 0.00 0 2845 17 0 0
v_cancel_payment_to_supplier 880 880 0 0.00 0 3495 18 0 0
v_cancel_supplier_invoice 776 766 10 1.28 0 967 24 0 10
v_cancel_supplier_order 770 770 0 0.00 0 1476 13 0 0
v_cancel_write_off 2054 2054 0 0.00 0 1554 21 0 0
v_max_id_clo_ord 5607 5607 0 0.00 0 848 5 0 0
v_max_id_clo_res 1052 1052 0 0.00 0 2121 14 0 0
v_max_id_ord_sup 5616 5616 0 0.00 0 3901 7 0 0
v_max_non_paid_invoice 1430 1430 0 0.00 0 262 7 0 0
v_max_non_paid_realizn 3785 3785 0 0.00 0 755 7 0 0
v_min_id_clo_ord 5607 5607 0 0.00 0 2461 8 0 0
v_min_id_clo_res 1052 1052 0 0.00 0 4851 17 0 0
v_min_id_ord_sup 5616 5616 0 0.00 0 5549 12 0 0
v_min_non_paid_invoice 6168 6168 0 0.00 0 4971 16 0 0
v_min_non_paid_realizn 7457 7457 0 0.00 0 6691 15 0 0
v_our_firm 10764 10764 0 0.00 0 93 4 0 0
v_reserve_write_off 20860 20860 0 0.00 0 5941 13 0 0
x_lock_depdocs_on_canc_invoice 11429 11429 0 0.00 0 22970 125 0 0
x_lock_depdocs_on_canc_sup_ord 385 385 0 0.00 0 5104 352 0 0
x_make_qstorno_1000_1200 5609 5609 0 0.00 3 43791 1191 0 0
x_make_qstorno_1200_2000 5620 5620 0 0.00 3 67309 2699 0 0
x_make_qstorno_2100_3300 464453 464453 0 0.00 0 91002 218 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 21-06-2022 13:38:24 to 21-06-2022 13:38:28.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
11:07:19 2 8192000000 8799835680 8811368448 5260816 0.064 2 1 2 0 5360240 63648 35776
11:08:21 40364 8192000000 9733820480 10034049024 804201680 8.939 100 87 173 0 833162256 7989936 20970640
11:10:07 19592 8192000000 9777336480 10087555072 849454384 9.395 100 90 180 0 883551280 12869344 21227552
11:11:39 16117 8192000000 9820665024 10140626944 880299760 9.703 100 86 172 0 929932992 29566112 20067120
11:12:59 11088 8192000000 9878846320 10215866368 923957200 10.135 100 83 165 3 987738880 45519584 18262096
11:14:13 4664 8192000000 9919340336 10266099712 960133920 10.490 100 79 157 1 1034797056 57932928 16730208
11:15:22 2971 8192000000 9955783328 10310471680 1007314352 10.949 100 90 178 2 1074404976 49138208 17952416
11:16:28 2154 8192000000 10023689984 10388144128 1030436688 11.173 100 93 186 0 1139180544 90305120 18438736
11:17:31 2590 8192000000 10008555696 10381017088 1046412640 11.326 100 86 164 1 1129601792 66918960 16270192
11:18:36 1164 8192000000 10046337184 10420793344 1064682080 11.501 100 94 184 1 1167037200 84021168 18333952
11:19:38 998 8192000000 10053930608 10431885312 1074647744 11.596 100 98 191 2 1175659824 82268592 18743488
11:20:41 1058 8192000000 10066335776 10448666624 1077012944 11.619 100 86 166 1 1187689040 95250848 15425248
11:21:43 5185 8192000000 10054832480 10436145152 1077046096 11.619 100 84 165 2 1174744432 81900128 15798208
11:22:52 5623 8192000000 10039446496 10423726080 1081691328 11.664 100 85 157 0 1160249680 61721664 16836688
11:23:58 1052 8192000000 10045429200 10428944384 1087314928 11.717 100 93 181 2 1161637776 55403712 18919136
11:25:00 5233 8192000000 10030355776 10424659968 1090338352 11.746 100 62 117 0 1151879248 49169520 12371376
11:26:06 5114 8192000000 10048549936 10443403264 1094852208 11.789 100 68 132 0 1166092704 57817104 13423392
11:27:12 1040 8192000000 10057826480 10446499840 1099312448 11.831 100 91 174 0 1179374384 61718640 18343296
11:28:15 1143 8192000000 10071472144 10459009024 1096210080 11.802 100 84 163 1 1192445584 79923232 16312272
11:29:18 1091 8192000000 10068976832 10454020096 1091351984 11.756 100 97 179 1 1189657920 79311216 18994720
11:30:20 2435 8192000000 10058587568 10448953344 1088374976 11.727 100 70 134 0 1182939328 79828576 14735776
11:31:25 1105 8192000000 10020535808 10398879744 1059887776 11.455 99 97 181 2 1143620848 65328320 18404752
11:32:27 957 8192000000 9995262544 10367692800 1041329520 11.277 100 94 179 1 1118774464 59820128 17624816
11:33:29 1409 8192000000 9986072416 10363478016 1028341392 11.152 100 76 148 0 1100553344 57907552 14304400
11:34:32 1216 8192000000 9987813840 10363199488 1033846624 11.205 99 83 153 0 1104240528 55630032 14763872
11:35:34 1663 8192000000 10007328688 10386804736 1047144000 11.333 100 86 170 1 1130009680 65198752 17666928
11:36:37 1166 8192000000 10020899904 10395561984 1046825280 11.330 100 95 184 1 1142925040 77381536 18718224
11:37:48 18735 8192000000 9981694784 10365571072 1046631568 11.328 100 55 101 1 1112405872 56185552 9588752
11:39:11 6246 8192000000 10029864720 10411941888 1074899920 11.599 100 69 130 1 1143475152 56433296 12141936
11:40:30 11936 8192000000 10058367904 10446888960 1087937680 11.723 100 61 117 0 1182726336 83039040 11749616
11:41:45 1559 8192000000 10122192304 10514481152 1107657680 11.910 100 75 140 4 1239254656 116020864 15576112
11:42:56 1020 8192000000 10132946000 10527621120 1117729040 12.006 100 90 178 1 1247785712 110025648 20031024
11:44:01 3914 8192000000 10167049952 10568187904 1124851376 12.073 100 80 160 1 1282972016 141386352 16734288
11:45:09 2561 8192000000 10160198112 10560520192 1133022032 12.150 100 66 125 1 1273660240 126739648 13898560
11:46:15 1249 8192000000 10234843136 10626863104 1149126368 12.301 100 94 179 1 1347784528 178066432 20591728
11:47:18 1148 8192000000 10245757648 10646499328 1161376704 12.416 100 96 180 3 1363800896 181213744 21210448
11:48:21 1117 8192000000 10298532752 10699726848 1156316000 12.369 100 91 175 0 1416383568 238906576 21160992
11:49:26 1281 8192000000 10328081872 10731044864 1172707248 12.522 100 92 179 1 1436159264 241521664 21930352
11:50:31 1055 8192000000 10355009632 10789441536 1181752224 12.607 100 97 188 1 1464158624 260986672 21419728
11:51:34 1472 8192000000 10405967584 10825388032 1193476512 12.716 100 97 187 0 1514031024 298948496 21606016
11:52:39 1074 8192000000 10421879344 10835984384 1188547344 12.670 100 83 156 3 1526321664 319462496 18311824
11:53:46 2514 8192000000 10417158352 10847428608 1184872880 12.636 100 70 140 0 1525252432 323131152 17248400
11:54:53 2042 8192000000 10448646560 10885263360 1193879232 12.719 100 79 154 1 1558306816 345379504 19048080
11:56:00 1410 8192000000 10414800448 10896949248 1198017136 12.758 100 95 187 0 1526149072 305906176 22225760
11:57:04 2804 8192000000 10428029024 10850689024 1206818432 12.840 100 86 166 0 1543481696 317498720 19164544
11:58:12 975 8192000000 10458448480 10875330560 1204560016 12.819 100 70 134 1 1566379024 346147760 15671248
11:59:20 7383 8192000000 10500502784 10925326336 1194415728 12.724 100 60 114 3 1606178096 396051760 15710608
12:00:30 3042 8192000000 10520080896 10964086784 1193350640 12.715 100 68 133 0 1636896928 426986784 16559504
12:01:37 1615 8192000000 10505977760 10938187776 1183443920 12.622 100 58 111 2 1610311328 411792432 15074976
12:02:48 15884 8192000000 10495442816 10951000064 1148602640 12.296 99 44 87 1 1603582944 442661584 12318720
12:04:11 15182 8192000000 10460264704 10925629440 1150039952 12.310 100 42 80 0 1570218800 408996848 11182000
12:05:35 9933 8192000000 10417293248 10899238912 1117712752 12.005 100 43 83 0 1515210384 386777456 10720176
12:06:49 11969 8192000000 10248581600 10768584704 1104251760 11.878 99 34 68 1 1358175264 245819840 8103664
12:08:05 18948 8192000000 10192779760 10651291648 1125922240 12.083 99 30 61 1 1289962848 156928416 7112192
12:09:37 19459 8192000000 10120557680 10621759488 1120666096 12.033 100 13 26 1 1243742528 120139904 2936528
12:10:57 82334 8192000000 10082492896 10595131392 1108745888 11.921 97 8 16 1 1197308816 87221552 1341376
12:13:26 18534 8192000000 10116944256 10562605056 1126717872 12.090 99 47 85 2 1202143872 66953776 8472224
12:14:46 74436 8192000000 10066841840 10519199744 1105963408 11.894 97 16 32 1 1144568256 35029216 3575632
12:17:01 128523 8192000000 10083155696 10541309952 1114364464 11.974 98 16 28 2 1178785920 61143808 3277648
12:20:10 80951 8192000000 10065050512 10489626624 1102612016 11.862 98 61 119 2 1151160112 34870720 13677376
12:22:32 34204 8192000000 10094027888 10531057664 1119140128 12.019 99 36 72 2 1199826032 73363392 7322512
12:24:08 33820 8192000000 10164481376 10632859648 1138335136 12.200 100 48 92 0 1280787760 131740048 10712576
12:25:55 31103 8192000000 10099668768 10603888640 1136338752 12.181 100 8 16 0 1200447584 62323392 1785440
12:27:31 78579 8192000000 10147171408 10597765120 1124344976 12.068 98 21 42 0 1219941792 91030128 4566688
12:30:15 61849 8192000000 10117597072 10583293952 1121205328 12.038 98 20 40 0 1209415312 83741712 4468272
12:32:19 44384 8192000000 10125068944 10592968704 1124464208 12.069 98 31 62 0 1252208112 120944656 6799248
12:34:05 48089 8192000000 10150582752 10604892160 1132260448 12.143 99 22 44 0 1222397312 85685408 4451456
12:36:05 25125 8192000000 10181463600 10609606656 1134292192 12.162 99 46 90 0 1256622704 112679664 9650848
12:37:30 47341 8192000000 10184830640 10646298624 1116314032 11.992 98 23 46 0 1246560432 124443008 5803392
12:39:19 56324 8192000000 10148822992 10612940800 1150674704 12.316 100 19 38 0 1237048800 82481792 3892304
12:41:15 86597 8192000000 10080889808 10517508096 1122558096 12.051 98 42 82 1 1169091552 37426416 9107040
12:44:11 64780 8192000000 10107832400 10562498560 1095034160 11.790 96 31 60 0 1176814672 75686096 6094416
12:46:59 73676 8192000000 10161261088 10675814400 1121500688 12.041 99 16 32 0 1271187856 145599712 4087456
12:49:19 82679 8192000000 10081244992 10595667968 1110866176 11.941 98 18 36 0 1187088480 72140544 4081760
12:51:50 43735 8192000000 10087505392 10583814144 1121793360 12.044 99 27 54 0 1192744256 65643296 5307600
12:53:39 16468 8192000000 10081054464 10545979392 1133138032 12.151 100 39 78 0 1196583984 55313584 8132368
12:54:56 40962 8192000000 10113683968 10542579712 1107158576 11.906 98 37 74 1 1176054928 61340992 7555360
12:56:43 9777 8192000000 10089735456 10508386304 1116926784 11.998 99 52 104 0 1184751456 57623344 10201328
12:57:53 23406 8192000000 10105779168 10550796288 1121251888 12.039 99 42 82 2 1190353520 60928048 8173584
12:59:25 99740 8192000000 10143428688 10602815488 1094148896 11.782 97 33 66 0 1206981424 106191696 6640832
13:02:30 62355 8192000000 10049433968 10543276032 1124203488 12.067 100 15 28 1 1187844512 60556432 3084592
13:04:33 58974 8192000000 10047038272 10512289792 1100566112 11.843 98 12 24 0 1140075120 37270928 2238080
13:06:32 41062 8192000000 10095190800 10528309248 1122998544 12.055 100 13 26 1 1184722864 59266896 2457424
13:08:14 22723 8192000000 10065373776 10518720512 1083399824 11.680 97 34 66 1 1162197776 70397664 8400288
13:09:51 32849 8192000000 10130081024 10565885952 1119755328 12.025 99 30 60 1 1221260096 94878688 6626080
13:11:24 59295 8192000000 10136281888 10568966144 1141347728 12.228 100 29 58 0 1220875248 73522928 6004592
13:13:31 68116 8192000000 10115886960 10602737664 1144555584 12.258 100 20 40 2 1212767632 63848608 4363440
13:15:42 82503 8192000000 10118706080 10578350080 1148310368 12.294 100 36 72 0 1229789712 74627632 6851712
13:18:07 27512 8192000000 10086669616 10514722816 1123253152 12.058 99 42 84 0 1173418400 41668256 8496992
13:19:36 76429 8192000000 10106876352 10607005696 1125459008 12.079 99 25 50 2 1217436016 86908720 5068288
13:21:52 108513 8192000000 10090973280 10533572608 1133526512 12.155 99 27 52 1 1161619392 22180208 5912672
13:24:56 52455 8192000000 10124892752 10608812032 1140312752 12.218 99 14 28 1 1206697488 62624064 3760672
13:27:41 59217 8192000000 10097466640 10553024512 1128581712 12.108 98 15 30 1 1182917056 50464336 3871008
13:29:43 64713 8192000000 10074159456 10560843776 1116930432 11.998 97 12 24 0 1157063152 37566016 2566704
13:31:54 25940 8192000000 10079598896 10516660224 1125116576 12.075 99 40 77 1 1159143280 26195664 7831040
13:33:20 35964 8192000000 10059880704 10499174400 1113066832 11.961 98 26 52 1 1174197856 56015600 5115424
13:34:56 13997 8192000000 10148682368 10572730368 1133615616 12.155 100 57 114 0 1229305376 84179536 11510224
13:36:12 33352 8192000000 10082937440 10518454272 1109293776 11.926 99 19 36 1 1185182544 71810784 4077984

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 21-06-2022 13:38:30 to 21-06-2022 13:38:46.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 93 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 21-06-2022 13:38:47 to 21-06-2022 13:38:49.

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          223444
MON$OLDEST_ACTIVE               227166
MON$OLDEST_SNAPSHOT             227166
MON$NEXT_TRANSACTION            227166
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                       2049760
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             782
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2782 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2782 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2782 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2782 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 ---
:40: 
:41: mscs-test-161.budzdorov.ru    Tue Jun 21 13:37:23 2022
:42:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:43: 
:44: 
:45: mscs-test-161.budzdorov.ru    Tue Jun 21 13:37:24 2022
:46:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:47: 
:48: 
:49: mscs-test-161.budzdorov.ru    Tue Jun 21 13:37:25 2022
:50:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:51: 
--- end of diff output ---

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

21.06.22 13:38:50. 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-21 13:38:52 No severe PSQL-related problems occured
21.06.22 13:38:53. End of report.