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

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2023-02-01 07:37:24
DTS_END                         2023-02-01 07:37:24.7560
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(134:18) ==> SP_CANCEL_SUPPLIER_INVOICE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/46578
TRN_ID                          223244
ATT_ID                          655
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: 9080.09 26445 1089611
customer order: creation 172.13 463 20655
customer order: refuse 15.32 372 1838
order to supplier: creation 45.87 1424 5504
order to supplier: removal 3.55 160789 426
invoice (draft): creation 45.48 3129 5458
invoice (draft): removal 51.18 1027 6142
invoice accept: apply 40.94 25437 4913
invoice accept: cancel 46.68 1150 5602
customer reserve: creation 3776.45 246 453174
customer reserve: removal 4583.40 98 550008
realization accept: apply 83.02 243 9962
realization accept: cancel 85.97 154 10316
payment from customer: creation 59.51 153 7141
payment from customer: removal 5.61 98 673
payment to supplier: creation 49.02 172 5882
payment to supplier: removal 3.75 136 450
service: total inventory turnovers 4.20 3473 504
service: total monetary turnovers 7.03 315 843
service: aggregate perf. data 0.97 14077 116
service: refresh index statistics 0.03 315936 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 01-02-2023 07:38:09 to 01-02-2023 07:38:11.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7386
TEST_TIME 2 5388
TEST_TIME 3 4914
TEST_TIME 4 5158
TEST_TIME 5 4909
TEST_TIME 6 4696
TEST_TIME 7 4717
TEST_TIME 8 5011
TEST_TIME 9 5095
TEST_TIME 10 5260
TEST_TIME 11 5773
TEST_TIME 12 5822
TEST_TIME 13 5902
TEST_TIME 14 6261
TEST_TIME 15 6329
TEST_TIME 16 6548
TEST_TIME 17 6524
TEST_TIME 18 6637
TEST_TIME 19 6665
TEST_TIME 20 6958
TEST_TIME 21 7278
TEST_TIME 22 7356
TEST_TIME 23 7331
TEST_TIME 24 7720
TEST_TIME 25 8162
TEST_TIME 26 8505
TEST_TIME 27 9304
TEST_TIME 28 10216
TEST_TIME 29 10369
TEST_TIME 30 10737
TEST_TIME 31 10658
TEST_TIME 32 10526
TEST_TIME 33 10382
TEST_TIME 34 10373
TEST_TIME 35 10302
TEST_TIME 36 10201
TEST_TIME 37 10130
TEST_TIME 38 10035
TEST_TIME 39 10001
TEST_TIME 40 9948
TEST_TIME 41 9885
TEST_TIME 42 9854
TEST_TIME 43 9879
TEST_TIME 44 9731
TEST_TIME 45 9803
TEST_TIME 46 9809
TEST_TIME 47 9833
TEST_TIME 48 9772
TEST_TIME 49 9804
TEST_TIME 50 9802
TEST_TIME 51 9886
TEST_TIME 52 9766
TEST_TIME 53 9762
TEST_TIME 54 9810
TEST_TIME 55 9919
TEST_TIME 56 10003
TEST_TIME 57 10091
TEST_TIME 58 10104
TEST_TIME 59 9967
TEST_TIME 60 9853
TEST_TIME 61 9773
TEST_TIME 62 9735
TEST_TIME 63 9671
TEST_TIME 64 9608
TEST_TIME 65 9565
TEST_TIME 66 9449
TEST_TIME 67 9474
TEST_TIME 68 9442
TEST_TIME 69 9384
TEST_TIME 70 9336
TEST_TIME 71 9316
TEST_TIME 72 9334
TEST_TIME 73 9343
TEST_TIME 74 9363
TEST_TIME 75 9476
TEST_TIME 76 9528
TEST_TIME 77 9502
TEST_TIME 78 9478
TEST_TIME 79 9469
TEST_TIME 80 9487
TEST_TIME 81 9611
TEST_TIME 82 9546
TEST_TIME 83 9496
TEST_TIME 84 9397
TEST_TIME 85 9439
TEST_TIME 86 9389
TEST_TIME 87 9327
TEST_TIME 88 9265
TEST_TIME 89 9231
TEST_TIME 90 9206
TEST_TIME 91 9182
TEST_TIME 92 9149
TEST_TIME 93 9131
TEST_TIME 94 9167
TEST_TIME 95 9210
TEST_TIME 96 9155
TEST_TIME 97 9124
TEST_TIME 98 9133
TEST_TIME 99 9238
TEST_TIME 100 9298
TEST_TIME 101 9312
TEST_TIME 102 9285
TEST_TIME 103 9239
TEST_TIME 104 9222
TEST_TIME 105 9196
TEST_TIME 106 9157
TEST_TIME 107 9106
TEST_TIME 108 9073
TEST_TIME 109 9038
TEST_TIME 110 9039
TEST_TIME 111 9021
TEST_TIME 112 9046
TEST_TIME 113 9037
TEST_TIME 114 9013
TEST_TIME 115 8985
TEST_TIME 116 9041
TEST_TIME 117 9049
TEST_TIME 118 9174
TEST_TIME 119 9137
TEST_TIME 120 9098

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 01-02-2023 07:38:12 to 01-02-2023 07:38:16.

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 1508183 1508183 0 0.00 0 17625 13 0 0
doc_list_biud 1508178 1508178 0 0.00 0 62680 41 0 0
sp_add_invoice_to_stock 5085 4913 172 3.38 49 219208 25438 0 172
sp_cancel_adding_invoice 5602 5602 0 0.00 5 82942 1151 0 0
sp_cancel_client_order 1838 1838 0 0.00 6 13165 373 0 0
sp_cancel_customer_reserve 550008 550008 0 0.00 1 21344 99 0 0
sp_cancel_pay_from_customer 673 673 0 0.00 4 1357 99 0 0
sp_cancel_pay_to_supplier 450 450 0 0.00 3 9229 137 0 0
sp_cancel_supplier_invoice 6148 6142 6 0.09 2 62702 1028 0 6
sp_cancel_supplier_order 426 426 0 0.00 10 1453715 160790 0 0
sp_cancel_write_off 10316 10316 0 0.00 2 11880 155 0 0
sp_client_order 20655 20655 0 0.00 5 23655 464 0 0
sp_customer_reserve 453174 453174 0 0.00 1 77747 247 0 0
sp_fill_shopping_cart 31594 31594 0 0.00 1 25832 74 0 0
sp_fill_shopping_cart_clo_res 453156 453156 0 0.00 0 21191 22 0 0
sp_get_clo_for_invoice 4890 4890 0 0.00 0 24893 364 0 0
sp_kill_cost_storno 17041 17041 0 0.00 0 6613 62 0 0
sp_kill_qstorno_ret_qs2qd 1116900 1116900 0 0.00 0 62676 44 0 0
sp_kill_qty_storno 589183 589183 0 0.00 0 62679 99 0 0
sp_lock_selected_doc 20802 20802 0 0.00 0 9060 8 0 0
sp_make_cost_storno 27875 27875 0 0.00 0 17186 68 0 0
sp_multiply_rows_for_pdistr 27874 27874 0 0.00 0 12150 35 0 0
sp_multiply_rows_for_qdistr 20655 20655 0 0.00 0 16258 197 0 0
sp_pay_from_customer 7141 7141 0 0.00 5 12160 154 0 0
sp_pay_to_supplier 5882 5882 0 0.00 6 16754 173 0 0
sp_payment_common 13023 13023 0 0.00 2 11412 44 0 0
sp_qd_handle_on_cancel_clo 1838 1838 0 0.00 0 13156 304 0 0
sp_qd_handle_on_invoice_upd_sts 10491 10491 0 0.00 0 26936 466 0 0
sp_qd_handle_on_reserve_upd_sts 9962 9962 0 0.00 0 15001 58 0 0
sp_reserve_write_off 9962 9962 0 0.00 7 21931 243 0 0
sp_supplier_invoice 5458 5458 0 0.00 8 95798 3130 0 0
sp_supplier_order 5504 5504 0 0.00 7 25575 1424 0 0
srv_aggregate_perf_data 116 116 0 0.00 2317 179812 14078 0 0
srv_fill_mon_cache_memory 72 72 0 0.00 785 115035 34519 0 0
srv_find_qd_qs_mism 1041622 1041622 0 0.00 0 14391 11 0 0
srv_make_invnt_saldo 504 504 0 0.00 32 65345 3474 0 0
srv_make_money_saldo 843 843 0 0.00 1 33210 315 0 0
srv_recalc_idx_stat 4 4 0 0.00 254864 438119 315937 0 0
t$perf-abend:sp_add_invoice_to_stock 172 172 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4917 4917 0 0.00 1 21665 753 0 0
t$perf-norm:sp_cancel_adding_invoice 437 437 0 0.00 1 6944 380 0 0
t$perf-norm:sp_cancel_client_order 1838 1838 0 0.00 0 2487 21 0 0
t$perf-norm:sp_cancel_customer_reserve 709 709 0 0.00 0 9017 35 0 0
t$perf-norm:sp_cancel_pay_from_customer 673 673 0 0.00 0 6425 23 0 0
t$perf-norm:sp_cancel_pay_to_supplier 450 450 0 0.00 0 2598 17 0 0
t$perf-norm:sp_cancel_supplier_invoice 417 417 0 0.00 0 1697 36 0 0
t$perf-norm:sp_cancel_supplier_order 426 426 0 0.00 0 92750 5799 0 0
t$perf-norm:sp_cancel_write_off 1011 1011 0 0.00 0 2897 21 0 0
t$perf-norm:sp_client_order 20656 20656 0 0.00 0 7661 29 0 0
t$perf-norm:sp_customer_reserve 992 992 0 0.00 0 1925 16 0 0
t$perf-norm:sp_pay_from_customer 7141 7141 0 0.00 0 14415 18 0 0
t$perf-norm:sp_pay_to_supplier 5882 5882 0 0.00 0 6672 19 0 0
t$perf-norm:sp_reserve_write_off 9962 9962 0 0.00 0 11274 23 0 0
t$perf-norm:sp_supplier_invoice 5458 5458 0 0.00 0 8322 29 0 0
t$perf-norm:sp_supplier_order 5504 5504 0 0.00 0 2122 26 0 0
t$perf-norm:srv_aggregate_perf_data 116 116 0 0.00 0 361 16 0 0
t$perf-norm:srv_fill_mon_cache_memory 72 72 0 0.00 0 437 9 0 0
t$perf-norm:srv_make_invnt_saldo 504 504 0 0.00 0 655 9 0 0
t$perf-norm:srv_make_money_saldo 843 843 0 0.00 0 7673 13 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 8 162 69 0 0
v_add_invoice_to_stock 10102 9758 344 3.40 0 6437 23 0 344
v_all_customers 37862 37862 0 0.00 0 96 3 0 0
v_all_suppliers 30548 30548 0 0.00 0 116 4 0 0
v_all_wares 41308 41308 0 0.00 0 117 3 0 0
v_cancel_adding_invoice 874 874 0 0.00 0 1299 14 0 0
v_cancel_client_order 3676 3676 0 0.00 0 3200 10 0 0
v_cancel_customer_prepayment 1346 1346 0 0.00 0 522 10 0 0
v_cancel_customer_reserve 1418 1418 0 0.00 0 270 8 0 0
v_cancel_payment_to_supplier 900 900 0 0.00 0 318 9 0 0
v_cancel_supplier_invoice 846 834 12 1.41 0 2128 25 0 12
v_cancel_supplier_order 852 852 0 0.00 0 300 10 0 0
v_cancel_write_off 2022 2022 0 0.00 0 2147 21 0 0
v_max_id_clo_ord 5497 5497 0 0.00 0 123 4 0 0
v_max_id_clo_res 992 992 0 0.00 0 1454 13 0 0
v_max_id_ord_sup 5443 5443 0 0.00 0 6222 11 0 0
v_max_non_paid_invoice 1547 1547 0 0.00 0 7766 12 0 0
v_max_non_paid_realizn 3646 3646 0 0.00 0 8137 12 0 0
v_min_id_clo_ord 5497 5497 0 0.00 0 11558 11 0 0
v_min_id_clo_res 992 992 0 0.00 0 6401 23 0 0
v_min_id_ord_sup 5443 5443 0 0.00 0 8182 15 0 0
v_min_non_paid_invoice 5882 5882 0 0.00 0 5398 17 0 0
v_min_non_paid_realizn 7141 7141 0 0.00 0 6565 18 0 0
v_our_firm 10436 10436 0 0.00 0 64 4 0 0
v_reserve_write_off 19924 19924 0 0.00 0 2593 9 0 0
x_lock_depdocs_on_canc_invoice 11327 11327 0 0.00 0 27093 118 0 0
x_lock_depdocs_on_canc_sup_ord 426 426 0 0.00 0 7249 329 0 0
x_make_qstorno_1000_1200 5504 5504 0 0.00 3 25569 1284 0 0
x_make_qstorno_1200_2000 5458 5458 0 0.00 4 95711 2908 0 0
x_make_qstorno_2100_3300 453174 453174 0 0.00 0 77739 215 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 01-02-2023 07:38:17 to 01-02-2023 07:38:21.

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:07:28 940 8192000000 8932080368 8988753920 10571536 0.128 3 2 5 0 11015664 101248 342880
05:08:30 21583 8192000000 9716258912 10013519872 797080432 8.867 100 87 174 0 829894928 11870272 20944224
05:10:00 22411 8192000000 9766143392 10076725248 840211552 9.302 100 88 176 0 873245520 11956224 21077744
05:11:31 11901 8192000000 9803867264 10122264576 869062992 9.591 100 79 158 0 909211584 22006784 18141808
05:12:45 10116 8192000000 9860059024 10194583552 914352992 10.040 100 78 151 1 968099808 36975008 16771808
05:13:58 2543 8192000000 9921582928 10265952256 965662352 10.544 100 87 169 1 1032438832 48421712 18354768
05:15:03 2485 8192000000 9978086112 10335338496 1004983056 10.927 100 84 164 0 1088262880 66275664 17004160
05:16:08 1999 8192000000 10002794992 10366205952 1025890432 11.129 100 92 182 1 1126653840 82193760 18569648
05:17:13 1386 8192000000 10010569920 10378924032 1043979008 11.303 100 96 180 3 1129466336 68120784 17366544
05:18:16 1099 8192000000 10039726560 10414469120 1059194240 11.449 100 96 188 2 1161450496 84054384 18201872
05:19:19 974 8192000000 10026535184 10404249600 1065600624 11.510 100 96 184 0 1145153264 61164352 18388288
05:20:23 1362 8192000000 10048161984 10432901120 1074766480 11.598 100 98 179 3 1173707888 79421392 19520016
05:21:25 1397 8192000000 10061216720 10448957440 1078707264 11.635 100 87 164 0 1179810768 84054464 17049040
05:22:29 1297 8192000000 10037728256 10425655296 1085064272 11.696 100 94 179 0 1161877696 58118224 18695200
05:23:31 1999 8192000000 10054601952 10442010624 1094339168 11.784 100 87 160 1 1173544496 63900576 15304752
05:24:34 1088 8192000000 10059296880 10447941632 1099337232 11.831 100 95 182 2 1179319600 61888720 18093648
05:25:36 1166 8192000000 10093972944 10487025664 1098350624 11.822 100 95 183 0 1211332064 94352320 18629120
05:26:38 1133 8192000000 10052589984 10442121216 1099635872 11.834 100 95 179 1 1175788096 57546336 18605888
05:27:40 5527 8192000000 10053528192 10456887296 1090055840 11.743 99 64 127 1 1179836304 77755760 12024704
05:28:47 1241 8192000000 10060503680 10445262848 1093852352 11.779 100 98 194 1 1182864368 69451872 19560144
05:29:49 1430 8192000000 10019869760 10407624704 1073670272 11.587 100 85 163 3 1143751552 54199824 15881456
05:30:52 1358 8192000000 10051264464 10437566464 1068713008 11.540 100 90 177 2 1173290512 87703376 16874128
05:31:54 1230 8192000000 10033679536 10416517120 1078050768 11.629 100 89 175 1 1159220608 62950384 18219456
05:32:56 1618 8192000000 10001652144 10374279168 1038743616 11.253 98 91 169 2 1116303168 60951280 16608272
05:33:58 1108 8192000000 9976166384 10353467392 1033320880 11.200 100 94 171 4 1097894176 45255168 19318128
05:35:00 1051 8192000000 10004654448 10383835136 1045646384 11.319 100 95 175 1 1126726448 63862640 17217424
05:36:02 999 8192000000 9994880160 10377846784 1060899232 11.465 100 81 160 2 1117280976 40845056 15536688
05:37:04 18886 8192000000 9960035440 10345582592 1010792592 10.983 97 45 86 0 1082060448 62333840 8934016
05:38:30 13064 8192000000 10015663248 10405257216 1054974128 11.408 100 56 104 0 1129717120 64437632 10305360
05:39:55 7555 8192000000 10023766624 10411130880 1069799440 11.550 99 68 127 4 1142718464 59138880 13780144
05:41:12 2017 8192000000 10071322224 10461720576 1090708784 11.749 100 81 149 3 1189840032 83015552 16115696
05:42:17 33013 8192000000 10080106048 10478518272 1094313824 11.784 99 48 82 1 1188800096 84161472 10324800
05:43:55 1509 8192000000 10156067856 10546487296 1120484208 12.032 100 84 155 1 1267652848 130759184 16409456
05:45:02 1040 8192000000 10202618048 10598109184 1131959040 12.140 100 94 182 2 1317736224 166923008 18854176
05:46:08 3333 8192000000 10236858320 10634002432 1141726928 12.232 100 71 141 1 1350808288 193756672 15324688
05:47:18 2682 8192000000 10292456544 10685243392 1162063408 12.423 100 89 170 5 1401229744 220477040 18689296
05:48:25 895 8192000000 10319854864 10730745856 1183844992 12.626 100 86 164 3 1425844880 223173024 18826864
05:49:29 823 8192000000 10347370976 10749517824 1189168064 12.676 100 95 186 0 1457477216 246374144 21935008
05:50:33 911 8192000000 10395493328 10795659264 1198642720 12.764 100 95 186 0 1504295184 283795136 21857328
05:51:37 1286 8192000000 10432080512 10861494272 1196450048 12.743 100 94 183 0 1536768480 319639008 20679424
05:52:43 1005 8192000000 10477434480 10880790528 1208474352 12.855 100 98 192 0 1584438976 354034544 21930080
05:53:47 1455 8192000000 10500770992 10921136128 1210243280 12.871 100 94 181 2 1609526928 377510400 21773248
05:54:52 1254 8192000000 10555713568 10975158272 1219541424 12.957 100 95 182 0 1661393840 420654416 21198000
05:55:56 774 8192000000 10587584176 10996318208 1212842784 12.895 100 97 190 1 1693887504 458599488 22445232
05:56:59 919 8192000000 10626807488 11062460416 1210055152 12.870 100 96 192 1 1729270960 495962416 23253392
05:58:02 2841 8192000000 10622771312 11075330048 1200909568 12.785 100 73 144 0 1732387392 512956928 18520896
05:59:12 1497 8192000000 10631395040 11063156736 1201814432 12.793 100 77 152 0 1735258352 514010192 19433728
06:00:20 2473 8192000000 10644772960 11091230720 1201491952 12.790 100 57 110 0 1749205968 533556096 14157920
06:01:27 3723 8192000000 10641193680 11134595072 1198109792 12.759 100 59 116 0 1750641376 536539312 15992272
06:02:35 9209 8192000000 10557983264 11041214464 1179729952 12.588 100 43 86 0 1678019360 485875760 12413648
06:03:55 12665 8192000000 10440580080 11010703360 1167866624 12.477 100 42 83 0 1546045840 367427584 10751632
06:05:21 25876 8192000000 10247175248 10795356160 1138406288 12.201 99 31 60 1 1401054784 254843280 7805216
06:07:00 32945 8192000000 10052419312 10487738368 1127331984 12.096 99 25 48 0 1171329648 38841376 5156288
06:08:35 72321 8192000000 10099156368 10533777408 1116750528 11.996 98 41 82 0 1182298000 56663760 8883712
06:10:48 58560 8192000000 10081496272 10553606144 1118916256 12.017 99 37 70 0 1154763008 28435584 7411168
06:12:47 46193 8192000000 10085290144 10513719296 1107876992 11.912 97 39 74 0 1169619168 53399616 8342560
06:14:35 47605 8192000000 10050885728 10473488384 1072797072 11.579 95 44 84 3 1132629872 49643808 10188992
06:16:23 12736 8192000000 10088874816 10523602944 1127871168 12.101 100 36 71 1 1190826704 55356112 7599424
06:17:38 20846 8192000000 10129097952 10556915712 1134262416 12.162 100 54 105 0 1214005328 68149968 11592944
06:19:22 29138 8192000000 10119755984 10570031104 1138250928 12.199 100 20 39 0 1224266192 81570848 4444416
06:20:52 23259 8192000000 10091742032 10537693184 1121652320 12.043 99 11 22 1 1184498608 60577264 2269024
06:22:15 88634 8192000000 10097010672 10607849472 1128802464 12.110 99 12 24 0 1207197200 75814656 2580080
06:24:45 17613 8192000000 10112749264 10548854784 1146402272 12.276 100 58 106 0 1214980240 57148432 11429536
06:26:08 37064 8192000000 10147455200 10583920640 1136961040 12.187 99 36 70 0 1230984752 86428192 7595520
06:27:52 64621 8192000000 10112418672 10562453504 1135909968 12.177 99 21 40 1 1197987520 57532640 4544912
06:30:10 24379 8192000000 10166584320 10602758144 1122808992 12.054 98 25 50 0 1257621136 129513744 5298400
06:31:36 76876 8192000000 10165272144 10640494592 1119740448 12.025 98 23 46 0 1284430832 159026480 5663904
06:33:53 31805 8192000000 10116263376 10584391680 1092963344 11.771 96 24 46 0 1207459280 109452080 5043856
06:35:27 40602 8192000000 10095463984 10555240448 1105490544 11.890 98 40 80 0 1176868656 63531056 7847056
06:37:15 49133 8192000000 10081860608 10511200256 1132525552 12.145 100 51 100 0 1191683984 49768928 9389504
06:39:05 54501 8192000000 10061246592 10517676032 1123773488 12.063 100 32 63 0 1191933408 61125184 7034736
06:41:00 42364 8192000000 10079057248 10530820096 1100856288 11.846 98 6 12 0 1159386928 56891264 1639376
06:42:42 48819 8192000000 10074979840 10541957120 1105516624 11.890 98 9 18 0 1168263952 60951424 1795904
06:44:39 52620 8192000000 10055396272 10511966208 1095399456 11.794 97 28 56 0 1150003424 48293744 6310224
06:46:32 63753 8192000000 10084135952 10536632320 1082690048 11.673 96 14 28 0 1144584000 58859904 3034048
06:48:36 45841 8192000000 10167327264 10612207616 1134457200 12.163 100 29 58 0 1243292496 103198704 5636592
06:50:23 59454 8192000000 10131412416 10581430272 1115593504 11.985 97 27 54 0 1230239664 108962352 5683808
06:52:24 64311 8192000000 10130303264 10596171776 1113380976 11.964 98 29 58 0 1194433680 74950896 6101808
06:54:29 41442 8192000000 10081682800 10503979008 1102123776 11.858 97 46 90 0 1140874992 29842112 8909104
06:56:12 37505 8192000000 10106035120 10542530560 1106719728 11.901 97 41 78 2 1179590272 63589424 9281120
06:57:54 74682 8192000000 10097920800 10577297408 1128307872 12.105 99 22 42 0 1236197936 103122112 4767952
07:00:09 115032 8192000000 10100017280 10538508288 1125406928 12.078 99 35 66 0 1184595600 51834368 7354304
07:03:05 64082 8192000000 10072241600 10553626624 1137654448 12.193 100 6 13 0 1175779264 37019792 1105024
07:05:10 34256 8192000000 10087210320 10563190784 1149169440 12.302 100 11 22 1 1193387824 41725344 2493040
07:06:44 57649 8192000000 10066261424 10522550272 1120617072 12.033 99 26 52 0 1162508768 35373872 6517824
07:09:09 39057 8192000000 10119432672 10567163904 1106824832 11.902 97 35 70 0 1170271440 56161056 7285552
07:10:50 43333 8192000000 10122009792 10566467584 1128382160 12.106 99 23 46 0 1205264192 71696400 5185632
07:12:52 22910 8192000000 10110843184 10545811456 1126021200 12.084 99 31 62 0 1199200400 66965264 6213936
07:14:21 10554 8192000000 10201241728 10628689920 1153424176 12.342 100 54 105 1 1303447040 138707440 11315424
07:15:36 31702 8192000000 10247698912 10716360704 1152673280 12.335 100 49 95 0 1327546880 165020192 9853408
07:17:13 48487 8192000000 10091995520 10566885376 1120166496 12.029 98 36 73 0 1186882608 59631536 7084576
07:19:02 49244 8192000000 10117229616 10552520704 1127258800 12.096 99 44 88 0 1197721744 62232064 8230880
07:20:51 42010 8192000000 10100520896 10536394752 1135168272 12.170 100 38 75 0 1202046368 59845936 7032160
07:22:36 56725 8192000000 10043826640 10501779456 1107335744 11.907 98 7 14 0 1143605696 35005456 1264496
07:24:33 60170 8192000000 10049761136 10529275904 1110288992 11.935 98 4 8 0 1140948128 30007920 651216
07:26:35 48295 8192000000 10081578224 10541137920 1119517728 12.022 98 42 72 1 1190664624 62272768 8874128
07:28:28 76127 8192000000 10146119824 10633461760 1136090464 12.179 98 29 58 1 1230773216 88015040 6667712
07:30:45 61392 8192000000 10143143600 10587017216 1098564208 11.824 96 23 46 1 1217700640 114084368 5052064
07:32:56 52935 8192000000 10175617168 10637389824 1144597968 12.259 100 33 66 2 1241535424 89740880 7196576
07:35:14 79707 8192000000 10079417328 10519973888 1108244528 11.916 97 14 28 0 1149557840 38374256 2939056

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 01-02-2023 07:38:23 to 01-02-2023 07:38:40.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 172 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 01-02-2023 07:38:41 to 01-02-2023 07:38:43.

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          221892
MON$OLDEST_ACTIVE               223885
MON$OLDEST_SNAPSHOT             223885
MON$NEXT_TRANSACTION            223885
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                       2037664
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             778
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2894 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2894 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2894 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2894 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 ---
:290: 
:291: mscs-test-161.budzdorov.ru    Wed Feb  1 07:37:28 2023
:292:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:293: 
:294: 
:295: mscs-test-161.budzdorov.ru    Wed Feb  1 07:37:34 2023
:296:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:297: 
:298: 
:299: mscs-test-161.budzdorov.ru    Wed Feb  1 07:37:36 2023
:300:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:301: 
--- end of diff output ---

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

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