Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 16.10.2022 10: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 15217776 kB
MemAvailable 30531172 kB
Buffers 0 kB
Cached 16521688 kB
SwapCached 2152 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 2022-10-16 10:37:21
DTS_END                         2022-10-16 10:37:21.0430
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(127:13) ==> X_MAKE_QSTORNO_2100_3300(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/54672
TRN_ID                          213196
ATT_ID                          653
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: 8756.52 29125 1050782
customer order: creation 156.43 477 18772
customer order: refuse 13.49 393 1619
order to supplier: creation 41.68 1507 5001
order to supplier: removal 3.49 167576 419
invoice (draft): creation 42.70 3464 5124
invoice (draft): removal 51.45 913 6174
invoice accept: apply 38.80 26958 4656
invoice accept: cancel 46.13 1131 5536
customer reserve: creation 3560.68 258 427282
customer reserve: removal 4517.69 102 542123
realization accept: apply 77.13 274 9255
realization accept: cancel 84.50 170 10140
payment from customer: creation 55.48 172 6658
payment from customer: removal 5.38 129 645
payment to supplier: creation 46.05 204 5526
payment to supplier: removal 3.53 116 423
service: total inventory turnovers 3.59 4005 431
service: total monetary turnovers 7.49 423 899
service: aggregate perf. data 0.79 19156 95
service: refresh index statistics 0.03 355073 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 16-10-2022 10:39:46 to 16-10-2022 10:39:48.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8550
TEST_TIME 2 6612
TEST_TIME 3 6743
TEST_TIME 4 6747
TEST_TIME 5 7187
TEST_TIME 6 6992
TEST_TIME 7 6930
TEST_TIME 8 6895
TEST_TIME 9 6565
TEST_TIME 10 6601
TEST_TIME 11 6831
TEST_TIME 12 7105
TEST_TIME 13 7267
TEST_TIME 14 7521
TEST_TIME 15 7548
TEST_TIME 16 7655
TEST_TIME 17 7712
TEST_TIME 18 7778
TEST_TIME 19 8115
TEST_TIME 20 8112
TEST_TIME 21 8117
TEST_TIME 22 8349
TEST_TIME 23 8301
TEST_TIME 24 8181
TEST_TIME 25 8367
TEST_TIME 26 8378
TEST_TIME 27 8333
TEST_TIME 28 8683
TEST_TIME 29 8946
TEST_TIME 30 9141
TEST_TIME 31 9228
TEST_TIME 32 9636
TEST_TIME 33 10432
TEST_TIME 34 10950
TEST_TIME 35 11161
TEST_TIME 36 11068
TEST_TIME 37 10772
TEST_TIME 38 10596
TEST_TIME 39 10468
TEST_TIME 40 10338
TEST_TIME 41 10265
TEST_TIME 42 10101
TEST_TIME 43 10046
TEST_TIME 44 9932
TEST_TIME 45 9880
TEST_TIME 46 9701
TEST_TIME 47 9707
TEST_TIME 48 9540
TEST_TIME 49 9763
TEST_TIME 50 9643
TEST_TIME 51 9603
TEST_TIME 52 9560
TEST_TIME 53 9416
TEST_TIME 54 9312
TEST_TIME 55 9269
TEST_TIME 56 9172
TEST_TIME 57 9186
TEST_TIME 58 9131
TEST_TIME 59 9208
TEST_TIME 60 9247
TEST_TIME 61 9172
TEST_TIME 62 9199
TEST_TIME 63 9140
TEST_TIME 64 9188
TEST_TIME 65 9212
TEST_TIME 66 9236
TEST_TIME 67 9284
TEST_TIME 68 9295
TEST_TIME 69 9381
TEST_TIME 70 9320
TEST_TIME 71 9200
TEST_TIME 72 9144
TEST_TIME 73 9149
TEST_TIME 74 9053
TEST_TIME 75 9004
TEST_TIME 76 9053
TEST_TIME 77 9023
TEST_TIME 78 8991
TEST_TIME 79 9022
TEST_TIME 80 9014
TEST_TIME 81 9013
TEST_TIME 82 9004
TEST_TIME 83 8986
TEST_TIME 84 8953
TEST_TIME 85 8997
TEST_TIME 86 9149
TEST_TIME 87 9135
TEST_TIME 88 9200
TEST_TIME 89 9126
TEST_TIME 90 9086
TEST_TIME 91 9035
TEST_TIME 92 8995
TEST_TIME 93 8988
TEST_TIME 94 8922
TEST_TIME 95 8873
TEST_TIME 96 8806
TEST_TIME 97 8872
TEST_TIME 98 8825
TEST_TIME 99 8866
TEST_TIME 100 8862
TEST_TIME 101 8870
TEST_TIME 102 8834
TEST_TIME 103 8810
TEST_TIME 104 8900
TEST_TIME 105 8942
TEST_TIME 106 8997
TEST_TIME 107 8981
TEST_TIME 108 9006
TEST_TIME 109 9098
TEST_TIME 110 9088
TEST_TIME 111 9085
TEST_TIME 112 9012
TEST_TIME 113 8984
TEST_TIME 114 8952
TEST_TIME 115 8923
TEST_TIME 116 8894
TEST_TIME 117 8854
TEST_TIME 118 8853
TEST_TIME 119 8822
TEST_TIME 120 8781

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 16-10-2022 10:39:49 to 16-10-2022 10:39:53.

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 1444835 1444835 0 0.00 0 15520 13 0 0
doc_list_biud 1444835 1444835 0 0.00 0 60649 43 0 0
sp_add_invoice_to_stock 4745 4656 89 1.87 35 245695 26959 0 89
sp_cancel_adding_invoice 5536 5536 0 0.00 6 107396 1131 0 0
sp_cancel_client_order 1619 1619 0 0.00 5 12314 393 0 0
sp_cancel_customer_reserve 542123 542123 0 0.00 1 60687 102 0 0
sp_cancel_pay_from_customer 645 645 0 0.00 4 9381 129 0 0
sp_cancel_pay_to_supplier 423 423 0 0.00 4 1884 117 0 0
sp_cancel_supplier_invoice 6182 6174 8 0.12 2 39277 914 0 8
sp_cancel_supplier_order 419 419 0 0.00 9 1506633 167577 0 0
sp_cancel_write_off 10140 10140 0 0.00 2 13073 170 0 0
sp_client_order 18772 18772 0 0.00 6 54999 478 0 0
sp_customer_reserve 427282 427282 0 0.00 0 93014 259 0 0
sp_fill_shopping_cart 28895 28895 0 0.00 1 30658 90 0 0
sp_fill_shopping_cart_clo_res 427244 427244 0 0.00 0 51903 19 0 0
sp_get_clo_for_invoice 4615 4615 0 0.00 0 25910 341 0 0
sp_kill_cost_storno 16744 16744 0 0.00 0 5563 69 0 0
sp_kill_qstorno_ret_qs2qd 1100979 1100979 0 0.00 0 60559 44 0 0
sp_kill_qty_storno 579880 579880 0 0.00 0 60645 100 0 0
sp_lock_selected_doc 19225 19225 0 0.00 0 9365 7 0 0
sp_make_cost_storno 26053 26053 0 0.00 0 13640 80 0 0
sp_multiply_rows_for_pdistr 26053 26053 0 0.00 0 6924 39 0 0
sp_multiply_rows_for_qdistr 18772 18772 0 0.00 0 52401 196 0 0
sp_pay_from_customer 6658 6658 0 0.00 5 9780 172 0 0
sp_pay_to_supplier 5526 5526 0 0.00 6 14510 205 0 0
sp_payment_common 12184 12184 0 0.00 2 6759 47 0 0
sp_qd_handle_on_cancel_clo 1619 1619 0 0.00 0 12306 320 0 0
sp_qd_handle_on_invoice_upd_sts 10150 10150 0 0.00 0 53817 532 0 0
sp_qd_handle_on_reserve_upd_sts 9255 9255 0 0.00 0 17745 57 0 0
sp_reserve_write_off 9255 9255 0 0.00 8 17846 274 0 0
sp_supplier_invoice 5124 5124 0 0.00 8 110979 3464 0 0
sp_supplier_order 5001 5001 0 0.00 7 42100 1508 0 0
srv_aggregate_perf_data 95 95 0 0.00 3063 233455 19156 0 0
srv_fill_mon_cache_memory 68 68 0 0.00 781 123866 38635 0 0
srv_find_qd_qs_mism 1005737 1005737 0 0.00 0 12196 10 0 0
srv_make_invnt_saldo 431 431 0 0.00 40 62883 4005 0 0
srv_make_money_saldo 899 899 0 0.00 1 14884 424 0 0
srv_recalc_idx_stat 4 4 0 0.00 167596 429531 355073 0 0
t$perf-abend:sp_add_invoice_to_stock 89 89 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4656 4656 0 0.00 1 11918 1000 0 0
t$perf-norm:sp_cancel_adding_invoice 369 369 0 0.00 1 6284 469 0 0
t$perf-norm:sp_cancel_client_order 1619 1619 0 0.00 0 4215 32 0 0
t$perf-norm:sp_cancel_customer_reserve 587 587 0 0.00 0 825 29 0 0
t$perf-norm:sp_cancel_pay_from_customer 645 645 0 0.00 0 7981 25 0 0
t$perf-norm:sp_cancel_pay_to_supplier 423 423 0 0.00 0 2898 18 0 0
t$perf-norm:sp_cancel_supplier_invoice 356 356 0 0.00 0 1125 50 0 0
t$perf-norm:sp_cancel_supplier_order 419 419 0 0.00 0 101412 6737 0 0
t$perf-norm:sp_cancel_write_off 940 940 0 0.00 0 3296 22 0 0
t$perf-norm:sp_client_order 18772 18772 0 0.00 0 16308 39 0 0
t$perf-norm:sp_customer_reserve 902 902 0 0.00 0 3726 21 0 0
t$perf-norm:sp_pay_from_customer 6658 6658 0 0.00 0 7781 21 0 0
t$perf-norm:sp_pay_to_supplier 5526 5526 0 0.00 0 6227 23 0 0
t$perf-norm:sp_reserve_write_off 9255 9255 0 0.00 0 8977 25 0 0
t$perf-norm:sp_supplier_invoice 5124 5124 0 0.00 0 6897 33 0 0
t$perf-norm:sp_supplier_order 5001 5001 0 0.00 0 4187 32 0 0
t$perf-norm:srv_aggregate_perf_data 95 95 0 0.00 0 1208 30 0 0
t$perf-norm:srv_fill_mon_cache_memory 68 68 0 0.00 0 1063 30 0 0
t$perf-norm:srv_make_invnt_saldo 431 431 0 0.00 0 552 12 0 0
t$perf-norm:srv_make_money_saldo 899 899 0 0.00 0 532 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 4 188 51 0 0
v_add_invoice_to_stock 9402 9224 178 1.89 0 2304 25 0 178
v_all_customers 34756 34756 0 0.00 0 70 4 0 0
v_all_suppliers 28206 28206 0 0.00 0 75 5 0 0
v_all_wares 37544 37544 0 0.00 0 69 4 0 0
v_cancel_adding_invoice 738 738 0 0.00 0 742 16 0 0
v_cancel_client_order 3238 3238 0 0.00 0 2756 11 0 0
v_cancel_customer_prepayment 1290 1290 0 0.00 0 946 10 0 0
v_cancel_customer_reserve 1174 1174 0 0.00 0 2402 15 0 0
v_cancel_payment_to_supplier 846 846 0 0.00 0 1818 15 0 0
v_cancel_supplier_invoice 728 712 16 2.19 0 2167 30 0 16
v_cancel_supplier_order 838 838 0 0.00 0 1137 14 0 0
v_cancel_write_off 1880 1880 0 0.00 0 3764 26 0 0
v_max_id_clo_ord 5001 5001 0 0.00 0 489 5 0 0
v_max_id_clo_res 902 902 0 0.00 0 2950 15 0 0
v_max_id_ord_sup 5122 5122 0 0.00 0 4440 6 0 0
v_max_non_paid_invoice 1546 1546 0 0.00 0 61 8 0 0
v_max_non_paid_realizn 3323 3323 0 0.00 0 1295 8 0 0
v_min_id_clo_ord 5001 5001 0 0.00 0 498 8 0 0
v_min_id_clo_res 902 902 0 0.00 0 811 9 0 0
v_min_id_ord_sup 5122 5122 0 0.00 0 6589 9 0 0
v_min_non_paid_invoice 5526 5526 0 0.00 0 6411 19 0 0
v_min_non_paid_realizn 6658 6658 0 0.00 0 4339 15 0 0
v_our_firm 9458 9458 0 0.00 0 71 5 0 0
v_reserve_write_off 18510 18510 0 0.00 0 3875 11 0 0
x_lock_depdocs_on_canc_invoice 11354 11354 0 0.00 0 18253 136 0 0
x_lock_depdocs_on_canc_sup_ord 419 419 0 0.00 0 5687 372 0 0
x_make_qstorno_1000_1200 5001 5001 0 0.00 3 42042 1326 0 0
x_make_qstorno_1200_2000 5124 5124 0 0.00 4 110405 3211 0 0
x_make_qstorno_2100_3300 427282 427282 0 0.00 0 93013 226 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 16-10-2022 10:39:54 to 16-10-2022 10:39:58.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
08:07:24 24 8192000000 8805579968 8819302400 7042144 0.085 4 1 2 0 7145088 67168 35776
08:08:25 23477 8192000000 9716826368 10014208000 799659216 8.893 100 91 182 0 829356448 7641760 22055472
08:09:51 4956 8192000000 9750532192 10053980160 826885616 9.168 100 91 182 0 860964160 12423776 21654768
08:11:01 14758 8192000000 9771068640 10082643968 842942944 9.329 100 89 178 0 883035760 19131472 20961344
08:12:31 8095 8192000000 9840092336 10167046144 896037056 9.859 100 81 161 0 953079840 39476864 17565920
08:13:44 2288 8192000000 9898280080 10236981248 939866912 10.292 100 85 167 1 1010161680 52785216 17509552
08:14:52 2287 8192000000 9953103408 10302246912 985827504 10.741 100 91 180 0 1068602720 63256032 19519184
08:15:57 1559 8192000000 9991243936 10350940160 1018930896 11.062 100 93 178 1 1104140384 67710432 17499056
08:17:00 925 8192000000 10013063616 10379968512 1044416464 11.307 100 97 187 0 1131087952 68892848 17778640
08:18:02 1055 8192000000 10053448032 10425585664 1053372224 11.393 100 94 186 0 1166221504 93622944 19226336
08:19:05 1074 8192000000 10027263984 10403258368 1060103456 11.457 100 98 187 2 1149959280 71589712 18266112
08:20:08 1164 8192000000 10049590912 10430369792 1070787392 11.560 100 98 183 1 1177785584 87467376 19530816
08:21:11 928 8192000000 10069240992 10450399232 1075945472 11.609 100 97 192 1 1192395632 97395776 19054384
08:22:14 1663 8192000000 10036356048 10424508416 1078604304 11.634 100 75 141 3 1158373888 66064976 13704608
08:23:17 1175 8192000000 10044148624 10428690432 1088981616 11.733 100 100 194 0 1166714176 58194848 19537712
08:24:19 1328 8192000000 10077098800 10467549184 1091413968 11.756 100 95 184 0 1205357504 95007344 18936192
08:25:21 3123 8192000000 10055721584 10448576512 1095776880 11.798 100 73 136 1 1171857232 62689920 13390432
08:26:25 1568 8192000000 10036783792 10425917440 1091764192 11.759 99 68 117 0 1155128128 51885024 11478912
08:27:28 975 8192000000 10036687472 10431991808 1102413616 11.861 100 77 151 2 1157475248 38743216 16318416
08:28:30 951 8192000000 10060756624 10447732736 1090948064 11.752 100 87 170 1 1183528144 73930896 18649184
08:29:34 1142 8192000000 10024966160 10406531072 1074971408 11.600 100 96 181 1 1149956448 54984544 20000496
08:30:37 1301 8192000000 10025031328 10411368448 1069843536 11.551 100 89 174 4 1148131600 60879536 17408528
08:31:39 1085 8192000000 10020075824 10407145472 1062579296 11.481 100 85 164 0 1145229472 64897520 17752656
08:32:41 1311 8192000000 10024844208 10399916032 1053570640 11.395 100 97 183 5 1150224256 78501456 18152160
08:33:43 4653 8192000000 9988764496 10379804672 1058652208 11.444 100 57 114 0 1119770320 49886688 11231424
08:34:49 1171 8192000000 10003386912 10375479296 1039547392 11.260 100 94 174 1 1126706944 68934688 18224864
08:35:52 1163 8192000000 9989870848 10362253312 1037370432 11.239 100 89 166 2 1113739776 59143520 17225824
08:36:55 1087 8192000000 9974633904 10355445760 1043679856 11.300 100 71 136 3 1100106752 41112768 15314128
08:38:01 2787 8192000000 9994327664 10377330688 1060192432 11.458 100 73 136 0 1119131440 45810992 13128016
08:39:15 15609 8192000000 10043331872 10434048000 1079031696 11.638 100 69 132 1 1169054000 76748976 13273328
08:40:37 3933 8192000000 10070441008 10462896128 1094398080 11.784 100 86 164 2 1192592992 80852240 17342672
08:41:44 8488 8192000000 10086346576 10482257920 1096262304 11.802 100 61 114 0 1201288672 92741552 12284816
08:43:03 1301 8192000000 10104640032 10491609088 1112391632 11.955 100 90 172 3 1220199248 91171056 16636560
08:44:09 10075 8192000000 10142036128 10552860672 1111555856 11.947 99 63 118 0 1255784464 131623392 12605216
08:45:25 9479 8192000000 10163050656 10572890112 1134162352 12.161 100 76 135 2 1279402736 131082160 14158224
08:46:41 2179 8192000000 10193884704 10589822976 1152595616 12.334 100 81 157 0 1310598176 140885984 17116576
08:47:46 1067 8192000000 10241300352 10638675968 1161635328 12.419 100 98 179 4 1355180288 172903120 20641840
08:48:51 2056 8192000000 10248470320 10653433856 1173364016 12.528 100 85 161 3 1361719872 169989264 18366592
08:49:56 1254 8192000000 10300249568 10703794176 1179973536 12.590 100 93 174 2 1409811472 210604512 19233424
08:51:01 896 8192000000 10290630608 10706087936 1182055600 12.609 100 94 177 3 1405220768 201972736 21192432
08:52:03 2081 8192000000 10321992336 10737143808 1182096928 12.610 100 90 173 2 1432581312 231673664 18810720
08:53:10 968 8192000000 10364294064 10778501120 1192144544 12.703 100 97 177 0 1474977248 261427600 21405104
08:54:14 1353 8192000000 10377320464 10799001600 1189234960 12.676 100 98 186 3 1487148992 275681584 22232448
08:55:19 1188 8192000000 10391059360 10826719232 1197460576 12.753 100 97 190 0 1498008688 278697440 21850672
08:56:24 2917 8192000000 10396896192 10799833088 1184381088 12.631 99 84 164 1 1510117264 306148800 19587376
08:57:29 1747 8192000000 10482607200 10891743232 1210549936 12.874 100 96 182 0 1590298192 355864320 23883936
08:58:35 772 8192000000 10492277472 10917404672 1200256544 12.779 100 99 194 2 1595545616 371725328 23563744
08:59:40 1469 8192000000 10515831088 10937778176 1216866080 12.933 100 97 187 1 1623875088 383733312 23275696
09:00:44 881 8192000000 10533825120 10943234048 1196118608 12.740 100 74 140 1 1636803520 424349888 16335024
09:01:48 994 8192000000 10568812960 10999246848 1186689872 12.653 100 98 188 2 1671208064 461601040 22917152
09:02:50 1235 8192000000 10615049760 11042607104 1191776576 12.700 100 71 142 0 1724132256 514692016 17663664
09:04:10 2516 8192000000 10671362320 11087994880 1194718496 12.727 100 77 154 2 1772700944 558907488 19074960
09:05:16 5358 8192000000 10648904576 11117645824 1197751728 12.755 100 66 132 1 1746016912 531153248 17111936
09:06:29 4702 8192000000 10627631312 11087167488 1193084544 12.712 100 47 94 0 1729592672 523965952 12542176
09:08:02 10476 8192000000 10595228880 11097702400 1183031872 12.618 100 35 68 0 1710684592 518175584 9477136
09:09:19 20762 8192000000 10492159232 10992930816 1160856688 12.411 100 28 56 1 1595918928 427148304 7913936
09:11:13 64719 8192000000 10095313216 10626183168 1128192192 12.104 99 14 28 0 1295026016 163598736 3235088
09:13:20 97877 8192000000 10088522464 10528608256 1145408512 12.266 100 36 66 0 1186842560 33381472 8052576
09:16:07 89138 8192000000 10062582256 10498797568 1081933248 11.666 94 24 46 1 1123343376 37078368 4331760
09:18:37 77930 8192000000 10071059920 10538274816 1110720304 11.939 98 21 39 0 1156704976 42003360 3981312
09:20:56 123865 8192000000 10179724816 10658373632 1139342432 12.209 99 27 56 0 1230140240 84386656 6411152
09:24:07 99723 8192000000 10169090256 10626334720 1150670992 12.316 100 17 34 0 1284121600 129552960 3897648
09:27:11 7218 8192000000 10067986976 10512228352 1137408320 12.191 99 32 62 0 1162159280 18648560 6102400
09:28:20 82031 8192000000 10063038224 10581090304 1134274048 12.162 99 22 44 0 1181560224 42542096 4744080
09:30:54 85372 8192000000 10066076528 10546978816 1142965712 12.243 100 20 39 0 1185513056 38602352 3944992
09:33:23 82450 8192000000 10078749328 10574196736 1142499840 12.239 100 12 24 0 1196650144 51760000 2390304
09:35:46 30158 8192000000 10098558368 10552954880 1121571248 12.042 98 30 56 0 1164002000 35870160 6560592
09:37:17 59246 8192000000 10089240768 10572705792 1113580784 11.966 97 17 34 0 1163754512 46591024 3582704
09:39:22 48984 8192000000 10121385744 10564960256 1138541824 12.202 99 36 72 0 1192466672 46696976 7227872
09:41:11 39099 8192000000 10152781904 10631254016 1120273520 12.030 98 40 79 0 1224279328 96053456 7952352
09:42:51 51091 8192000000 10125121264 10552414208 1115621824 11.986 97 36 70 0 1186808976 63820256 7366896
09:45:23 31675 8192000000 10137032784 10605953024 1124088896 12.066 99 40 78 0 1185933584 53650720 8193968
09:46:56 102169 8192000000 10051352592 10492186624 1088891248 11.732 96 16 30 3 1119696816 27602208 3203360
09:49:52 83453 8192000000 10079421760 10584858624 1113553792 11.966 98 19 38 0 1194550800 77235392 3761616
09:52:35 66402 8192000000 10075786336 10576171008 1111266336 11.944 98 12 24 0 1191422912 77355216 2801360
09:56:06 34595 8192000000 10078128848 10527338496 1095755776 11.797 98 28 56 0 1170602272 68949168 5897328
09:57:41 55945 8192000000 10063662960 10507800576 1084480320 11.690 97 37 74 1 1168716224 75276480 8959424
09:59:38 34181 8192000000 10096369920 10525880320 1104563488 11.881 98 32 64 0 1184701184 73238896 6898800
10:01:26 45115 8192000000 10171641600 10612609024 1122612736 12.052 99 35 70 1 1252137872 121714144 7810992
10:03:56 68584 8192000000 10094711376 10543751168 1103963008 11.875 97 14 29 1 1163351376 56322304 3066064
10:06:05 86978 8192000000 10064512800 10497314816 1109542288 11.928 99 41 80 2 1155935328 36746496 9646544
10:08:33 55739 8192000000 10087772592 10543439872 1125613872 12.080 100 32 64 0 1192677584 60602288 6461424
10:10:52 46663 8192000000 10074976704 10541711360 1127618112 12.099 99 9 18 0 1161516176 32131728 1766336
10:12:39 58880 8192000000 10122200000 10577219584 1128668176 12.109 99 19 38 0 1207137840 74021344 4448320
10:14:44 79033 8192000000 10105463904 10559664128 1114782176 11.978 98 18 37 1 1198791664 79723152 4286336
10:17:04 31920 8192000000 10097603568 10542022656 1136536960 12.183 99 25 48 0 1183731504 41841984 5352560
10:18:39 36346 8192000000 10107994880 10552274944 1124269616 12.067 98 16 32 0 1184815136 57178672 3366848
10:20:15 62819 8192000000 10240547024 10721984512 1153054992 12.338 100 26 52 0 1306396112 147210368 6130752
10:22:19 71043 8192000000 10072261040 10574553088 1094531008 11.786 96 14 28 1 1196084624 98855008 2698608
10:24:31 20311 8192000000 10139218032 10577428480 1138709712 12.203 99 46 92 0 1218237856 70124704 9403440
10:25:55 109085 8192000000 10055989760 10547769344 1081601552 11.663 95 21 42 0 1179019984 92775088 4643344
10:28:45 18934 8192000000 10101260736 10555940864 1124906256 12.073 100 57 114 0 1210118976 73541776 11670944
10:30:05 67115 8192000000 10071252176 10521714688 1127193760 12.095 100 31 62 0 1193137040 59690496 6252784
10:32:14 96311 8192000000 10069888880 10572414976 1132695568 12.147 100 7 14 0 1198791376 64505952 1589856
10:34:53 58134 8192000000 10072986576 10550337536 1105747200 11.892 97 16 30 0 1153946960 45147520 3052240
10:36:57 47994 8192000000 9466799488 9856270336 587828992 6.695 53 3 6 0 613151664 24679936 642736

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 16-10-2022 10:40:00 to 16-10-2022 10:40:16.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 89 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 16-10-2022 10:40:17 to 16-10-2022 10:40:19.

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          212540
MON$OLDEST_ACTIVE               213643
MON$OLDEST_SNAPSHOT             213643
MON$NEXT_TRANSACTION            213643
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                       2066592
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             764
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2859 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2859 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2859 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2859 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 ---
:87: 
:88: mscs-test-161.budzdorov.ru    Sun Oct 16 10:37:58 2022
:89:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:90: 
--- end of diff output ---

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

16.10.22 10:40:20. 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-10-16 10:40:22 No severe PSQL-related problems occured
16.10.22 10:40:24. End of report.