Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 18.01.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 14888956 kB
MemAvailable 30373492 kB
Buffers 0 kB
Cached 16793712 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.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-01-18 07:37:23
DTS_END                         2023-01-18 07:37:23.7400
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_ADDING_INVOICE(33:5) ==> SP_ADD_INVOICE_TO_STOCK(141:26) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/58272
TRN_ID                          223372
ATT_ID                          659
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: 9234.30 28556 1108116
customer order: creation 171.07 482 20528
customer order: refuse 14.43 379 1732
order to supplier: creation 46.08 1304 5530
order to supplier: removal 3.57 160257 428
invoice (draft): creation 45.37 3207 5444
invoice (draft): removal 52.13 1021 6256
invoice accept: apply 42.22 24897 5066
invoice accept: cancel 46.72 1171 5606
customer reserve: creation 3915.86 236 469903
customer reserve: removal 4604.45 96 552534
realization accept: apply 84.03 294 10084
realization accept: cancel 77.56 164 9307
payment from customer: creation 58.94 180 7073
payment from customer: removal 6.05 145 726
payment to supplier: creation 49.24 205 5909
payment to supplier: removal 3.47 141 416
service: total inventory turnovers 4.02 3755 482
service: total monetary turnovers 8.05 356 966
service: aggregate perf. data 1.02 14202 122
service: refresh index statistics 0.03 358623 4

Performance in TOTAL, chart


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

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6805
TEST_TIME 2 5182
TEST_TIME 3 6722
TEST_TIME 4 6237
TEST_TIME 5 5985
TEST_TIME 6 6453
TEST_TIME 7 6161
TEST_TIME 8 6183
TEST_TIME 9 6079
TEST_TIME 10 6125
TEST_TIME 11 6272
TEST_TIME 12 6376
TEST_TIME 13 6461
TEST_TIME 14 6759
TEST_TIME 15 7025
TEST_TIME 16 7096
TEST_TIME 17 7409
TEST_TIME 18 7639
TEST_TIME 19 7685
TEST_TIME 20 8144
TEST_TIME 21 8166
TEST_TIME 22 8172
TEST_TIME 23 8293
TEST_TIME 24 8384
TEST_TIME 25 8350
TEST_TIME 26 8401
TEST_TIME 27 8468
TEST_TIME 28 8538
TEST_TIME 29 9175
TEST_TIME 30 9985
TEST_TIME 31 10384
TEST_TIME 32 10748
TEST_TIME 33 11041
TEST_TIME 34 11079
TEST_TIME 35 11348
TEST_TIME 36 11054
TEST_TIME 37 10947
TEST_TIME 38 11152
TEST_TIME 39 10979
TEST_TIME 40 10831
TEST_TIME 41 10705
TEST_TIME 42 10555
TEST_TIME 43 10452
TEST_TIME 44 10453
TEST_TIME 45 10352
TEST_TIME 46 10231
TEST_TIME 47 10430
TEST_TIME 48 10287
TEST_TIME 49 10309
TEST_TIME 50 10282
TEST_TIME 51 10354
TEST_TIME 52 10363
TEST_TIME 53 10291
TEST_TIME 54 10140
TEST_TIME 55 10087
TEST_TIME 56 9988
TEST_TIME 57 9925
TEST_TIME 58 9863
TEST_TIME 59 9809
TEST_TIME 60 9790
TEST_TIME 61 9714
TEST_TIME 62 9791
TEST_TIME 63 9805
TEST_TIME 64 9738
TEST_TIME 65 9646
TEST_TIME 66 9589
TEST_TIME 67 9623
TEST_TIME 68 9550
TEST_TIME 69 9503
TEST_TIME 70 9528
TEST_TIME 71 9531
TEST_TIME 72 9577
TEST_TIME 73 9645
TEST_TIME 74 9626
TEST_TIME 75 9612
TEST_TIME 76 9571
TEST_TIME 77 9702
TEST_TIME 78 9672
TEST_TIME 79 9616
TEST_TIME 80 9514
TEST_TIME 81 9460
TEST_TIME 82 9417
TEST_TIME 83 9349
TEST_TIME 84 9325
TEST_TIME 85 9334
TEST_TIME 86 9290
TEST_TIME 87 9236
TEST_TIME 88 9231
TEST_TIME 89 9313
TEST_TIME 90 9299
TEST_TIME 91 9290
TEST_TIME 92 9264
TEST_TIME 93 9313
TEST_TIME 94 9443
TEST_TIME 95 9420
TEST_TIME 96 9499
TEST_TIME 97 9499
TEST_TIME 98 9498
TEST_TIME 99 9508
TEST_TIME 100 9462
TEST_TIME 101 9418
TEST_TIME 102 9405
TEST_TIME 103 9378
TEST_TIME 104 9361
TEST_TIME 105 9311
TEST_TIME 106 9296
TEST_TIME 107 9294
TEST_TIME 108 9262
TEST_TIME 109 9247
TEST_TIME 110 9204
TEST_TIME 111 9200
TEST_TIME 112 9207
TEST_TIME 113 9197
TEST_TIME 114 9240
TEST_TIME 115 9270
TEST_TIME 116 9275
TEST_TIME 117 9246
TEST_TIME 118 9296
TEST_TIME 119 9318
TEST_TIME 120 9255

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 18-01-2023 07:38:09 to 18-01-2023 07:38:12.

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 1540291 1540291 0 0.00 0 20258 13 0 0
doc_list_biud 1540291 1540291 0 0.00 0 45774 41 0 0
sp_add_invoice_to_stock 5194 5066 128 2.46 39 216824 24897 0 128
sp_cancel_adding_invoice 5606 5606 0 0.00 6 69299 1171 0 0
sp_cancel_client_order 1732 1732 0 0.00 5 10940 380 0 0
sp_cancel_customer_reserve 552534 552534 0 0.00 1 33464 97 0 0
sp_cancel_pay_from_customer 726 726 0 0.00 4 10113 146 0 0
sp_cancel_pay_to_supplier 416 416 0 0.00 4 8283 141 0 0
sp_cancel_supplier_invoice 6268 6256 12 0.19 2 19582 1022 0 12
sp_cancel_supplier_order 428 428 0 0.00 18 1485069 160257 0 0
sp_cancel_write_off 9307 9307 0 0.00 2 8074 164 0 0
sp_client_order 20528 20528 0 0.00 5 34691 482 0 0
sp_customer_reserve 469903 469903 0 0.00 0 93506 236 0 0
sp_fill_shopping_cart 31498 31498 0 0.00 1 17027 85 0 0
sp_fill_shopping_cart_clo_res 469888 469888 0 0.00 0 19365 19 0 0
sp_get_clo_for_invoice 5029 5029 0 0.00 0 31661 311 0 0
sp_kill_cost_storno 16055 16055 0 0.00 0 10168 73 0 0
sp_kill_qstorno_ret_qs2qd 1121059 1121059 0 0.00 0 33455 42 0 0
sp_kill_qty_storno 590975 590975 0 0.00 0 45773 97 0 0
sp_lock_selected_doc 20902 20902 0 0.00 0 5688 6 0 0
sp_make_cost_storno 28094 28094 0 0.00 0 20244 81 0 0
sp_multiply_rows_for_pdistr 28094 28094 0 0.00 0 9569 40 0 0
sp_multiply_rows_for_qdistr 20528 20528 0 0.00 0 29059 174 0 0
sp_pay_from_customer 7073 7073 0 0.00 5 10097 181 0 0
sp_pay_to_supplier 5909 5909 0 0.00 6 25982 205 0 0
sp_payment_common 12982 12982 0 0.00 2 13022 49 0 0
sp_qd_handle_on_cancel_clo 1732 1732 0 0.00 0 10928 312 0 0
sp_qd_handle_on_invoice_upd_sts 10634 10634 0 0.00 0 45772 521 0 0
sp_qd_handle_on_reserve_upd_sts 10084 10084 0 0.00 0 19387 78 0 0
sp_reserve_write_off 10084 10084 0 0.00 8 20811 294 0 0
sp_supplier_invoice 5444 5444 0 0.00 9 90932 3207 0 0
sp_supplier_order 5530 5530 0 0.00 7 24955 1304 0 0
srv_aggregate_perf_data 122 122 0 0.00 2510 136079 14203 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 655 170704 37214 0 0
srv_find_qd_qs_mism 1058578 1058578 0 0.00 0 12306 10 0 0
srv_make_invnt_saldo 482 482 0 0.00 63 61213 3756 0 0
srv_make_money_saldo 966 966 0 0.00 1 21721 356 0 0
srv_recalc_idx_stat 4 4 0 0.00 64123 626338 358623 0 0
t$perf-abend:sp_add_invoice_to_stock 128 128 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5066 5066 0 0.00 0 34834 940 0 0
t$perf-norm:sp_cancel_adding_invoice 404 404 0 0.00 1 5238 404 0 0
t$perf-norm:sp_cancel_client_order 1732 1732 0 0.00 0 4179 32 0 0
t$perf-norm:sp_cancel_customer_reserve 672 672 0 0.00 0 4638 26 0 0
t$perf-norm:sp_cancel_pay_from_customer 726 726 0 0.00 0 6159 24 0 0
t$perf-norm:sp_cancel_pay_to_supplier 416 416 0 0.00 0 3940 23 0 0
t$perf-norm:sp_cancel_supplier_invoice 403 403 0 0.00 0 1863 45 0 0
t$perf-norm:sp_cancel_supplier_order 428 428 0 0.00 0 104093 6819 0 0
t$perf-norm:sp_cancel_write_off 1009 1009 0 0.00 0 7242 27 0 0
t$perf-norm:sp_client_order 20528 20528 0 0.00 0 5191 37 0 0
t$perf-norm:sp_customer_reserve 1019 1019 0 0.00 0 4431 24 0 0
t$perf-norm:sp_pay_from_customer 7073 7073 0 0.00 0 3846 21 0 0
t$perf-norm:sp_pay_to_supplier 5909 5909 0 0.00 0 3847 20 0 0
t$perf-norm:sp_reserve_write_off 10084 10084 0 0.00 0 11233 29 0 0
t$perf-norm:sp_supplier_invoice 5444 5444 0 0.00 0 3318 28 0 0
t$perf-norm:sp_supplier_order 5530 5530 0 0.00 0 5212 31 0 0
t$perf-norm:srv_aggregate_perf_data 122 122 0 0.00 0 1617 28 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 150 7 0 0
t$perf-norm:srv_make_invnt_saldo 482 482 0 0.00 0 3427 21 0 0
t$perf-norm:srv_make_money_saldo 966 966 0 0.00 0 1534 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 4 171 46 0 0
v_add_invoice_to_stock 10312 10056 256 2.48 0 6593 28 0 256
v_all_customers 37564 37564 0 0.00 0 107 4 0 0
v_all_suppliers 30852 30852 0 0.00 0 110 4 0 0
v_all_wares 41056 41056 0 0.00 0 110 4 0 0
v_cancel_adding_invoice 808 808 0 0.00 0 4795 20 0 0
v_cancel_client_order 3464 3464 0 0.00 0 6121 11 0 0
v_cancel_customer_prepayment 1452 1452 0 0.00 0 4658 14 0 0
v_cancel_customer_reserve 1344 1344 0 0.00 0 1507 12 0 0
v_cancel_payment_to_supplier 832 832 0 0.00 0 3646 17 0 0
v_cancel_supplier_invoice 830 806 24 2.89 0 4216 34 0 24
v_cancel_supplier_order 856 856 0 0.00 0 2074 12 0 0
v_cancel_write_off 2018 2018 0 0.00 0 4209 27 0 0
v_max_id_clo_ord 5530 5530 0 0.00 0 2243 6 0 0
v_max_id_clo_res 1019 1019 0 0.00 0 2161 11 0 0
v_max_id_ord_sup 5440 5440 0 0.00 0 3726 8 0 0
v_max_non_paid_invoice 1453 1453 0 0.00 0 1091 9 0 0
v_max_non_paid_realizn 3786 3786 0 0.00 0 7886 10 0 0
v_min_id_clo_ord 5530 5530 0 0.00 0 615 7 0 0
v_min_id_clo_res 1019 1019 0 0.00 0 3751 13 0 0
v_min_id_ord_sup 5440 5440 0 0.00 0 4204 10 0 0
v_min_non_paid_invoice 5909 5909 0 0.00 0 13011 23 0 0
v_min_non_paid_realizn 7073 7073 0 0.00 0 10049 18 0 0
v_our_firm 10066 10066 0 0.00 0 83 5 0 0
v_reserve_write_off 20168 20168 0 0.00 0 6079 13 0 0
x_lock_depdocs_on_canc_invoice 11459 11459 0 0.00 0 18822 122 0 0
x_lock_depdocs_on_canc_sup_ord 428 428 0 0.00 0 10185 353 0 0
x_make_qstorno_1000_1200 5530 5530 0 0.00 2 24938 1135 0 0
x_make_qstorno_1200_2000 5444 5444 0 0.00 4 90745 2979 0 0
x_make_qstorno_2100_3300 469903 469903 0 0.00 0 93494 206 0 0

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

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:27 10 8192000000 8805646960 8819437568 9842512 0.120 3 3 5 0 10040112 100528 97072
05:08:28 13091 8192000000 9716913552 10015256576 804109344 8.938 100 80 160 0 830171584 6936352 19125888
05:09:43 19141 8192000000 9747739744 10055036928 829590048 9.195 100 90 180 0 862031696 11091376 21350272
05:11:06 12616 8192000000 9792242944 10107973632 863762816 9.538 100 86 172 0 899723728 16069504 19891408
05:12:22 15385 8192000000 9845863056 10174029824 902859808 9.927 100 87 174 0 954555712 32121568 19574336
05:13:41 5700 8192000000 9908311008 10252345344 948964400 10.381 100 87 172 0 1027786048 60648320 18173328
05:14:49 2541 8192000000 9945935904 10297278464 981375760 10.698 100 91 176 0 1064788000 65374240 18038000
05:15:55 985 8192000000 9987900576 10347433984 1012371056 10.998 100 93 184 0 1109932992 79255792 18306144
05:16:58 1302 8192000000 10005913840 10372435968 1038868816 11.254 100 94 178 0 1127026128 70269312 17888000
05:18:00 753 8192000000 10005227040 10377646080 1046603792 11.328 100 92 171 1 1119404784 54939840 17861152
05:19:03 1064 8192000000 10024937968 10399318016 1056104720 11.419 100 94 175 0 1146252528 73331808 16816000
05:20:06 1324 8192000000 10033788896 10413907968 1059577440 11.452 100 94 178 3 1162055424 83547328 18930656
05:21:09 1419 8192000000 10055449840 10437390336 1066238672 11.516 100 92 177 1 1181374800 96948048 18188080
05:22:12 1382 8192000000 10063019376 10449362944 1073908096 11.589 100 89 171 2 1181751360 90136432 17706832
05:23:14 1251 8192000000 10058016816 10440728576 1076683120 11.616 100 98 189 4 1179271440 83546816 19041504
05:24:17 1225 8192000000 10060343312 10443677696 1086195360 11.706 100 97 186 2 1179813088 74620272 18997456
05:25:20 2093 8192000000 10033427376 10420670464 1089615152 11.739 100 82 158 5 1157499504 51788112 16096240
05:26:23 1139 8192000000 10070644464 10460475392 1095443408 11.794 100 96 172 2 1193613104 79427792 18741904
05:27:25 1155 8192000000 10041702864 10424647680 1083793296 11.684 99 93 180 0 1165863552 62852512 19217744
05:28:28 1095 8192000000 10064272352 10447515648 1080146384 11.649 100 89 173 4 1184541840 86336208 18059248
05:29:30 1438 8192000000 10042658352 10435162112 1084722880 11.692 100 79 151 2 1168701696 68790800 15188016
05:30:33 6625 8192000000 10034863968 10440134656 1085175200 11.697 100 55 107 0 1153525440 58062448 10287792
05:31:43 4674 8192000000 10013173296 10398146560 1065535728 11.509 99 63 117 1 1128244304 51481264 11227312
05:32:52 1224 8192000000 10016768928 10393440256 1048693696 11.348 99 94 176 1 1141165104 73987744 18483664
05:33:55 5004 8192000000 9995950256 10377584640 1045068336 11.313 99 69 130 2 1114119632 56616640 12434656
05:35:01 1223 8192000000 9974290240 10344464384 1031098832 11.179 100 100 188 2 1098021712 48321728 18601152
05:36:03 1185 8192000000 10003754416 10377842688 1037998192 11.245 100 96 188 0 1125684608 67682064 20004352
05:37:04 2289 8192000000 9981957136 10355904512 1030894048 11.177 99 70 124 3 1100893584 58382528 11617008
05:38:13 1234 8192000000 9991284960 10364903424 1053347968 11.393 100 95 174 4 1109918608 37657040 18913600
05:39:20 2406 8192000000 10010470032 10391068672 1068639600 11.539 100 75 142 2 1132993568 48925712 15428256
05:40:25 1681 8192000000 10052541872 10436341760 1088749536 11.731 100 91 169 1 1175952640 69958944 17244160
05:41:30 6008 8192000000 10071472704 10464673792 1101295680 11.850 100 74 145 1 1196924560 80250240 15378640
05:42:41 7016 8192000000 10103300080 10505023488 1116076528 11.990 100 61 115 2 1217308368 88977536 12254304
05:43:58 1119 8192000000 10167903696 10561445888 1130425872 12.125 100 86 163 0 1277012048 128970560 17615616
05:45:04 1721 8192000000 10196485728 10594156544 1139455488 12.210 100 82 160 2 1315464896 158960192 17049216
05:46:09 2618 8192000000 10217383712 10612731904 1143446528 12.248 100 91 174 0 1334230480 171352416 19431536
05:47:16 2883 8192000000 10255927184 10656268288 1155723152 12.363 100 92 177 0 1370422736 194309840 20389744
05:48:25 764 8192000000 10300056144 10697711616 1160398272 12.407 100 97 189 2 1414257824 232800656 21058896
05:49:29 12673 8192000000 10335472544 10739691520 1165586608 12.456 100 83 164 1 1448860096 263911152 19362336
05:50:45 1268 8192000000 10362838480 10773389312 1176752432 12.560 100 82 161 0 1475030848 279811920 18466496
05:51:50 1918 8192000000 10384466288 10792976384 1185175872 12.638 100 89 174 0 1499099168 294262576 19660720
05:52:55 883 8192000000 10406600832 10813526016 1189536928 12.679 100 98 184 0 1515969472 303634112 22798432
05:53:58 883 8192000000 10384462128 10826752000 1174925440 12.543 100 96 191 0 1493211584 296061264 22224880
05:55:02 773 8192000000 10395348592 10818838528 1172658448 12.522 100 97 186 2 1500351152 306816704 20876000
05:56:05 1075 8192000000 10436329984 10844418048 1192116592 12.703 100 97 189 1 1545977424 332115680 21745152
05:57:09 829 8192000000 10452081104 10899353600 1184828960 12.635 100 95 184 1 1554158704 345367568 23962176
05:58:13 641 8192000000 10455099440 10867404800 1191802592 12.700 100 93 183 1 1565329584 351414944 22112048
05:59:16 1375 8192000000 10477187616 10902425600 1187468288 12.660 100 76 148 2 1588015248 382767216 17779744
06:00:22 1890 8192000000 10535072000 10980388864 1183729264 12.625 100 94 186 0 1639533184 432968160 22835760
06:01:28 1443 8192000000 10548287856 10971131904 1194807664 12.728 100 78 155 0 1657311904 443681360 18822880
06:02:40 6050 8192000000 10588239200 11010187264 1183668480 12.624 100 60 115 1 1686288800 487864896 14755424
06:03:50 6106 8192000000 10595608880 11043479552 1184199184 12.629 100 49 98 0 1703103872 505650448 13254240
06:04:57 21088 8192000000 10602722192 11060563968 1155453360 12.361 99 40 80 1 1712463952 545685296 11325296
06:06:20 14019 8192000000 10507314928 11044671488 1152943280 12.337 100 47 90 0 1627479088 462191216 12344592
06:07:38 25116 8192000000 10323955488 10808008704 1140466432 12.220 99 23 46 0 1445681776 299415248 5800096
06:09:19 35961 8192000000 10194634960 10683256832 1132797408 12.148 99 16 32 0 1281840384 145209728 3833248
06:10:56 108220 8192000000 10118820544 10616160256 1107798672 11.912 97 12 24 0 1220481152 109960144 2722336
06:13:45 48441 8192000000 10141327088 10606780416 1131853408 12.139 99 26 50 1 1246119232 109057056 5208768
06:16:32 92283 8192000000 10085149728 10531545088 1125153952 12.076 98 21 42 0 1148001952 18569600 4278400
06:19:05 63933 8192000000 10110289152 10545590272 1127602224 12.099 99 35 66 0 1179723488 45340240 6781024
06:21:11 74770 8192000000 10114225168 10597728256 1123259856 12.058 99 12 24 0 1221062864 94892592 2910416
06:23:32 61278 8192000000 10107780688 10611765248 1129052592 12.112 99 18 36 0 1241778720 108405456 4320672
06:25:55 42340 8192000000 10081112144 10555158528 1103564864 11.871 97 24 50 0 1161450864 53306464 4579536
06:27:57 170702 8192000000 10072275840 10556895232 1104197296 11.877 97 9 18 0 1163797056 57672800 1926960
06:31:48 86343 8192000000 10067666224 10531713024 1102037616 11.857 97 39 72 1 1138759664 28548224 8173824
06:34:15 110639 8192000000 10069411792 10496946176 1097361856 11.813 96 43 79 1 1143233888 36738448 9133584
06:37:07 62483 8192000000 10127688176 10561040384 1131349424 12.134 98 57 111 3 1208093744 64293472 12450848
06:39:11 42640 8192000000 10105340320 10611183616 1138768208 12.204 100 9 18 0 1211205472 70681280 1755984
06:40:54 26497 8192000000 10047862784 10506825728 1126677104 12.090 100 9 18 0 1169519936 41462912 1379920
06:42:21 95346 8192000000 10055969968 10527236096 1089728384 11.740 96 10 20 0 1121166352 29490944 1947024
06:44:58 32513 8192000000 10063333952 10500075520 1110785712 11.940 98 27 52 2 1140370464 22853328 6731424
06:46:31 15823 8192000000 10112404624 10537897984 1112470928 11.956 99 65 126 0 1205429200 79492160 13466112
06:47:47 28452 8192000000 10139481456 10583310336 1127050208 12.094 99 27 52 0 1221224000 88538272 5635520
06:49:17 65266 8192000000 10080730400 10524102656 1096586032 11.805 97 13 26 0 1217257936 117458016 3213888
06:51:23 24756 8192000000 10098938224 10534969344 1093123488 11.772 97 42 84 0 1171864400 69912032 8828880
06:53:19 39020 8192000000 10149279168 10587332608 1106974368 11.904 98 43 86 0 1207273152 91484288 8814496
06:54:58 16024 8192000000 10070500784 10489188352 1121447200 12.041 99 65 115 0 1157399488 23427872 12524416
06:56:17 92344 8192000000 10095149104 10532233216 1132316352 12.143 100 45 86 0 1210186816 68631040 9239424
06:59:06 25580 8192000000 10096450528 10554023936 1121828480 12.044 99 24 48 0 1205305344 78245488 5231376
07:00:36 71976 8192000000 10093402272 10568470528 1119222288 12.020 99 10 20 0 1182512448 60915600 2374560
07:02:49 19851 8192000000 10078229792 10513612800 1123280464 12.058 99 40 75 1 1184639312 53225056 8133792
07:04:14 59645 8192000000 10137760704 10599145472 1128006848 12.103 99 19 38 0 1229490592 96707216 4776528
07:06:30 43283 8192000000 10098553792 10547466240 1117305760 12.002 98 39 78 1 1221849424 95723456 8820208
07:08:16 39114 8192000000 10138143376 10582503424 1118512576 12.013 97 33 66 0 1203797056 78451584 6832896
07:09:55 55946 8192000000 10173277360 10646228992 1130050624 12.122 98 31 62 0 1289464768 153048352 6365792
07:12:23 38415 8192000000 10168924208 10619006976 1136662608 12.184 99 40 80 0 1234232464 89558256 8011600
07:14:02 50627 8192000000 10071134512 10501709824 1115383568 11.983 98 35 66 2 1145255440 22897920 6973952
07:15:55 32961 8192000000 10144419712 10578001920 1144522512 12.258 100 49 97 0 1229413456 75187744 9703200
07:17:30 118931 8192000000 10063359312 10516807680 1114752304 11.977 98 23 40 1 1184730656 65264416 4713936
07:20:33 51895 8192000000 10087568336 10560352256 1116444640 11.993 98 28 56 0 1225339536 103064160 5830736
07:22:26 65040 8192000000 10079030576 10590593024 1123178176 12.057 99 9 18 0 1203268448 77953056 2137216
07:24:42 45260 8192000000 10069090992 10549587968 1136110528 12.179 100 15 29 0 1172258576 33239840 2908208
07:26:28 71264 8192000000 10110360720 10547355648 1127069056 12.094 99 36 72 1 1174505552 39929888 7506608
07:28:39 47520 8192000000 10099629968 10565955584 1119281568 12.020 98 25 50 0 1220195968 95364336 5550064
07:30:31 22045 8192000000 10133816096 10575663104 1132085936 12.141 99 37 74 0 1227428496 86911696 8430864
07:31:57 80415 8192000000 10060475808 10540494848 1113207584 11.963 98 30 59 0 1230725376 110097104 7420688
07:34:22 46231 8192000000 10138997024 10584113152 1098797088 11.826 96 25 50 0 1188783360 84437232 5549040
07:36:08 57048 8192000000 10092514176 10529189888 1109485600 11.928 97 28 53 0 1161061088 46450240 5125248

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 18-01-2023 07:38:19 to 18-01-2023 07:38:36.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 128 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

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

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          222114
MON$OLDEST_ACTIVE               223520
MON$OLDEST_SNAPSHOT             223520
MON$NEXT_TRANSACTION            223520
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                       2080608
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             777
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2890 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2890 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2890 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2890 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 ---
:816: 
:817: mscs-test-161.budzdorov.ru    Wed Jan 18 07:37:25 2023
:818:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:819: 
:820: 
:821: mscs-test-161.budzdorov.ru    Wed Jan 18 07:37:29 2023
:822:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:823: 
:824: 
:825: mscs-test-161.budzdorov.ru    Wed Jan 18 07:37:31 2023
:826:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:827: 
:828: 
:829: mscs-test-161.budzdorov.ru    Wed Jan 18 07:37:32 2023
:830:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:831: 
--- end of diff output ---

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

18.01.23 07:38:40. 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-01-18 07:38:43 No severe PSQL-related problems occured
18.01.23 07:38:44. End of report.