Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 15.12.2022 10:38
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 14529448 kB
MemAvailable 30103772 kB
Buffers 0 kB
Cached 17193476 kB
SwapCached 3392 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-15 10:37:23
DTS_END                         2022-12-15 10:37:23.7330
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_RESERVE_WRITE_OFF(34:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/49490
TRN_ID                          223150
ATT_ID                          645
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: 8937.16 21378 1072459
customer order: creation 172.52 426 20702
customer order: refuse 15.26 369 1831
order to supplier: creation 45.24 1296 5429
order to supplier: removal 3.24 163949 389
invoice (draft): creation 45.73 3109 5487
invoice (draft): removal 48.63 1002 5835
invoice accept: apply 42.39 24297 5087
invoice accept: cancel 43.64 1199 5237
customer reserve: creation 3978.25 226 477390
customer reserve: removal 4255.14 98 510617
realization accept: apply 84.19 286 10103
realization accept: cancel 73.02 158 8762
payment from customer: creation 59.37 170 7124
payment from customer: removal 6.16 115 739
payment to supplier: creation 48.98 187 5877
payment to supplier: removal 3.22 121 386
service: total inventory turnovers 3.93 4024 472
service: total monetary turnovers 7.32 351 878
service: aggregate perf. data 0.91 16289 109
service: refresh index statistics 0.04 209893 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 15-12-2022 10:38:52 to 15-12-2022 10:38:54.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4917
TEST_TIME 2 6295
TEST_TIME 3 5816
TEST_TIME 4 5866
TEST_TIME 5 6140
TEST_TIME 6 5668
TEST_TIME 7 5896
TEST_TIME 8 6279
TEST_TIME 9 6441
TEST_TIME 10 7260
TEST_TIME 11 7381
TEST_TIME 12 7316
TEST_TIME 13 7379
TEST_TIME 14 7568
TEST_TIME 15 7545
TEST_TIME 16 7583
TEST_TIME 17 8052
TEST_TIME 18 8350
TEST_TIME 19 8399
TEST_TIME 20 8310
TEST_TIME 21 8844
TEST_TIME 22 8816
TEST_TIME 23 8835
TEST_TIME 24 8819
TEST_TIME 25 8902
TEST_TIME 26 8997
TEST_TIME 27 9284
TEST_TIME 28 9506
TEST_TIME 29 9889
TEST_TIME 30 10538
TEST_TIME 31 10555
TEST_TIME 32 10850
TEST_TIME 33 10961
TEST_TIME 34 11422
TEST_TIME 35 11207
TEST_TIME 36 11076
TEST_TIME 37 10875
TEST_TIME 38 10719
TEST_TIME 39 10791
TEST_TIME 40 10863
TEST_TIME 41 10737
TEST_TIME 42 10662
TEST_TIME 43 10545
TEST_TIME 44 10485
TEST_TIME 45 10410
TEST_TIME 46 10291
TEST_TIME 47 10249
TEST_TIME 48 10182
TEST_TIME 49 10176
TEST_TIME 50 10309
TEST_TIME 51 10259
TEST_TIME 52 10186
TEST_TIME 53 10047
TEST_TIME 54 9921
TEST_TIME 55 9838
TEST_TIME 56 9768
TEST_TIME 57 9629
TEST_TIME 58 9719
TEST_TIME 59 9641
TEST_TIME 60 9531
TEST_TIME 61 9596
TEST_TIME 62 9486
TEST_TIME 63 9418
TEST_TIME 64 9432
TEST_TIME 65 9412
TEST_TIME 66 9431
TEST_TIME 67 9503
TEST_TIME 68 9503
TEST_TIME 69 9475
TEST_TIME 70 9477
TEST_TIME 71 9451
TEST_TIME 72 9552
TEST_TIME 73 9526
TEST_TIME 74 9544
TEST_TIME 75 9546
TEST_TIME 76 9561
TEST_TIME 77 9508
TEST_TIME 78 9564
TEST_TIME 79 9555
TEST_TIME 80 9637
TEST_TIME 81 9602
TEST_TIME 82 9485
TEST_TIME 83 9552
TEST_TIME 84 9466
TEST_TIME 85 9467
TEST_TIME 86 9459
TEST_TIME 87 9431
TEST_TIME 88 9405
TEST_TIME 89 9361
TEST_TIME 90 9341
TEST_TIME 91 9297
TEST_TIME 92 9214
TEST_TIME 93 9213
TEST_TIME 94 9218
TEST_TIME 95 9226
TEST_TIME 96 9192
TEST_TIME 97 9178
TEST_TIME 98 9188
TEST_TIME 99 9219
TEST_TIME 100 9201
TEST_TIME 101 9254
TEST_TIME 102 9258
TEST_TIME 103 9243
TEST_TIME 104 9236
TEST_TIME 105 9328
TEST_TIME 106 9288
TEST_TIME 107 9316
TEST_TIME 108 9358
TEST_TIME 109 9277
TEST_TIME 110 9227
TEST_TIME 111 9210
TEST_TIME 112 9169
TEST_TIME 113 9132
TEST_TIME 114 9105
TEST_TIME 115 9050
TEST_TIME 116 9041
TEST_TIME 117 9021
TEST_TIME 118 9010
TEST_TIME 119 9019
TEST_TIME 120 8961

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 15-12-2022 10:38:56 to 15-12-2022 10:38:59.

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 1509456 1509456 0 0.00 0 18212 13 0 0
doc_list_biud 1509444 1509444 0 0.00 0 36443 39 0 0
sp_add_invoice_to_stock 5204 5087 117 2.24 52 183724 24298 0 117
sp_cancel_adding_invoice 5237 5237 0 0.00 4 88218 1199 0 0
sp_cancel_client_order 1831 1831 0 0.00 5 8882 370 0 0
sp_cancel_customer_reserve 510617 510617 0 0.00 1 36448 98 0 0
sp_cancel_pay_from_customer 739 739 0 0.00 4 5329 116 0 0
sp_cancel_pay_to_supplier 386 386 0 0.00 3 2164 122 0 0
sp_cancel_supplier_invoice 5843 5835 8 0.13 3 21615 1003 0 8
sp_cancel_supplier_order 389 389 0 0.00 17 1360382 163949 0 0
sp_cancel_write_off 8762 8762 0 0.00 2 10782 158 0 0
sp_client_order 20702 20702 0 0.00 5 18346 427 0 0
sp_customer_reserve 477390 477390 0 0.00 0 132005 227 0 0
sp_fill_shopping_cart 31604 31604 0 0.00 1 30406 86 0 0
sp_fill_shopping_cart_clo_res 477361 477361 0 0.00 0 14914 18 0 0
sp_get_clo_for_invoice 5047 5047 0 0.00 0 28077 307 0 0
sp_kill_cost_storno 15124 15124 0 0.00 0 14551 69 0 0
sp_kill_qstorno_ret_qs2qd 1036220 1036220 0 0.00 0 36441 43 0 0
sp_kill_qty_storno 547818 547818 0 0.00 0 36443 99 0 0
sp_lock_selected_doc 20953 20953 0 0.00 0 7717 7 0 0
sp_make_cost_storno 28150 28150 0 0.00 0 18169 81 0 0
sp_multiply_rows_for_pdistr 28148 28148 0 0.00 0 10312 37 0 0
sp_multiply_rows_for_qdistr 20700 20700 0 0.00 0 12128 158 0 0
sp_pay_from_customer 7124 7124 0 0.00 5 14896 170 0 0
sp_pay_to_supplier 5877 5877 0 0.00 6 18335 187 0 0
sp_payment_common 13000 13000 0 0.00 2 11042 45 0 0
sp_qd_handle_on_cancel_clo 1831 1831 0 0.00 0 8874 293 0 0
sp_qd_handle_on_invoice_upd_sts 10284 10284 0 0.00 0 36042 517 0 0
sp_qd_handle_on_reserve_upd_sts 10100 10100 0 0.00 0 32834 77 0 0
sp_reserve_write_off 10103 10103 0 0.00 8 33017 287 0 0
sp_supplier_invoice 5487 5487 0 0.00 9 63888 3109 0 0
sp_supplier_order 5429 5429 0 0.00 8 29807 1297 0 0
srv_aggregate_perf_data 110 109 1 0.90 2334 182809 16290 0 1
srv_fill_mon_cache_memory 69 69 0 0.00 886 153787 37468 0 0
srv_find_qd_qs_mism 1021585 1021585 0 0.00 0 12914 10 0 0
srv_make_invnt_saldo 472 472 0 0.00 30 78308 4025 0 0
srv_make_money_saldo 878 878 0 0.00 1 16993 351 0 0
srv_recalc_idx_stat 5 5 0 0.00 59556 412374 209894 0 0
t$perf-abend:sp_add_invoice_to_stock 117 117 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5094 5094 0 0.00 1 17834 962 0 0
t$perf-norm:sp_cancel_adding_invoice 398 398 0 0.00 1 6722 513 0 0
t$perf-norm:sp_cancel_client_order 1831 1831 0 0.00 0 3055 29 0 0
t$perf-norm:sp_cancel_customer_reserve 678 678 0 0.00 0 4738 33 0 0
t$perf-norm:sp_cancel_pay_from_customer 739 739 0 0.00 0 3338 17 0 0
t$perf-norm:sp_cancel_pay_to_supplier 386 386 0 0.00 0 308 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 399 399 0 0.00 0 1703 42 0 0
t$perf-norm:sp_cancel_supplier_order 389 389 0 0.00 0 84301 7572 0 0
t$perf-norm:sp_cancel_write_off 989 989 0 0.00 0 1703 18 0 0
t$perf-norm:sp_client_order 20703 20703 0 0.00 0 14007 39 0 0
t$perf-norm:sp_customer_reserve 952 952 0 0.00 0 1537 14 0 0
t$perf-norm:sp_pay_from_customer 7124 7124 0 0.00 0 7719 19 0 0
t$perf-norm:sp_pay_to_supplier 5877 5877 0 0.00 0 12195 26 0 0
t$perf-norm:sp_reserve_write_off 10103 10103 0 0.00 0 8270 28 0 0
t$perf-norm:sp_supplier_invoice 5488 5488 0 0.00 0 9924 35 0 0
t$perf-norm:sp_supplier_order 5429 5429 0 0.00 0 4355 32 0 0
t$perf-norm:srv_aggregate_perf_data 109 109 0 0.00 0 194 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 335 8 0 0
t$perf-norm:srv_make_invnt_saldo 472 472 0 0.00 0 1757 17 0 0
t$perf-norm:srv_make_money_saldo 878 878 0 0.00 0 3799 13 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 3 143 84 0 0
v_add_invoice_to_stock 10322 10088 234 2.26 0 3804 26 0 234
v_all_customers 37416 37416 0 0.00 0 120 4 0 0
v_all_suppliers 30480 30480 0 0.00 0 69 4 0 0
v_all_wares 41394 41394 0 0.00 0 126 4 0 0
v_cancel_adding_invoice 796 796 0 0.00 0 3721 19 0 0
v_cancel_client_order 3662 3662 0 0.00 0 2950 12 0 0
v_cancel_customer_prepayment 1478 1478 0 0.00 0 1952 14 0 0
v_cancel_customer_reserve 1356 1356 0 0.00 1 1925 15 0 0
v_cancel_payment_to_supplier 772 772 0 0.00 0 2114 16 0 0
v_cancel_supplier_invoice 814 798 16 1.96 0 1878 28 0 16
v_cancel_supplier_order 778 778 0 0.00 0 2696 19 0 0
v_cancel_write_off 1978 1978 0 0.00 0 1177 23 0 0
v_max_id_clo_ord 5423 5423 0 0.00 0 1294 5 0 0
v_max_id_clo_res 952 952 0 0.00 0 4373 20 0 0
v_max_id_ord_sup 5481 5481 0 0.00 0 2429 6 0 0
v_max_non_paid_invoice 1540 1540 0 0.00 0 69 7 0 0
v_max_non_paid_realizn 3888 3888 0 0.00 0 2413 8 0 0
v_min_id_clo_ord 5423 5423 0 0.00 0 6141 11 0 0
v_min_id_clo_res 952 952 0 0.00 0 3579 21 0 0
v_min_id_ord_sup 5481 5481 0 0.00 0 8085 11 0 0
v_min_non_paid_invoice 5876 5876 0 0.00 0 11035 16 0 0
v_min_non_paid_realizn 7123 7123 0 0.00 0 5470 17 0 0
v_our_firm 10448 10448 0 0.00 0 64 5 0 0
v_reserve_write_off 20198 20198 0 0.00 0 3718 11 0 0
x_lock_depdocs_on_canc_invoice 10673 10673 0 0.00 0 18190 140 0 0
x_lock_depdocs_on_canc_sup_ord 389 389 0 0.00 0 3660 347 0 0
x_make_qstorno_1000_1200 5429 5429 0 0.00 2 29794 1125 0 0
x_make_qstorno_1200_2000 5487 5487 0 0.00 4 63878 2869 0 0
x_make_qstorno_2100_3300 477389 477389 0 0.00 0 132003 197 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 15-12-2022 10:39:00 to 15-12-2022 10:39:04.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
08:07:27 1 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
08:08:28 38249 8192000000 9729268832 10028851200 803955776 8.936 100 90 180 0 834189456 7760528 22473152
08:10:11 17842 8192000000 9759175968 10067914752 836353728 9.263 100 90 180 0 871969280 13533056 22082496
08:11:33 16061 8192000000 9807642096 10129100800 864316544 9.543 100 87 174 0 916184464 31639856 20228064
08:12:54 6130 8192000000 9840908736 10176114688 904903376 9.947 100 71 140 0 957664800 37557920 15203504
08:14:07 7436 8192000000 9917090192 10261626880 953781152 10.428 100 81 162 0 1030297808 59671200 16845456
08:15:19 2464 8192000000 9958899264 10311843840 991381040 10.795 100 91 174 0 1073129424 64209984 17538400
08:16:25 2011 8192000000 9990077024 10348072960 1016420512 11.037 100 83 160 1 1092941936 60960880 15560544
08:17:29 1341 8192000000 10022753184 10391371776 1036989648 11.236 100 95 180 1 1150551280 95049472 18512160
08:18:33 5577 8192000000 10016653680 10386194432 1048830272 11.349 100 90 174 1 1136462384 69501984 18130128
08:19:39 3372 8192000000 10015404592 10398486528 1061783056 11.474 100 62 122 1 1136319088 62281520 12254512
08:20:44 1081 8192000000 10032278160 10413772800 1066717280 11.521 100 89 169 3 1154165232 71124624 16323328
08:21:46 1175 8192000000 10058215856 10443001856 1074468384 11.595 100 95 186 3 1177764624 84575648 18720592
08:22:48 1163 8192000000 10037286864 10418606080 1072636272 11.577 100 95 184 2 1156804688 65621024 18547392
08:23:50 1317 8192000000 10035556960 10422235136 1079856384 11.646 100 84 161 0 1158493264 62642608 15994272
08:24:52 1491 8192000000 10040057072 10427346944 1082680048 11.673 100 79 156 0 1164185024 65007216 16497760
08:25:55 1108 8192000000 10048075008 10438344704 1087356320 11.718 100 81 154 2 1173237552 69118592 16762640
08:26:56 1031 8192000000 10045835984 10434908160 1092466112 11.766 100 92 166 1 1164639328 55803968 16369248
08:27:59 1137 8192000000 10051231664 10436042752 1091428480 11.756 100 96 181 5 1175210672 63779840 20002352
08:29:00 3959 8192000000 10045602336 10443005952 1090407888 11.747 100 67 132 0 1173027376 68599456 14020032
08:30:06 1036 8192000000 10047178064 10434990080 1079774384 11.645 100 87 162 0 1168352624 73035232 15543008
08:31:08 1289 8192000000 10038224256 10423685120 1084456336 11.690 100 98 187 0 1162099824 58227152 19416336
08:32:09 883 8192000000 10036032832 10415841280 1065298496 11.507 100 89 166 0 1157049120 73503616 18247008
08:33:11 1317 8192000000 9982711040 10352373760 1031116480 11.179 100 90 167 2 1103937904 56803744 16017680
08:34:13 1751 8192000000 9959986640 10325319680 1020665168 11.078 99 83 159 4 1075979104 38977248 16336688
08:35:16 1604 8192000000 9994433424 10375110656 1039151520 11.257 99 83 159 3 1122876736 66235216 17490000
08:36:20 1578 8192000000 10003563952 10382508032 1042373920 11.287 100 85 166 1 1125176160 65479760 17322480
08:37:22 929 8192000000 9998012176 10379984896 1056738656 11.425 100 87 144 1 1120692544 48270688 15683200
08:38:31 11803 8192000000 10019411872 10402394112 1076124304 11.611 100 75 128 2 1143929824 55601504 12204016
08:39:47 1301 8192000000 10075043024 10455093248 1090333632 11.746 100 91 166 1 1189904320 82770224 16800464
08:40:52 1395 8192000000 10093825248 10477195264 1094543024 11.786 100 89 172 1 1212585216 100185248 17856944
08:41:56 1656 8192000000 10120788048 10505760768 1106940176 11.903 100 86 161 1 1232748544 107692256 18116112
08:43:06 7805 8192000000 10151538752 10550833152 1125770032 12.081 100 63 122 1 1255980432 117148192 13062208
08:44:23 965 8192000000 10192562384 10592083968 1135967776 12.178 100 97 185 2 1305817472 150379392 19470304
08:45:27 2416 8192000000 10236470032 10634219520 1141939488 12.234 100 89 176 1 1355749792 194581696 19228608
08:46:33 5327 8192000000 10256894016 10659504128 1151914560 12.327 100 76 148 0 1378338320 210176176 16247584
08:47:40 936 8192000000 10254955456 10680438784 1157571472 12.381 100 95 182 3 1368597424 190233328 20792624
08:48:44 902 8192000000 10273672336 10674962432 1164062608 12.441 100 87 168 1 1387613968 205775536 17775824
08:49:50 2858 8192000000 10308476608 10711388160 1148866176 12.299 98 81 159 0 1410863536 242444736 19552624
08:50:56 1126 8192000000 10316268176 10736390144 1170650832 12.503 100 95 186 1 1424845088 233236416 20957840
08:52:00 982 8192000000 10350984560 10756988928 1164515776 12.446 100 99 190 2 1461460256 274659856 22284624
08:53:05 881 8192000000 10366459616 10806984704 1173184608 12.527 100 97 182 1 1477169328 283399600 20585120
08:54:10 1483 8192000000 10399899744 10853957632 1175308368 12.546 100 90 179 3 1509790768 313595168 20887232
08:55:17 1241 8192000000 10427023088 10847789056 1187914656 12.664 100 93 179 1 1534852032 325993680 20943696
08:56:23 5808 8192000000 10422072640 10846978048 1192854256 12.710 100 60 113 1 1519433120 312227600 14351264
08:57:41 2586 8192000000 10432573856 10915762176 1192389824 12.706 100 70 132 1 1555275824 345860464 17025536
08:58:51 2793 8192000000 10445630288 10870648832 1193251584 12.714 100 65 130 1 1552476496 342781936 16442976
08:59:59 880 8192000000 10470316112 10889740288 1191146112 12.694 100 97 193 0 1576302544 362877184 22279248
09:01:03 4109 8192000000 10488433744 10926649344 1191517104 12.697 100 66 129 0 1597876224 390630528 15728592
09:02:10 4244 8192000000 10510051584 10949361664 1180959168 12.599 100 62 124 1 1617405712 420926912 15519632
09:03:19 19308 8192000000 10537610192 10987208704 1156623888 12.372 98 48 96 0 1615903424 447123584 12155952
09:04:43 18392 8192000000 10521736640 10970132480 1162867584 12.430 99 43 86 0 1619937728 445867616 11202528
09:06:04 20823 8192000000 10461960128 10930614272 1155876464 12.365 100 27 54 0 1563119840 399383104 7860272
09:07:26 24011 8192000000 10305044400 10825015296 1143312464 12.247 99 29 56 0 1415192800 264538576 7341760
09:08:51 34568 8192000000 10257329152 10771013632 1140080512 12.216 99 30 58 0 1396118000 249526288 6511200
09:10:27 5716 8192000000 10170947200 10654646272 1144963296 12.262 100 53 104 0 1273078928 116197088 11918544
09:11:35 27505 8192000000 10158686992 10620887040 1146832832 12.280 100 46 92 0 1271768144 114168000 10767312
09:13:24 95064 8192000000 10194788560 10648289280 1151427488 12.323 99 33 66 0 1303913888 145000352 7486048
09:16:45 124335 8192000000 10105235808 10589413376 1099813760 11.836 95 18 33 0 1143207072 39927136 3466176
09:20:06 93514 8192000000 10088241568 10567262208 1101345088 11.850 96 32 62 1 1164198848 56562912 6290848
09:22:48 48353 8192000000 10078365456 10501242880 1125746368 12.081 99 50 93 0 1165791824 29224944 10820512
09:24:38 38377 8192000000 10103195712 10548580352 1131604528 12.136 99 22 41 0 1178808608 42984432 4219648
09:26:17 143575 8192000000 10060536464 10576355328 1080278480 11.650 95 6 12 0 1140511408 58898896 1334032
09:29:45 134928 8192000000 10062762720 10501586944 1098339328 11.822 97 27 50 0 1134413472 31182480 4891664
09:33:05 153785 8192000000 10127190336 10564939776 1127046592 12.094 99 50 92 1 1199001600 61109856 10845152
09:36:40 7094 8192000000 10095781504 10529538048 1132833248 12.148 99 48 95 0 1184411776 41720416 9858112
09:37:48 17464 8192000000 10180026576 10621747200 1152886704 12.337 100 51 99 0 1265952240 102145120 10920416
09:39:17 127243 8192000000 10170038272 10635468800 1120162336 12.029 97 20 40 0 1253611952 128819232 4630384
09:42:39 78116 8192000000 10138138736 10633998336 1138568752 12.202 99 13 26 0 1204054064 62655024 2830288
09:45:09 29701 8192000000 10093530128 10553458688 1150119888 12.311 100 32 64 0 1174222656 17449088 6653680
09:46:44 21383 8192000000 10103350672 10548826112 1143874752 12.252 100 46 90 1 1202553504 49221200 9457552
09:48:11 60541 8192000000 10149195264 10617937920 1125026272 12.074 98 20 40 0 1222922240 93437136 4458832
09:50:16 63163 8192000000 10074133152 10545565696 1123067584 12.056 98 15 30 0 1178335616 52311728 2956304
09:52:22 10375 8192000000 10135233248 10563674112 1134282752 12.162 100 64 126 1 1220654064 73727776 12643536
09:53:32 48718 8192000000 10105244432 10540843008 1084095184 11.686 96 42 84 0 1157801040 65255712 8450144
09:55:30 23833 8192000000 10061316672 10523914240 1085090928 11.696 97 29 58 0 1130952704 40125312 5736464
09:56:57 84512 8192000000 10136256736 10622676992 1112701056 11.958 99 19 38 0 1202136576 85680784 3754736
09:59:26 102440 8192000000 10074283184 10547597312 1132175984 12.142 100 24 44 1 1226919088 88820528 5922576
10:03:34 72424 8192000000 10083529952 10569658368 1134817520 12.167 100 14 28 1 1202476048 64652416 3006112
10:05:53 60412 8192000000 10070494160 10531074048 1110271280 11.935 98 9 18 0 1146329760 33976000 2082480
10:08:02 59184 8192000000 10087501376 10542772224 1127591600 12.099 99 10 20 0 1164494416 34890080 2012736
10:10:14 60970 8192000000 10051444208 10536550400 1114324496 11.973 98 11 20 0 1182163856 65803280 2036080
10:12:15 49217 8192000000 10045364816 10491043840 1103449520 11.870 97 16 32 1 1143327456 36261584 3616352
10:14:14 36412 8192000000 10103672112 10543398912 1099952512 11.837 97 31 62 0 1194740688 88234064 6554112
10:15:51 44925 8192000000 10102804608 10568056832 1112634032 11.957 98 36 71 0 1226133328 106575008 6924288
10:18:04 32893 8192000000 10123676256 10607071232 1133812816 12.157 100 44 88 0 1228819584 86486272 8520496
10:19:41 8041 8192000000 10081087184 10499944448 1134715488 12.166 100 58 114 0 1166502496 20279040 11507968
10:20:51 14358 8192000000 10150164864 10575179776 1144384864 12.257 100 55 105 2 1253766320 98738784 10642672
10:22:07 47344 8192000000 10105368736 10560016384 1140322096 12.219 99 31 62 0 1212009648 65564944 6122608
10:23:54 80517 8192000000 10121459200 10636574720 1122666672 12.052 98 22 43 0 1259106528 132082560 4357296
10:26:15 62185 8192000000 10045302368 10465783808 1098476592 11.823 97 55 101 3 1121333376 12408224 10448560
10:28:17 74575 8192000000 10054481616 10514636800 1104313408 11.879 98 12 24 0 1140937888 34064320 2560160
10:30:32 48946 8192000000 10067218960 10531696640 1113073184 11.962 98 20 38 1 1157139728 39850880 4215664
10:33:26 55524 8192000000 10095846880 10556526592 1136039904 12.178 100 24 46 1 1190284640 49536064 4708672
10:35:30 45199 8192000000 10123499696 10568019968 1130169984 12.123 99 22 44 0 1204834240 70100336 4563920
10:37:18 10678 8192000000 9144457824 9477931008 279921712 3.304 25 8 16 0 353374880 71568784 1884384

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 15-12-2022 10:39:06 to 15-12-2022 10:39:22.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 117 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 15-12-2022 10:39:23 to 15-12-2022 10:39:25.

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          223457
MON$OLDEST_ACTIVE               223458
MON$OLDEST_SNAPSHOT             223458
MON$NEXT_TRANSACTION            223458
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                       2097792
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             778
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2885 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2885 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2885 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2885 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 ---
--- end of diff output ---

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

15.12.22 10:39:26. 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-15 10:39:29 No severe PSQL-related problems occured
15.12.22 10:39:30. End of report.