Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 07.12.2022 13:37
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 14239300 kB
MemAvailable 30101868 kB
Buffers 0 kB
Cached 17430268 kB
SwapCached 1716 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-12-07 13:37:23
DTS_END                         2022-12-07 13:37:23.3370
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/41578
TRN_ID                          208887
ATT_ID                          556
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: 9120.08 24016 1094410
customer order: creation 163.30 547 19596
customer order: refuse 13.66 497 1639
order to supplier: creation 43.29 1525 5195
order to supplier: removal 3.49 181515 419
invoice (draft): creation 42.88 3668 5145
invoice (draft): removal 52.43 1033 6292
invoice accept: apply 39.53 28617 4744
invoice accept: cancel 46.90 1398 5628
customer reserve: creation 3723.27 264 446792
customer reserve: removal 4709.59 105 565151
realization accept: apply 78.53 317 9424
realization accept: cancel 79.98 168 9598
payment from customer: creation 55.17 215 6620
payment from customer: removal 5.26 152 631
payment to supplier: creation 47.03 234 5643
payment to supplier: removal 3.15 157 378
service: total inventory turnovers 3.87 4777 464
service: total monetary turnovers 7.67 506 920
service: aggregate perf. data 1.05 15841 126
service: refresh index statistics 0.04 238774 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 07-12-2022 13:38:13 to 07-12-2022 13:38:15.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8163
TEST_TIME 2 6761
TEST_TIME 3 6312
TEST_TIME 4 6749
TEST_TIME 5 6346
TEST_TIME 6 6607
TEST_TIME 7 6202
TEST_TIME 8 6263
TEST_TIME 9 6017
TEST_TIME 10 6105
TEST_TIME 11 6092
TEST_TIME 12 6415
TEST_TIME 13 6518
TEST_TIME 14 6559
TEST_TIME 15 6751
TEST_TIME 16 7033
TEST_TIME 17 7227
TEST_TIME 18 7407
TEST_TIME 19 7410
TEST_TIME 20 7451
TEST_TIME 21 7444
TEST_TIME 22 7410
TEST_TIME 23 7504
TEST_TIME 24 7565
TEST_TIME 25 7685
TEST_TIME 26 7639
TEST_TIME 27 7995
TEST_TIME 28 8052
TEST_TIME 29 7991
TEST_TIME 30 8183
TEST_TIME 31 8511
TEST_TIME 32 8742
TEST_TIME 33 8830
TEST_TIME 34 8923
TEST_TIME 35 8886
TEST_TIME 36 9120
TEST_TIME 37 9768
TEST_TIME 38 9857
TEST_TIME 39 9977
TEST_TIME 40 10154
TEST_TIME 41 10472
TEST_TIME 42 10599
TEST_TIME 43 10732
TEST_TIME 44 10757
TEST_TIME 45 10575
TEST_TIME 46 10771
TEST_TIME 47 10580
TEST_TIME 48 10426
TEST_TIME 49 10258
TEST_TIME 50 10239
TEST_TIME 51 10088
TEST_TIME 52 10003
TEST_TIME 53 9895
TEST_TIME 54 9908
TEST_TIME 55 9977
TEST_TIME 56 9896
TEST_TIME 57 9841
TEST_TIME 58 9819
TEST_TIME 59 9892
TEST_TIME 60 9813
TEST_TIME 61 9775
TEST_TIME 62 9683
TEST_TIME 63 9598
TEST_TIME 64 9561
TEST_TIME 65 9553
TEST_TIME 66 9477
TEST_TIME 67 9410
TEST_TIME 68 9419
TEST_TIME 69 9494
TEST_TIME 70 9490
TEST_TIME 71 9507
TEST_TIME 72 9511
TEST_TIME 73 9510
TEST_TIME 74 9584
TEST_TIME 75 9622
TEST_TIME 76 9561
TEST_TIME 77 9597
TEST_TIME 78 9607
TEST_TIME 79 9496
TEST_TIME 80 9452
TEST_TIME 81 9471
TEST_TIME 82 9406
TEST_TIME 83 9443
TEST_TIME 84 9382
TEST_TIME 85 9322
TEST_TIME 86 9286
TEST_TIME 87 9240
TEST_TIME 88 9177
TEST_TIME 89 9223
TEST_TIME 90 9252
TEST_TIME 91 9213
TEST_TIME 92 9184
TEST_TIME 93 9229
TEST_TIME 94 9240
TEST_TIME 95 9240
TEST_TIME 96 9299
TEST_TIME 97 9340
TEST_TIME 98 9341
TEST_TIME 99 9318
TEST_TIME 100 9359
TEST_TIME 101 9365
TEST_TIME 102 9378
TEST_TIME 103 9325
TEST_TIME 104 9301
TEST_TIME 105 9228
TEST_TIME 106 9281
TEST_TIME 107 9222
TEST_TIME 108 9207
TEST_TIME 109 9162
TEST_TIME 110 9120
TEST_TIME 111 9078
TEST_TIME 112 9054
TEST_TIME 113 9093
TEST_TIME 114 9158
TEST_TIME 115 9149
TEST_TIME 116 9168
TEST_TIME 117 9136
TEST_TIME 118 9114
TEST_TIME 119 9190
TEST_TIME 120 9143

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 07-12-2022 13:38:16 to 07-12-2022 13:38:20.

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 1506398 1506398 0 0.00 0 16067 15 0 0
doc_list_biud 1506390 1506390 0 0.00 0 38427 46 0 0
sp_add_invoice_to_stock 4831 4744 87 1.80 39 234608 28617 0 87
sp_cancel_adding_invoice 5628 5628 0 0.00 6 115493 1398 0 0
sp_cancel_client_order 1639 1639 0 0.00 5 8313 497 0 0
sp_cancel_customer_reserve 565151 565151 0 0.00 1 22419 106 0 0
sp_cancel_pay_from_customer 631 631 0 0.00 3 2637 152 0 0
sp_cancel_pay_to_supplier 378 378 0 0.00 4 3867 158 0 0
sp_cancel_supplier_invoice 6304 6292 12 0.19 3 18100 1033 0 12
sp_cancel_supplier_order 419 419 0 0.00 8 1815267 181516 0 0
sp_cancel_write_off 9598 9598 0 0.00 2 10564 169 0 0
sp_client_order 19596 19596 0 0.00 5 27071 547 0 0
sp_customer_reserve 446792 446792 0 0.00 0 75565 264 0 0
sp_fill_shopping_cart 29928 29928 0 0.00 1 21799 98 0 0
sp_fill_shopping_cart_clo_res 446760 446760 0 0.00 0 13232 19 0 0
sp_get_clo_for_invoice 4710 4710 0 0.00 0 26044 298 0 0
sp_kill_cost_storno 16235 16235 0 0.00 0 7259 81 0 0
sp_kill_qstorno_ret_qs2qd 1146611 1146611 0 0.00 0 35853 45 0 0
sp_kill_qty_storno 602861 602861 0 0.00 0 38427 105 0 0
sp_lock_selected_doc 19584 19584 0 0.00 0 4426 7 0 0
sp_make_cost_storno 26397 26397 0 0.00 0 15423 97 0 0
sp_multiply_rows_for_pdistr 26397 26397 0 0.00 0 6268 44 0 0
sp_multiply_rows_for_qdistr 19596 19596 0 0.00 0 15164 202 0 0
sp_pay_from_customer 6620 6620 0 0.00 6 16668 216 0 0
sp_pay_to_supplier 5643 5643 0 0.00 7 14388 235 0 0
sp_payment_common 12263 12263 0 0.00 2 7490 54 0 0
sp_qd_handle_on_cancel_clo 1639 1639 0 0.00 0 8306 409 0 0
sp_qd_handle_on_invoice_upd_sts 10338 10338 0 0.00 0 38427 641 0 0
sp_qd_handle_on_reserve_upd_sts 9424 9424 0 0.00 0 13677 71 0 0
sp_reserve_write_off 9424 9424 0 0.00 9 17659 318 0 0
sp_supplier_invoice 5145 5145 0 0.00 7 171187 3668 0 0
sp_supplier_order 5195 5195 0 0.00 7 25618 1525 0 0
srv_aggregate_perf_data 127 126 1 0.78 2226 292549 15841 0 1
srv_fill_mon_cache_memory 74 74 0 0.00 762 109770 32414 0 0
srv_find_qd_qs_mism 1048006 1048006 0 0.00 0 11663 10 0 0
srv_make_invnt_saldo 464 464 0 0.00 92 82919 4778 0 0
srv_make_money_saldo 920 920 0 0.00 1 50799 506 0 0
srv_recalc_idx_stat 5 5 0 0.00 55096 452822 238774 0 0
t$perf-abend:sp_add_invoice_to_stock 87 87 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-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4745 4745 0 0.00 0 10866 1246 0 0
t$perf-norm:sp_cancel_adding_invoice 374 374 0 0.00 1 6155 669 0 0
t$perf-norm:sp_cancel_client_order 1639 1639 0 0.00 0 1395 34 0 0
t$perf-norm:sp_cancel_customer_reserve 648 648 0 0.00 0 775 36 0 0
t$perf-norm:sp_cancel_pay_from_customer 631 631 0 0.00 0 637 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 378 378 0 0.00 0 4203 29 0 0
t$perf-norm:sp_cancel_supplier_invoice 436 436 0 0.00 0 12530 68 0 0
t$perf-norm:sp_cancel_supplier_order 419 419 0 0.00 0 139079 7964 0 0
t$perf-norm:sp_cancel_write_off 927 927 0 0.00 0 1110 21 0 0
t$perf-norm:sp_client_order 19596 19596 0 0.00 0 52724 55 0 0
t$perf-norm:sp_customer_reserve 983 983 0 0.00 0 3195 20 0 0
t$perf-norm:sp_pay_from_customer 6620 6620 0 0.00 0 7731 24 0 0
t$perf-norm:sp_pay_to_supplier 5643 5643 0 0.00 0 2073 22 0 0
t$perf-norm:sp_reserve_write_off 9424 9424 0 0.00 0 7211 28 0 0
t$perf-norm:sp_supplier_invoice 5145 5145 0 0.00 0 4038 38 0 0
t$perf-norm:sp_supplier_order 5195 5195 0 0.00 0 36809 49 0 0
t$perf-norm:srv_aggregate_perf_data 126 126 0 0.00 0 1003 26 0 0
t$perf-norm:srv_fill_mon_cache_memory 74 74 0 0.00 0 54 4 0 0
t$perf-norm:srv_make_invnt_saldo 464 464 0 0.00 0 3747 23 0 0
t$perf-norm:srv_make_money_saldo 920 920 0 0.00 0 2478 7 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 7 199 97 0 0
v_add_invoice_to_stock 9590 9416 174 1.81 0 6135 26 0 174
v_all_customers 35640 35640 0 0.00 0 72 5 0 0
v_all_suppliers 29093 29093 0 0.00 0 106 5 0 0
v_all_wares 39190 39190 0 0.00 0 192 5 0 0
v_cancel_adding_invoice 748 748 0 0.00 0 2160 20 0 0
v_cancel_client_order 3278 3278 0 0.00 0 4526 13 0 0
v_cancel_customer_prepayment 1262 1262 0 0.00 0 1481 14 0 0
v_cancel_customer_reserve 1296 1296 0 0.00 1 221 12 0 0
v_cancel_payment_to_supplier 756 756 0 0.00 0 3229 18 0 0
v_cancel_supplier_invoice 896 872 24 2.67 0 564 23 0 24
v_cancel_supplier_order 838 838 0 0.00 0 429 12 0 0
v_cancel_write_off 1854 1854 0 0.00 0 4412 27 0 0
v_max_id_clo_ord 5195 5195 0 0.00 0 745 6 0 0
v_max_id_clo_res 983 983 0 0.00 0 2666 15 0 0
v_max_id_ord_sup 5137 5137 0 0.00 0 3043 8 0 0
v_max_non_paid_invoice 1428 1428 0 0.00 0 239 10 0 0
v_max_non_paid_realizn 3422 3422 0 0.00 0 1021 10 0 0
v_min_id_clo_ord 5195 5195 0 0.00 0 3948 11 0 0
v_min_id_clo_res 983 983 0 0.00 0 925 11 0 0
v_min_id_ord_sup 5137 5137 0 0.00 0 12027 12 0 0
v_min_non_paid_invoice 5643 5643 0 0.00 0 5332 16 0 0
v_min_non_paid_realizn 6619 6619 0 0.00 0 5031 17 0 0
v_our_firm 9938 9938 0 0.00 0 65 6 0 0
v_reserve_write_off 18846 18846 0 0.00 0 7958 14 0 0
x_lock_depdocs_on_canc_invoice 11484 11484 0 0.00 0 19515 144 0 0
x_lock_depdocs_on_canc_sup_ord 419 419 0 0.00 0 47233 516 0 0
x_make_qstorno_1000_1200 5195 5195 0 0.00 2 25558 1308 0 0
x_make_qstorno_1200_2000 5145 5145 0 0.00 3 170314 3420 0 0
x_make_qstorno_2100_3300 446790 446790 0 0.00 0 75539 229 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 07-12-2022 13:38:21 to 07-12-2022 13:38:25.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
11:07:27 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
11:08:27 29084 8192000000 9720696480 10019426304 795374704 8.849 100 89 178 0 825434208 7888048 22171456
11:10:02 25127 8192000000 9751806528 10059411456 827088752 9.170 100 92 184 0 862310176 12724288 22497136
11:11:32 14413 8192000000 9791216432 10107293696 855070512 9.451 100 93 182 1 899449184 22969152 21409520
11:12:55 8644 8192000000 9852467760 10180456448 901857984 9.917 100 80 152 0 954058752 35210256 16990512
11:14:06 2586 8192000000 9890742432 10230820864 943946000 10.332 100 88 171 1 1015091456 53354592 17790864
11:15:13 2369 8192000000 9948766000 10295865344 982665344 10.710 100 87 166 1 1063804496 63613680 17525472
11:16:17 1933 8192000000 9969877328 10328895488 1003008480 10.908 100 82 157 1 1091824144 72912432 15903232
11:17:21 1291 8192000000 10011815328 10374639616 1029891008 11.167 100 95 187 1 1129654224 81153840 18609376
11:18:26 1005 8192000000 10026160688 10397089792 1047804832 11.340 100 96 184 0 1140197472 73841072 18551568
11:19:28 1485 8192000000 10042630768 10416283648 1057315616 11.431 100 96 181 1 1158406832 82748384 18342832
11:20:32 842 8192000000 10030076528 10408767488 1067984064 11.533 100 95 181 1 1147677872 62402576 17291232
11:21:35 1257 8192000000 10042562752 10418630656 1073717232 11.588 100 91 173 0 1159594144 68589952 17286960
11:22:37 2292 8192000000 10038767920 10425327616 1074885088 11.599 100 78 151 1 1159176224 67683872 16607264
11:23:40 1414 8192000000 10046408128 10441707520 1077620400 11.625 100 67 131 1 1172108976 81718096 12770480
11:24:44 1301 8192000000 10040550272 10423685120 1082865424 11.675 100 93 177 0 1159785424 59009872 17910128
11:25:46 2892 8192000000 10036383920 10427359232 1082999024 11.676 100 77 146 1 1157177488 59262080 14916384
11:26:52 1077 8192000000 10040247744 10425466880 1089267856 11.736 100 89 165 1 1162147360 56837456 16042048
11:27:55 1338 8192000000 10077800144 10461712384 1081053520 11.658 99 98 190 0 1198557312 98688480 18815312
11:28:57 1134 8192000000 10042678400 10427543552 1090237104 11.745 100 96 176 2 1166297840 57163200 18897536
11:30:00 1455 8192000000 10050437984 10441678848 1092736208 11.769 100 84 152 2 1173947856 64680192 16531456
11:31:03 1325 8192000000 10045472080 10430320640 1072144576 11.573 100 95 186 3 1168499296 76590016 19764704
11:32:05 1083 8192000000 9991376416 10370924544 1051424160 11.374 100 84 140 2 1114131408 49381328 13325920
11:33:07 2072 8192000000 9965986080 10343264256 1033377696 11.201 99 64 121 1 1080937024 35237200 12322128
11:34:11 1273 8192000000 9962559600 10333290496 1023518656 11.106 98 96 175 1 1087886640 46407296 17960688
11:35:14 6127 8192000000 9975116640 10360451072 1042165008 11.285 100 62 119 1 1113435360 59616960 11653392
11:36:24 1384 8192000000 9971109520 10344886272 1033798144 11.205 99 82 160 3 1094345456 44536320 16010992
11:37:26 2125 8192000000 10007889856 10392420352 1044398448 11.307 100 63 112 4 1124400512 69062752 10939312
11:38:36 14524 8192000000 10002964928 10386857984 1066310224 11.517 100 60 114 0 1118567472 41161616 11095632
11:39:58 1622 8192000000 10035624720 10419548160 1079632480 11.644 100 95 180 1 1157295520 58080736 19582304
11:41:05 2224 8192000000 10089308464 10475425792 1097969344 11.818 100 90 171 0 1207057296 91545344 17542608
11:42:12 1653 8192000000 10103197632 10492665856 1108670848 11.920 100 86 164 0 1220720896 95139072 16910976
11:43:18 2328 8192000000 10130410464 10521309184 1108982576 11.923 100 72 136 1 1247497584 124362816 14152192
11:44:23 9269 8192000000 10115066128 10513571840 1117071008 11.999 100 63 114 1 1232224544 102717504 12436032
11:45:36 9789 8192000000 10138274944 10550345728 1124829424 12.073 100 61 116 1 1255900528 118323488 12747616
11:46:51 10399 8192000000 10181998144 10591477760 1133140080 12.151 100 61 113 1 1291846816 146504592 12202144
11:48:10 4187 8192000000 10201755952 10609303552 1138269232 12.199 100 68 136 0 1318124816 164822688 15032896
11:49:17 834 8192000000 10235364928 10633658368 1147883168 12.290 100 97 189 1 1347430832 178194224 21353440
11:50:22 1381 8192000000 10258892544 10661134336 1156049536 12.366 100 88 169 0 1372353184 197426304 18877344
11:51:26 2001 8192000000 10301449440 10707079168 1164718848 12.447 100 89 175 1 1409995792 226418448 18858496
11:52:33 1248 8192000000 10306075808 10724868096 1170676672 12.503 100 98 183 0 1408310832 217597872 20036288
11:53:38 1333 8192000000 10293712320 10714243072 1176256368 12.555 100 97 185 0 1406444368 207850320 22337680
11:54:43 1026 8192000000 10337736304 10758930432 1187746784 12.662 100 98 190 0 1446701200 237343296 21611120
11:55:47 856 8192000000 10354399952 10755518464 1186998912 12.655 100 99 197 2 1461832688 253522656 21311120
11:56:52 1146 8192000000 10392199712 10795409408 1186739360 12.653 100 95 184 1 1499762160 291360224 21662576
11:57:58 1952 8192000000 10426395040 10831757312 1179419168 12.585 100 94 186 2 1532251264 331380272 21451824
11:59:04 953 8192000000 10472169440 10876067840 1193555904 12.716 100 95 185 4 1573407600 357864384 21987312
12:00:13 918 8192000000 10480857680 10885496832 1183878176 12.626 100 94 187 1 1589599664 383043056 22678432
12:01:16 2066 8192000000 10513865024 10923016192 1190283984 12.686 100 92 177 0 1621274752 410241552 20749216
12:02:20 753 8192000000 10526098320 10971295744 1193410976 12.715 100 98 192 0 1628044176 412707696 21925504
12:03:24 1289 8192000000 10526791136 10966532096 1159745088 12.401 100 93 184 0 1627065120 444092992 23227040
12:04:28 1882 8192000000 10547704000 10984079360 1186055072 12.647 100 84 166 1 1655610576 449191440 20364064
12:05:37 802 8192000000 10544805904 10968797184 1164181344 12.442 100 97 191 1 1650039728 462431488 23426896
12:06:40 857 8192000000 10547046384 10978037760 1165782352 12.457 100 98 190 1 1649548640 459460832 24305456
12:07:44 896 8192000000 10563536656 11039313920 1183592320 12.624 100 93 184 1 1664744944 458402496 22750128
12:08:47 3667 8192000000 10548588336 10993946624 1189943104 12.683 100 81 148 1 1648483248 441240272 17299872
12:09:52 4097 8192000000 10514770304 10986827776 1183966880 12.627 99 55 108 0 1618425584 420909200 13549504
12:11:04 22307 8192000000 10522405504 10984722432 1179353200 12.584 99 37 74 0 1636876768 447904368 9619200
12:12:30 8393 8192000000 10537401040 11015593984 1173055792 12.525 100 55 103 0 1646148240 458287136 14805312
12:13:46 7686 8192000000 10494570256 10981924864 1181548256 12.605 100 50 93 1 1596118784 402716064 11854464
12:15:01 10921 8192000000 10386325392 10845634560 1181539264 12.605 100 34 68 0 1484657760 294393808 8724688
12:16:31 38273 8192000000 10305877136 10795352064 1161764480 12.420 99 25 48 0 1426141936 258506048 5871408
12:18:11 35612 8192000000 10265066096 10790744064 1165181056 12.452 100 17 34 0 1408106848 238535888 4389904
12:19:48 105306 8192000000 10150905824 10615005184 1155306384 12.359 99 17 32 1 1266777088 107889552 3581152
12:22:48 28638 8192000000 10185206384 10647236608 1150422992 12.313 99 33 64 1 1267497184 110056688 7017504
12:24:26 109768 8192000000 10094017040 10537816064 1159117232 12.395 100 51 89 0 1196464528 28530080 8817216
12:27:24 43362 8192000000 10118464752 10604584960 1156880496 12.374 100 15 30 0 1218801632 58829280 3091856
12:29:23 12241 8192000000 10130830288 10594164736 1148221696 12.293 100 40 76 1 1231181104 73764624 9194784
12:30:38 38568 8192000000 10188658176 10655047680 1142182368 12.236 99 29 56 0 1282091680 133811312 6098000
12:32:17 88701 8192000000 10136023632 10605191168 1147867440 12.289 99 20 41 0 1222797472 70353232 4576800
12:34:55 64410 8192000000 10146873616 10618417152 1159821168 12.402 100 18 36 0 1236386688 72285760 4279760
12:37:01 45098 8192000000 10103316448 10577760256 1154446112 12.351 100 16 32 1 1213927536 56183504 3297920
12:39:20 39193 8192000000 10073484368 10574643200 1133289712 12.152 99 16 32 1 1174963552 38644224 3029616
12:41:00 76407 8192000000 10086942080 10552168448 1131072064 12.131 99 7 14 1 1175031536 42864064 1095408
12:43:20 44462 8192000000 10106172224 10563239936 1121437248 12.041 98 12 23 0 1176117520 52736544 1943728
12:45:05 16772 8192000000 10151481232 10600394752 1148301600 12.294 100 62 120 0 1238839216 78021072 12516544
12:46:22 59141 8192000000 10111623072 10598817792 1157108064 12.376 100 27 54 1 1233465360 70832832 5524464
12:48:24 38747 8192000000 10134648608 10597658624 1142044784 12.235 99 31 63 0 1216806128 68253600 6507744
12:50:04 98725 8192000000 10132346816 10637475840 1120901424 12.036 97 25 50 0 1208159232 81974688 5283120
12:52:51 40428 8192000000 10078968512 10513166336 1122488640 12.050 98 34 68 0 1160888544 31061600 7338304
12:54:35 78516 8192000000 10106605360 10575654912 1134080560 12.160 99 35 70 0 1189827168 49102976 6643632
12:56:54 75411 8192000000 10101611616 10551988224 1091828864 11.760 96 25 50 0 1145307872 48689328 4789680
12:59:09 33470 8192000000 10102917648 10562547712 1104674704 11.882 97 33 64 1 1178184320 67035600 6474016
13:00:53 57414 8192000000 10091687744 10553159680 1141400784 12.229 100 8 14 0 1186039248 43038448 1600016
13:02:52 66186 8192000000 10123634256 10591019008 1126616112 12.089 98 11 22 0 1214326672 85031168 2679392
13:05:03 75513 8192000000 10114601808 10644402176 1137085184 12.188 99 10 20 0 1206798304 67395344 2317776
13:07:19 65953 8192000000 10117367696 10592296960 1124070192 12.065 98 16 32 0 1212526448 84852416 3603840
13:09:26 54823 8192000000 10139031824 10612998144 1135437216 12.173 99 25 50 0 1238147792 96879968 5830608
13:11:25 31982 8192000000 10146408816 10576433152 1099697360 11.835 95 31 61 1 1195410256 89358512 6354384
13:12:58 79434 8192000000 10074825312 10563133440 1116933184 11.998 98 20 40 0 1219657072 98218736 4505152
13:15:18 34575 8192000000 10133248864 10605965312 1135787744 12.176 99 28 56 0 1206809472 65538160 5483568
13:17:01 47745 8192000000 10101310576 10546778112 1138742176 12.204 99 37 63 1 1214089632 69440064 5907392
13:18:50 29467 8192000000 10139117008 10593468416 1139065872 12.207 99 54 106 0 1228704144 79133888 10504384
13:20:20 103225 8192000000 10105342272 10575695872 1152030624 12.329 100 32 64 1 1205319120 46391360 6897136
13:23:04 93060 8192000000 10096456640 10628825088 1140567280 12.221 99 16 33 1 1196663296 52599264 3496752
13:25:48 58826 8192000000 10097618688 10547449856 1131642736 12.137 98 23 44 0 1171656608 35521520 4492352
13:27:54 62157 8192000000 10143276992 10613329920 1142102960 12.235 99 18 36 0 1246886480 100517200 4266320
13:30:13 80900 8192000000 10106677024 10641068032 1135300992 12.171 98 18 36 0 1236433248 96767728 4364528
13:32:44 36634 8192000000 10115074064 10581606400 1141507728 12.230 99 26 51 0 1209477568 62774352 5195488
13:34:36 23252 8192000000 10113395056 10556796928 1137810608 12.195 99 42 84 0 1195179632 48478432 8890592
13:36:01 48444 8192000000 10091937616 10568343552 1129414976 12.116 99 32 65 0 1249841168 113866864 6559328

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 07-12-2022 13:38:27 to 07-12-2022 13:38:43.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 87 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 07-12-2022 13:38:44 to 07-12-2022 13:38:46.

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          206764
MON$OLDEST_ACTIVE               211061
MON$OLDEST_SNAPSHOT             211061
MON$NEXT_TRANSACTION            211061
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                       2133632
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             757
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2882 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2882 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2882 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2882 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 ---
:92: 
:93: mscs-test-161.budzdorov.ru    Wed Dec  7 13:37:25 2022
:94:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:95: 
:96: 
:97: mscs-test-161.budzdorov.ru    Wed Dec  7 13:37:33 2022
:98:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:99: 
:100: 
:101: mscs-test-161.budzdorov.ru    Wed Dec  7 13:37:34 2022
:102:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:103: 
:104: 
:105: mscs-test-161.budzdorov.ru    Wed Dec  7 13:37:34 2022
:106:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:107: 
--- end of diff output ---

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

07.12.22 13:38:47. 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-12-07 13:38:50 No severe PSQL-related problems occured
07.12.22 13:38:51. End of report.