Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 22.01.2023 07: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 14982072 kB
MemAvailable 30447120 kB
Buffers 0 kB
Cached 16742708 kB
SwapCached 1644 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-22 07:37:23
DTS_END                         2023-01-22 07:37:23.5800
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/35216
TRN_ID                          213184
ATT_ID                          580
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: 8883.12 30258 1065974
customer order: creation 163.11 464 19573
customer order: refuse 15.06 489 1807
order to supplier: creation 44.16 1405 5299
order to supplier: removal 3.28 191044 394
invoice (draft): creation 44.63 3503 5356
invoice (draft): removal 50.18 1080 6021
invoice accept: apply 39.57 27954 4748
invoice accept: cancel 45.39 1419 5447
customer reserve: creation 3790.25 256 454830
customer reserve: removal 4399.13 112 527895
realization accept: apply 79.59 286 9551
realization accept: cancel 82.53 180 9904
payment from customer: creation 57.01 178 6841
payment from customer: removal 5.36 132 643
payment to supplier: creation 47.55 201 5706
payment to supplier: removal 3.83 163 460
service: total inventory turnovers 4.04 4444 485
service: total monetary turnovers 7.49 456 899
service: aggregate perf. data 0.93 15338 111
service: refresh index statistics 0.03 356046 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 22-01-2023 07:40:00 to 22-01-2023 07:40:02.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6405
TEST_TIME 2 4715
TEST_TIME 3 4742
TEST_TIME 4 5096
TEST_TIME 5 5032
TEST_TIME 6 5182
TEST_TIME 7 5132
TEST_TIME 8 5258
TEST_TIME 9 5348
TEST_TIME 10 5493
TEST_TIME 11 5570
TEST_TIME 12 5693
TEST_TIME 13 5994
TEST_TIME 14 6301
TEST_TIME 15 6414
TEST_TIME 16 6518
TEST_TIME 17 6848
TEST_TIME 18 7052
TEST_TIME 19 7289
TEST_TIME 20 7451
TEST_TIME 21 7442
TEST_TIME 22 7482
TEST_TIME 23 7941
TEST_TIME 24 7970
TEST_TIME 25 7843
TEST_TIME 26 8291
TEST_TIME 27 8283
TEST_TIME 28 8212
TEST_TIME 29 8478
TEST_TIME 30 8519
TEST_TIME 31 8775
TEST_TIME 32 8946
TEST_TIME 33 8992
TEST_TIME 34 9390
TEST_TIME 35 9569
TEST_TIME 36 9751
TEST_TIME 37 9917
TEST_TIME 38 10539
TEST_TIME 39 10634
TEST_TIME 40 10394
TEST_TIME 41 10215
TEST_TIME 42 10170
TEST_TIME 43 10020
TEST_TIME 44 9969
TEST_TIME 45 9881
TEST_TIME 46 9809
TEST_TIME 47 9675
TEST_TIME 48 9702
TEST_TIME 49 9731
TEST_TIME 50 9633
TEST_TIME 51 9543
TEST_TIME 52 9495
TEST_TIME 53 9373
TEST_TIME 54 9289
TEST_TIME 55 9205
TEST_TIME 56 9338
TEST_TIME 57 9280
TEST_TIME 58 9216
TEST_TIME 59 9244
TEST_TIME 60 9265
TEST_TIME 61 9192
TEST_TIME 62 9189
TEST_TIME 63 9131
TEST_TIME 64 9236
TEST_TIME 65 9267
TEST_TIME 66 9329
TEST_TIME 67 9313
TEST_TIME 68 9437
TEST_TIME 69 9391
TEST_TIME 70 9314
TEST_TIME 71 9209
TEST_TIME 72 9158
TEST_TIME 73 9131
TEST_TIME 74 9112
TEST_TIME 75 9111
TEST_TIME 76 9082
TEST_TIME 77 9041
TEST_TIME 78 8998
TEST_TIME 79 8900
TEST_TIME 80 8879
TEST_TIME 81 8820
TEST_TIME 82 8758
TEST_TIME 83 8817
TEST_TIME 84 8888
TEST_TIME 85 8846
TEST_TIME 86 8862
TEST_TIME 87 8835
TEST_TIME 88 8800
TEST_TIME 89 8817
TEST_TIME 90 8866
TEST_TIME 91 8904
TEST_TIME 92 8919
TEST_TIME 93 8943
TEST_TIME 94 8962
TEST_TIME 95 9038
TEST_TIME 96 9049
TEST_TIME 97 9010
TEST_TIME 98 9007
TEST_TIME 99 9001
TEST_TIME 100 8953
TEST_TIME 101 8960
TEST_TIME 102 9008
TEST_TIME 103 9001
TEST_TIME 104 8997
TEST_TIME 105 9032
TEST_TIME 106 8983
TEST_TIME 107 9059
TEST_TIME 108 9095
TEST_TIME 109 9153
TEST_TIME 110 9129
TEST_TIME 111 9063
TEST_TIME 112 9028
TEST_TIME 113 9011
TEST_TIME 114 8982
TEST_TIME 115 8956
TEST_TIME 116 8981
TEST_TIME 117 8966
TEST_TIME 118 8975
TEST_TIME 119 8972
TEST_TIME 120 8906

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 22-01-2023 07:40:03 to 22-01-2023 07:40:07.

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 1483298 1483298 0 0.00 0 23067 13 0 0
doc_list_biud 1483282 1483282 0 0.00 0 31343 46 0 0
sp_add_invoice_to_stock 4816 4748 68 1.41 92 249108 27954 0 68
sp_cancel_adding_invoice 5447 5447 0 0.00 6 87686 1419 0 0
sp_cancel_client_order 1807 1807 0 0.00 6 10806 490 0 0
sp_cancel_customer_reserve 527895 527895 0 0.00 1 26371 112 0 0
sp_cancel_pay_from_customer 643 643 0 0.00 4 1868 132 0 0
sp_cancel_pay_to_supplier 460 460 0 0.00 4 5790 163 0 0
sp_cancel_supplier_invoice 6022 6021 1 0.01 3 29233 1081 0 1
sp_cancel_supplier_order 394 394 0 0.00 12 1319248 191044 0 0
sp_cancel_write_off 9904 9904 0 0.00 2 12323 181 0 0
sp_client_order 19573 19573 0 0.00 6 26702 464 0 0
sp_customer_reserve 454830 454830 0 0.00 1 119331 257 0 0
sp_fill_shopping_cart 30220 30220 0 0.00 2 17169 88 0 0
sp_fill_shopping_cart_clo_res 454795 454795 0 0.00 0 18223 20 0 0
sp_get_clo_for_invoice 4698 4698 0 0.00 0 37226 315 0 0
sp_kill_cost_storno 16454 16454 0 0.00 0 7528 83 0 0
sp_kill_qstorno_ret_qs2qd 1072109 1072109 0 0.00 0 29222 49 0 0
sp_kill_qty_storno 565716 565716 0 0.00 0 31343 112 0 0
sp_lock_selected_doc 19975 19975 0 0.00 0 8656 9 0 0
sp_make_cost_storno 26796 26796 0 0.00 0 12754 90 0 0
sp_multiply_rows_for_pdistr 26794 26794 0 0.00 0 23028 43 0 0
sp_multiply_rows_for_qdistr 19571 19571 0 0.00 0 21476 173 0 0
sp_pay_from_customer 6841 6841 0 0.00 6 14378 179 0 0
sp_pay_to_supplier 5706 5706 0 0.00 6 23073 201 0 0
sp_payment_common 12544 12544 0 0.00 2 12755 47 0 0
sp_qd_handle_on_cancel_clo 1807 1807 0 0.00 0 10796 400 0 0
sp_qd_handle_on_invoice_upd_sts 10145 10145 0 0.00 0 31342 584 0 0
sp_qd_handle_on_reserve_upd_sts 9550 9550 0 0.00 0 10764 52 0 0
sp_reserve_write_off 9551 9551 0 0.00 8 16644 287 0 0
sp_supplier_invoice 5356 5356 0 0.00 10 83827 3504 0 0
sp_supplier_order 5299 5299 0 0.00 8 47869 1405 0 0
srv_aggregate_perf_data 112 111 1 0.89 2286 145008 15339 0 1
srv_fill_mon_cache_memory 70 70 0 0.00 562 156901 34417 0 0
srv_find_qd_qs_mism 1017690 1017690 0 0.00 0 17962 10 0 0
srv_make_invnt_saldo 485 485 0 0.00 53 119833 4444 0 0
srv_make_money_saldo 899 899 0 0.00 1 15332 457 0 0
srv_recalc_idx_stat 4 4 0 0.00 223125 521155 356047 0 0
t$perf-abend:sp_add_invoice_to_stock 68 68 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 1 1 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 4750 4750 0 0.00 1 12844 973 0 0
t$perf-norm:sp_cancel_adding_invoice 408 408 0 0.00 2 7884 567 0 0
t$perf-norm:sp_cancel_client_order 1807 1807 0 0.00 0 11084 38 0 0
t$perf-norm:sp_cancel_customer_reserve 609 609 0 0.00 0 1280 26 0 0
t$perf-norm:sp_cancel_pay_from_customer 643 643 0 0.00 0 4125 17 0 0
t$perf-norm:sp_cancel_pay_to_supplier 460 460 0 0.00 0 2382 25 0 0
t$perf-norm:sp_cancel_supplier_invoice 399 399 0 0.00 0 1265 28 0 0
t$perf-norm:sp_cancel_supplier_order 394 394 0 0.00 0 95061 8162 0 0
t$perf-norm:sp_cancel_write_off 1009 1009 0 0.00 0 1858 22 0 0
t$perf-norm:sp_client_order 19573 19573 0 0.00 0 8840 37 0 0
t$perf-norm:sp_customer_reserve 944 944 0 0.00 0 6289 29 0 0
t$perf-norm:sp_pay_from_customer 6842 6842 0 0.00 0 3508 18 0 0
t$perf-norm:sp_pay_to_supplier 5707 5707 0 0.00 0 9416 27 0 0
t$perf-norm:sp_reserve_write_off 9551 9551 0 0.00 0 8248 24 0 0
t$perf-norm:sp_supplier_invoice 5356 5356 0 0.00 0 3975 31 0 0
t$perf-norm:sp_supplier_order 5299 5299 0 0.00 0 8091 35 0 0
t$perf-norm:srv_aggregate_perf_data 111 111 0 0.00 0 1018 21 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 634 17 0 0
t$perf-norm:srv_make_invnt_saldo 485 485 0 0.00 0 5555 35 0 0
t$perf-norm:srv_make_money_saldo 899 899 0 0.00 0 2151 8 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 185 49 0 0
v_add_invoice_to_stock 9530 9394 136 1.42 0 4418 27 0 136
v_all_customers 36064 36064 0 0.00 0 78 4 0 0
v_all_suppliers 29938 29938 0 0.00 0 123 4 0 0
v_all_wares 39127 39127 0 0.00 0 117 4 0 0
v_cancel_adding_invoice 816 816 0 0.00 0 141 14 0 0
v_cancel_client_order 3614 3614 0 0.00 0 7217 13 0 0
v_cancel_customer_prepayment 1286 1286 0 0.00 0 893 13 0 0
v_cancel_customer_reserve 1218 1218 0 0.00 1 3663 16 0 0
v_cancel_payment_to_supplier 920 920 0 0.00 0 5007 24 0 0
v_cancel_supplier_invoice 800 798 2 0.25 0 1956 27 0 2
v_cancel_supplier_order 788 788 0 0.00 0 2117 18 0 0
v_cancel_write_off 2018 2018 0 0.00 0 3248 27 0 0
v_max_id_clo_ord 5299 5299 0 0.00 0 6649 6 0 0
v_max_id_clo_res 944 944 0 0.00 0 2174 14 0 0
v_max_id_ord_sup 5353 5353 0 0.00 0 9138 9 0 0
v_max_non_paid_invoice 1388 1388 0 0.00 0 1708 9 0 0
v_max_non_paid_realizn 3441 3441 0 0.00 0 694 7 0 0
v_min_id_clo_ord 5299 5299 0 0.00 0 3807 12 0 0
v_min_id_clo_res 944 944 0 0.00 0 3416 12 0 0
v_min_id_ord_sup 5353 5353 0 0.00 0 6386 10 0 0
v_min_non_paid_invoice 5705 5705 0 0.00 0 5968 16 0 0
v_min_non_paid_realizn 6839 6839 0 0.00 0 12678 19 0 0
v_our_firm 9856 9856 0 0.00 0 48 4 0 0
v_reserve_write_off 19096 19096 0 0.00 0 7998 13 0 0
x_lock_depdocs_on_canc_invoice 11069 11069 0 0.00 0 31945 151 0 0
x_lock_depdocs_on_canc_sup_ord 394 394 0 0.00 0 5316 425 0 0
x_make_qstorno_1000_1200 5299 5299 0 0.00 2 47856 1226 0 0
x_make_qstorno_1200_2000 5356 5356 0 0.00 5 83795 3250 0 0
x_make_qstorno_2100_3300 454828 454828 0 0.00 0 119323 224 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 22-01-2023 07:40:08 to 22-01-2023 07:40:12.

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 13 8192000000 8803356864 8816156672 8495984 0.103 3 2 3 0 8649744 92400 61360
05:08:28 22605 8192000000 9719641296 10016591872 794899264 8.845 100 88 176 1 824526144 7941040 21685840
05:09:56 16677 8192000000 9758766480 10067517440 834649904 9.246 100 84 169 0 867019712 12037968 20331840
05:11:16 13681 8192000000 9796853872 10113458176 869935424 9.599 100 92 184 0 909394176 18085440 21373312
05:12:34 8243 8192000000 9831801360 10164858880 897130208 9.870 100 71 140 0 947108208 34785424 15192576
05:13:48 5899 8192000000 9905817040 10245832704 945645504 10.348 100 79 152 1 1020604720 58789280 16169936
05:14:59 2667 8192000000 9941041536 10294218752 977441360 10.659 100 88 172 1 1051089472 55979136 17668976
05:16:04 1655 8192000000 9977724448 10336743424 1008165584 10.958 100 90 176 0 1108459248 82267680 18025984
05:17:08 1254 8192000000 10002836352 10366853120 1030553584 11.174 100 98 190 1 1123993200 74081568 19358048
05:18:11 994 8192000000 10024398336 10396352512 1047486336 11.337 100 94 186 2 1146519376 80858368 18174672
05:19:14 1196 8192000000 10017932992 10393624576 1055037840 11.409 100 92 176 4 1139221424 66310656 17872928
05:20:16 1043 8192000000 10045081872 10421571584 1062035088 11.476 100 98 188 1 1167881952 86780080 19066784
05:21:18 7604 8192000000 10009142048 10402643968 1067316384 11.526 100 57 106 1 1127839952 49768880 10754688
05:22:28 3845 8192000000 10049322528 10436759552 1070959168 11.561 100 69 128 1 1162823664 77755792 14108704
05:23:33 1585 8192000000 10042205040 10428813312 1083495456 11.681 100 93 171 2 1163005648 61279952 18230240
05:24:35 1230 8192000000 10050682048 10441359360 1082805424 11.674 100 77 150 1 1171290032 72964976 15519632
05:25:37 1737 8192000000 10058584368 10446290944 1084272432 11.688 100 86 167 1 1173970624 72876016 16822176
05:26:40 1135 8192000000 10091204208 10477846528 1088925984 11.732 100 97 192 0 1212763536 103758704 20078848
05:27:43 1010 8192000000 10055110528 10442833920 1091386800 11.756 100 92 181 0 1177824976 68404080 18034096
05:28:46 1759 8192000000 10032580256 10422231040 1088200832 11.726 100 88 155 8 1154450320 50587792 15661696
05:29:48 985 8192000000 10031445536 10420228096 1089055920 11.734 100 83 157 1 1151318448 45845168 16417360
05:30:50 1047 8192000000 10038489680 10422059008 1075462048 11.604 100 91 171 1 1159621696 67518240 16641408
05:31:52 5690 8192000000 10044087984 10432499712 1075436432 11.604 100 76 141 1 1157117040 67486816 14193792
05:32:59 997 8192000000 10040202880 10428567552 1074897472 11.599 100 90 171 0 1162157984 68941904 18318608
05:34:01 927 8192000000 10002916224 10373373952 1038562688 11.251 100 98 189 2 1124323344 66115056 19645600
05:35:02 1096 8192000000 9980645504 10351394816 1034877424 11.215 100 91 170 2 1098938976 46390832 17670720
05:36:06 1045 8192000000 9983244992 10355556352 1017048624 11.044 99 96 190 3 1109195664 72151136 19995904
05:37:08 878 8192000000 9980926752 10355064832 1026071856 11.131 100 89 164 0 1103421872 60605696 16744320
05:38:14 25615 8192000000 9989624624 10371727360 1033107680 11.198 99 55 108 1 1114264592 70466256 10690656
05:40:06 5765 8192000000 10026078928 10411425792 1063579984 11.491 100 68 134 0 1147553936 69899728 14074224
05:41:17 1083 8192000000 10098727264 10482372608 1082895360 11.675 100 91 180 1 1221937568 118676624 20365584
05:42:22 2974 8192000000 10112767504 10502275072 1101751072 11.854 100 80 153 1 1229408480 111322224 16335184
05:43:38 2053 8192000000 10166619664 10559135744 1113522912 11.966 100 93 184 0 1286586464 153098240 19965312
05:44:46 1137 8192000000 10214403808 10609958912 1120541920 12.032 100 93 186 0 1336667744 195701088 20424736
05:45:53 998 8192000000 10226957024 10621300736 1130687120 12.128 100 80 153 0 1340256496 193392576 16176800
05:46:58 923 8192000000 10261679488 10659704832 1147192192 12.283 100 96 183 0 1374714608 204843088 22679328
05:48:03 5052 8192000000 10313620080 10711351296 1153393088 12.341 100 80 156 0 1423170048 252141392 17635568
05:49:20 4175 8192000000 10320080864 10731339776 1171144176 12.508 100 74 147 2 1432914064 245491200 16278688
05:50:27 2111 8192000000 10314052192 10745212928 1172316480 12.518 100 90 170 0 1428456720 238245504 17894736
05:51:32 1168 8192000000 10372112976 10774831104 1190237504 12.686 100 92 177 1 1485795520 275968896 19589120
05:52:37 683 8192000000 10406485744 10811019264 1186625888 12.652 100 99 195 0 1514932976 305662592 22644496
05:53:42 1557 8192000000 10380218032 10830409728 1171677168 12.513 99 85 168 2 1492488336 300876000 19935168
05:54:49 1990 8192000000 10411635296 10832048128 1186171360 12.648 100 96 187 0 1516119744 308651824 21296560
05:55:54 2451 8192000000 10375532832 10831839232 1188169328 12.666 100 79 153 1 1502699888 296254960 18275600
05:57:03 1265 8192000000 10407474080 10828976128 1188578176 12.670 100 95 182 1 1514386464 302233248 23575040
05:58:09 2060 8192000000 10439801888 10862342144 1192592864 12.707 100 89 178 0 1549341760 335498032 21250864
05:59:16 843 8192000000 10442962464 10882744320 1186144448 12.647 100 96 192 1 1548393936 339222464 23027024
06:00:21 1335 8192000000 10475443824 10913832960 1192883104 12.710 100 86 168 1 1573372560 360364816 20124640
06:01:27 5097 8192000000 10477597360 10916986880 1193733712 12.718 100 67 129 2 1579906624 370106608 16066304
06:02:34 4491 8192000000 10499406064 10921701376 1179232704 12.583 100 66 131 3 1604915616 409666992 16015920
06:03:44 11865 8192000000 10456808080 10910736384 1184835456 12.635 100 59 110 1 1559307072 361098496 13373120
06:05:17 1757 8192000000 10439991360 10897252352 1174377104 12.538 100 78 146 1 1541009392 349430352 17201936
06:06:21 552 8192000000 10490834848 10915188736 1191300240 12.695 100 93 186 3 1588969680 375448272 22221168
06:07:27 2659 8192000000 10465682768 10929717248 1195433392 12.734 100 63 122 0 1570033296 358832464 15767440
06:08:37 6589 8192000000 10449288608 10930380800 1187692192 12.662 100 48 96 1 1558633952 358547472 12394288
06:09:49 12097 8192000000 10466058592 10901606400 1170141248 12.498 99 43 86 0 1557366560 376365760 10859552
06:11:05 18006 8192000000 10441913920 10915602432 1159193184 12.396 99 29 58 0 1536607248 369689808 7724256
06:12:41 43654 8192000000 10328722928 10811678720 1152716352 12.335 99 33 66 0 1459140016 298471856 7951808
06:14:45 39622 8192000000 10212433792 10730459136 1121699616 12.043 99 14 26 0 1373848336 248451824 3696896
06:16:30 106546 8192000000 10101152160 10533060608 1132397344 12.144 100 22 42 0 1195914800 59335888 4181568
06:19:27 156899 8192000000 10114897408 10565914624 1121209504 12.038 98 41 78 2 1155472032 26842128 7420400
06:23:32 51499 8192000000 10128180800 10564272128 1144059168 12.254 99 49 86 0 1224439968 71900704 8480096
06:25:24 43244 8192000000 10109820304 10610569216 1137432480 12.191 99 14 28 0 1239297632 98692208 3172944
06:27:12 43948 8192000000 10101368448 10553282560 1126810336 12.091 98 13 24 0 1185826864 56541904 2474624
06:29:51 146888 8192000000 10129065648 10627072000 1124268896 12.067 98 18 36 0 1242029104 113764400 3995808
06:33:28 61948 8192000000 10076534624 10525429760 1113563392 11.966 98 11 22 0 1145345760 29581008 2201360
06:35:31 46146 8192000000 10111957872 10567290880 1114734992 11.977 98 19 37 0 1183533264 65003136 3795136
06:37:18 85821 8192000000 10116644944 10564898816 1123894480 12.064 98 27 54 0 1182984016 53331408 5758128
06:39:45 16905 8192000000 10156269664 10612318208 1146988864 12.281 100 35 70 1 1234430064 80385008 7056192
06:41:04 28988 8192000000 10138366512 10632220672 1117170416 12.000 98 21 40 1 1253437760 131539568 4727776
06:42:47 115709 8192000000 10119661984 10618884096 1128857776 12.111 98 21 39 0 1208633792 75606400 4169616
06:45:47 50062 8192000000 10077629040 10534277120 1103331728 11.869 97 23 45 0 1145332592 37409712 4591152
06:47:41 101661 8192000000 10068644160 10500792320 1087020720 11.714 95 54 106 0 1160050480 62506272 10523488
06:50:23 23463 8192000000 10070628448 10501849088 1132808032 12.148 100 51 97 1 1174178752 32566560 8804160
06:51:51 27068 8192000000 10080151616 10519433216 1132451248 12.144 100 31 61 0 1186152688 47792720 5908720
06:53:20 16912 8192000000 10040961408 10475085824 1110580848 11.938 98 31 59 1 1139889600 22730624 6578128
06:54:53 24412 8192000000 10036510992 10506665984 1117310480 12.002 100 7 13 0 1157326480 38897840 1118160
06:56:29 50387 8192000000 10045425872 10480693248 1121673712 12.043 100 34 65 1 1160321680 31545968 7102000
06:58:39 54909 8192000000 10100354928 10569433088 1104781888 11.883 97 16 32 0 1169892848 61093072 4017888
07:00:40 94309 8192000000 10098573632 10564775936 1111023760 11.942 98 16 32 0 1173030128 58260208 3746160
07:03:23 41234 8192000000 10136111520 10575921152 1132275200 12.143 99 34 65 0 1230867424 91470192 7122032
07:05:09 30767 8192000000 10214202912 10645364736 1134884352 12.167 99 44 86 1 1306434096 162062544 9487200
07:06:43 25522 8192000000 10201153744 10659078144 1133952000 12.159 99 38 76 0 1332886752 189842784 9091968
07:08:17 34946 8192000000 10174800816 10621612032 1098942784 11.828 96 35 71 0 1236410528 129555904 7911840
07:10:03 36617 8192000000 10188621600 10629431296 1112360832 11.955 98 31 62 0 1254623648 135537072 6725744
07:11:47 70715 8192000000 10072440880 10573733888 1085807312 11.703 97 26 52 0 1200969920 109426592 5736016
07:15:15 52698 8192000000 10038280912 10482241536 1106706848 11.901 99 16 32 0 1139309648 29380384 3222416
07:17:09 36602 8192000000 10085973872 10514776064 1120205184 12.029 99 37 61 3 1158386480 31878656 6302640
07:19:05 64090 8192000000 10054947984 10505629696 1084730992 11.693 96 22 42 0 1147279808 58745232 3803584
07:21:10 20656 8192000000 10110825056 10550390784 1126639488 12.090 99 46 88 1 1198758496 63581344 8537664
07:22:32 41428 8192000000 10094292400 10519887872 1112619456 11.957 98 34 68 0 1174244448 54374112 7250880
07:24:22 34950 8192000000 10086668496 10600034304 1109043776 11.923 99 28 54 0 1243986512 129327792 5614944
07:26:10 44088 8192000000 10100452352 10577543168 1120874496 12.035 100 32 62 1 1176698688 49149472 6674720
07:27:55 114749 8192000000 10063038048 10498797568 1089370352 11.737 96 40 75 2 1138804352 40693216 8740784
07:30:51 41638 8192000000 10136531280 10559864832 1130625792 12.127 99 55 106 0 1228798192 86817056 11355344
07:32:34 67017 8192000000 10120784832 10599563264 1132549584 12.145 99 18 34 0 1235583216 99421072 3612560
07:34:49 27449 8192000000 10123378864 10562576384 1120949600 12.036 98 21 42 0 1207487712 81853280 4684832
07:36:17 64335 8192000000 10088604032 10544009216 1127430224 12.097 99 10 20 0 1172759808 43217584 2112000

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 22-01-2023 07:40:14 to 22-01-2023 07:40:30.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 68 v_add_invoice_to_stock 335544517
except 1 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 22-01-2023 07:40:31 to 22-01-2023 07:40:33.

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          215305
MON$OLDEST_ACTIVE               215306
MON$OLDEST_SNAPSHOT             215306
MON$NEXT_TRANSACTION            215306
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                       2073888
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             765
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 ---
:976: 
:977: mscs-test-161.budzdorov.ru    Sun Jan 22 07:37:56 2023
:978:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:979: 
:980: 
:981: mscs-test-161.budzdorov.ru    Sun Jan 22 07:37:59 2023
:982:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:983: 
--- end of diff output ---

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

22.01.23 07:40:34. 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-22 07:40:37 No severe PSQL-related problems occured
22.01.23 07:40:38. End of report.