Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 25.07.2022 04: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 16065552 kB
MemAvailable 30975668 kB
Buffers 20 kB
Cached 15570444 kB
SwapCached 2552 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.2
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-07-25 04:37:19
DTS_END                         2022-07-25 04:37:19.3220
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/35010
TRN_ID                          216544
ATT_ID                          642
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: 8700.25 20542 1044030
customer order: creation 165.92 471 19910
customer order: refuse 13.92 397 1670
order to supplier: creation 42.98 1305 5158
order to supplier: removal 3.38 156938 406
invoice (draft): creation 44.08 3063 5290
invoice (draft): removal 48.67 913 5840
invoice accept: apply 40.76 28150 4891
invoice accept: cancel 43.30 1187 5196
customer reserve: creation 3735.49 275 448259
customer reserve: removal 4280.66 99 513679
realization accept: apply 80.66 270 9679
realization accept: cancel 74.50 175 8940
payment from customer: creation 56.73 166 6808
payment from customer: removal 5.53 114 664
payment to supplier: creation 47.63 179 5716
payment to supplier: removal 3.60 95 432
service: total inventory turnovers 3.94 3398 473
service: total monetary turnovers 7.52 339 902
service: aggregate perf. data 0.93 13833 111
service: refresh index statistics 0.05 199464 6

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 25-07-2022 04:38:01 to 25-07-2022 04:38:04.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8146
TEST_TIME 2 7614
TEST_TIME 3 6834
TEST_TIME 4 6216
TEST_TIME 5 6352
TEST_TIME 6 6428
TEST_TIME 7 6690
TEST_TIME 8 6587
TEST_TIME 9 6726
TEST_TIME 10 6976
TEST_TIME 11 6970
TEST_TIME 12 6934
TEST_TIME 13 7234
TEST_TIME 14 7420
TEST_TIME 15 7305
TEST_TIME 16 7351
TEST_TIME 17 7342
TEST_TIME 18 7497
TEST_TIME 19 7421
TEST_TIME 20 7609
TEST_TIME 21 7578
TEST_TIME 22 7996
TEST_TIME 23 8339
TEST_TIME 24 8911
TEST_TIME 25 9037
TEST_TIME 26 10249
TEST_TIME 27 10391
TEST_TIME 28 10965
TEST_TIME 29 10763
TEST_TIME 30 10480
TEST_TIME 31 10237
TEST_TIME 32 10210
TEST_TIME 33 10077
TEST_TIME 34 9945
TEST_TIME 35 9925
TEST_TIME 36 9802
TEST_TIME 37 9753
TEST_TIME 38 9660
TEST_TIME 39 9544
TEST_TIME 40 9347
TEST_TIME 41 9335
TEST_TIME 42 9288
TEST_TIME 43 9379
TEST_TIME 44 9443
TEST_TIME 45 9341
TEST_TIME 46 9285
TEST_TIME 47 9374
TEST_TIME 48 9331
TEST_TIME 49 9348
TEST_TIME 50 9234
TEST_TIME 51 9270
TEST_TIME 52 9439
TEST_TIME 53 9392
TEST_TIME 54 9316
TEST_TIME 55 9437
TEST_TIME 56 9333
TEST_TIME 57 9203
TEST_TIME 58 9105
TEST_TIME 59 9106
TEST_TIME 60 9042
TEST_TIME 61 8967
TEST_TIME 62 8865
TEST_TIME 63 8811
TEST_TIME 64 8803
TEST_TIME 65 8805
TEST_TIME 66 8912
TEST_TIME 67 8890
TEST_TIME 68 8914
TEST_TIME 69 8876
TEST_TIME 70 8958
TEST_TIME 71 8977
TEST_TIME 72 9017
TEST_TIME 73 8949
TEST_TIME 74 9051
TEST_TIME 75 9146
TEST_TIME 76 9075
TEST_TIME 77 9130
TEST_TIME 78 9101
TEST_TIME 79 9190
TEST_TIME 80 9151
TEST_TIME 81 9053
TEST_TIME 82 8999
TEST_TIME 83 8965
TEST_TIME 84 8937
TEST_TIME 85 8916
TEST_TIME 86 8870
TEST_TIME 87 8834
TEST_TIME 88 8791
TEST_TIME 89 8846
TEST_TIME 90 8799
TEST_TIME 91 8751
TEST_TIME 92 8754
TEST_TIME 93 8735
TEST_TIME 94 8823
TEST_TIME 95 8887
TEST_TIME 96 8939
TEST_TIME 97 8947
TEST_TIME 98 8998
TEST_TIME 99 8970
TEST_TIME 100 8960
TEST_TIME 101 8950
TEST_TIME 102 8909
TEST_TIME 103 8868
TEST_TIME 104 8852
TEST_TIME 105 8833
TEST_TIME 106 8826
TEST_TIME 107 8783
TEST_TIME 108 8752
TEST_TIME 109 8719
TEST_TIME 110 8668
TEST_TIME 111 8727
TEST_TIME 112 8720
TEST_TIME 113 8701
TEST_TIME 114 8685
TEST_TIME 115 8727
TEST_TIME 116 8729
TEST_TIME 117 8716
TEST_TIME 118 8706
TEST_TIME 119 8764
TEST_TIME 120 8728

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 25-07-2022 04:38:05 to 25-07-2022 04:38:08.

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 1457208 1457208 0 0.00 0 25086 14 0 0
doc_list_biud 1457199 1457199 0 0.00 0 45821 40 0 0
sp_add_invoice_to_stock 5006 4891 115 2.29 34 258527 28151 0 115
sp_cancel_adding_invoice 5196 5196 0 0.00 6 107696 1188 0 0
sp_cancel_client_order 1670 1670 0 0.00 5 10498 397 0 0
sp_cancel_customer_reserve 513679 513679 0 0.00 1 22565 99 0 0
sp_cancel_pay_from_customer 664 664 0 0.00 4 2339 115 0 0
sp_cancel_pay_to_supplier 432 432 0 0.00 4 1174 96 0 0
sp_cancel_supplier_invoice 5852 5840 12 0.20 3 45837 914 0 12
sp_cancel_supplier_order 406 406 0 0.00 14 1257194 156939 0 0
sp_cancel_write_off 8940 8940 0 0.00 2 8656 176 0 0
sp_client_order 19910 19910 0 0.00 5 25916 472 0 0
sp_customer_reserve 448259 448259 0 0.00 0 91013 275 0 0
sp_fill_shopping_cart 30350 30350 0 0.00 1 16532 78 0 0
sp_fill_shopping_cart_clo_res 448210 448210 0 0.00 0 22591 21 0 0
sp_get_clo_for_invoice 4838 4838 0 0.00 0 25125 331 0 0
sp_kill_cost_storno 15232 15232 0 0.00 0 8454 68 0 0
sp_kill_qstorno_ret_qs2qd 1042544 1042544 0 0.00 0 45817 44 0 0
sp_kill_qty_storno 550248 550248 0 0.00 0 45819 99 0 0
sp_lock_selected_doc 20050 20050 0 0.00 0 7821 7 0 0
sp_make_cost_storno 27041 27041 0 0.00 0 20297 76 0 0
sp_multiply_rows_for_pdistr 27041 27041 0 0.00 0 14659 37 0 0
sp_multiply_rows_for_qdistr 19910 19910 0 0.00 0 11099 197 0 0
sp_pay_from_customer 6808 6808 0 0.00 5 14685 166 0 0
sp_pay_to_supplier 5716 5716 0 0.00 6 17112 180 0 0
sp_payment_common 12523 12523 0 0.00 2 12041 41 0 0
sp_qd_handle_on_cancel_clo 1670 1670 0 0.00 0 10489 336 0 0
sp_qd_handle_on_invoice_upd_sts 10034 10034 0 0.00 0 23935 462 0 0
sp_qd_handle_on_reserve_upd_sts 9679 9679 0 0.00 0 10490 57 0 0
sp_reserve_write_off 9679 9679 0 0.00 8 23244 271 0 0
sp_supplier_invoice 5290 5290 0 0.00 10 82406 3064 0 0
sp_supplier_order 5158 5158 0 0.00 7 58688 1305 0 0
srv_aggregate_perf_data 112 111 1 0.89 2605 139981 13833 0 1
srv_fill_mon_cache_memory 70 70 0 0.00 740 119362 33474 0 0
srv_find_qd_qs_mism 996921 996921 0 0.00 0 25084 12 0 0
srv_make_invnt_saldo 473 473 0 0.00 59 50967 3398 0 0
srv_make_money_saldo 902 902 0 0.00 1 13360 339 0 0
srv_recalc_idx_stat 6 6 0 0.00 62767 369482 199464 0 0
t$perf-abend:sp_add_invoice_to_stock 115 115 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 4895 4895 0 0.00 1 17923 821 0 0
t$perf-norm:sp_cancel_adding_invoice 374 374 0 0.00 2 5569 411 0 0
t$perf-norm:sp_cancel_client_order 1670 1670 0 0.00 0 10462 34 0 0
t$perf-norm:sp_cancel_customer_reserve 623 623 0 0.00 0 5391 38 0 0
t$perf-norm:sp_cancel_pay_from_customer 664 664 0 0.00 0 1620 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 432 432 0 0.00 0 323 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 408 408 0 0.00 0 885 27 0 0
t$perf-norm:sp_cancel_supplier_order 406 406 0 0.00 0 82823 5265 0 0
t$perf-norm:sp_cancel_write_off 958 958 0 0.00 0 877 16 0 0
t$perf-norm:sp_client_order 19913 19913 0 0.00 0 18461 38 0 0
t$perf-norm:sp_customer_reserve 917 917 0 0.00 0 2381 13 0 0
t$perf-norm:sp_pay_from_customer 6809 6809 0 0.00 0 10563 20 0 0
t$perf-norm:sp_pay_to_supplier 5716 5716 0 0.00 0 17403 22 0 0
t$perf-norm:sp_reserve_write_off 9679 9679 0 0.00 0 17261 27 0 0
t$perf-norm:sp_supplier_invoice 5290 5290 0 0.00 0 8339 33 0 0
t$perf-norm:sp_supplier_order 5158 5158 0 0.00 0 19029 33 0 0
t$perf-norm:srv_aggregate_perf_data 111 111 0 0.00 0 608 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 818 31 0 0
t$perf-norm:srv_make_invnt_saldo 473 473 0 0.00 0 552 12 0 0
t$perf-norm:srv_make_money_saldo 902 902 0 0.00 0 15903 22 0 0
t$perf-norm:srv_recalc_idx_stat 6 6 0 0.00 4 192 65 0 0
v_add_invoice_to_stock 9902 9672 230 2.32 0 8605 25 0 230
v_all_customers 36490 36490 0 0.00 0 58 3 0 0
v_all_suppliers 29378 29378 0 0.00 0 142 4 0 0
v_all_wares 39820 39820 0 0.00 0 142 4 0 0
v_cancel_adding_invoice 748 748 0 0.00 0 5232 25 0 0
v_cancel_client_order 3340 3340 0 0.00 0 1486 8 0 0
v_cancel_customer_prepayment 1328 1328 0 0.00 0 842 12 0 0
v_cancel_customer_reserve 1246 1246 0 0.00 1 655 10 0 0
v_cancel_payment_to_supplier 864 864 0 0.00 0 454 10 0 0
v_cancel_supplier_invoice 840 816 24 2.85 0 5197 29 0 24
v_cancel_supplier_order 812 812 0 0.00 0 9240 28 0 0
v_cancel_write_off 1916 1916 0 0.00 0 2063 21 0 0
v_max_id_clo_ord 5152 5152 0 0.00 0 419 4 0 0
v_max_id_clo_res 917 917 0 0.00 0 2318 12 0 0
v_max_id_ord_sup 5288 5288 0 0.00 0 7458 12 0 0
v_max_non_paid_invoice 1466 1466 0 0.00 0 7791 12 0 0
v_max_non_paid_realizn 3592 3592 0 0.00 0 410 6 0 0
v_min_id_clo_ord 5152 5152 0 0.00 0 2053 7 0 0
v_min_id_clo_res 917 917 0 0.00 0 2516 10 0 0
v_min_id_ord_sup 5288 5288 0 0.00 0 5071 11 0 0
v_min_non_paid_invoice 5715 5715 0 0.00 0 12009 17 0 0
v_min_non_paid_realizn 6808 6808 0 0.00 0 4478 14 0 0
v_our_firm 9762 9762 0 0.00 0 294 4 0 0
v_reserve_write_off 19358 19358 0 0.00 0 8605 11 0 0
x_lock_depdocs_on_canc_invoice 10628 10628 0 0.00 0 15894 138 0 0
x_lock_depdocs_on_canc_sup_ord 406 406 0 0.00 0 7084 319 0 0
x_make_qstorno_1000_1200 5158 5158 0 0.00 3 58675 1165 0 0
x_make_qstorno_1200_2000 5290 5290 0 0.00 4 82284 2828 0 0
x_make_qstorno_2100_3300 448252 448252 0 0.00 0 91011 243 0 0

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

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
02:07:23 2 8192000000 8797251808 8807960576 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:24 31924 8192000000 9719539056 10019987456 796212400 8.858 100 92 184 0 826942336 7972896 22757040
02:10:03 17983 8192000000 9772009888 10081898496 844149488 9.341 100 87 173 1 877870912 12998096 20723328
02:11:29 12234 8192000000 9826123104 10149150720 888113632 9.780 100 86 170 0 929425856 21576224 19736000
02:12:46 12783 8192000000 9879213440 10220163072 934247408 10.236 100 80 158 0 999342384 47600176 17494800
02:14:02 3600 8192000000 9931081456 10274775040 959170912 10.481 100 89 178 1 1046832144 68315504 19345728
02:15:08 2502 8192000000 9963087136 10316767232 995012784 10.830 100 87 171 1 1082840800 70164512 17663504
02:16:12 1808 8192000000 10006285568 10364334080 1014366160 11.018 100 93 185 0 1121197920 88587952 18243808
02:17:15 1167 8192000000 10022470832 10388443136 1036599504 11.232 100 93 177 1 1136957680 82162416 18195760
02:18:17 1121 8192000000 10035660480 10409967616 1056387456 11.422 100 94 180 1 1158171408 82324416 19459536
02:19:19 957 8192000000 10046016320 10423762944 1069319296 11.546 100 92 180 0 1166592336 79140752 18132288
02:20:21 898 8192000000 10041903072 10421182464 1076690016 11.616 100 98 191 1 1164798976 69222512 18886448
02:21:23 2941 8192000000 10043028464 10430840832 1080714768 11.654 100 75 142 2 1170396432 74896816 14784848
02:22:27 993 8192000000 10074472288 10465972224 1084158400 11.687 100 96 187 0 1197749024 94987648 18602976
02:23:29 4393 8192000000 10064385184 10461745152 1087197824 11.716 100 63 124 0 1187757616 88412320 12147472
02:24:37 1195 8192000000 10059294784 10447028224 1094557232 11.786 100 84 155 2 1178844736 68820000 15467504
02:25:39 2909 8192000000 10031981808 10427428864 1100121040 11.839 100 64 119 0 1163452064 51533184 11797840
02:26:43 1060 8192000000 10066491968 10457034752 1100739984 11.845 100 100 182 4 1191179824 71364144 19075696
02:27:45 1298 8192000000 10055266800 10443120640 1094953248 11.790 100 95 184 1 1171452928 59248032 17251648
02:28:47 1215 8192000000 10061143248 10444509184 1089235216 11.735 100 97 183 0 1185908480 77011760 19661504
02:29:49 820 8192000000 10048840752 10438742016 1088550800 11.729 100 95 178 0 1176264624 69484816 18229008
02:30:50 1160 8192000000 10023720816 10415910912 1061879200 11.474 99 72 138 0 1146078560 68506352 15693008
02:31:53 1263 8192000000 10003752336 10385121280 1052624288 11.386 100 93 180 1 1126037232 54597648 18815296
02:32:55 1388 8192000000 9975680448 10344620032 1016321632 11.036 98 88 165 2 1100106576 66861328 16923616
02:33:57 971 8192000000 9986129440 10361892864 1031276000 11.181 100 84 156 0 1115066272 67001008 16789264
02:34:59 1034 8192000000 9979192592 10353324032 1026121728 11.131 100 79 146 3 1100411760 59883168 14406864
02:36:02 1074 8192000000 10019360528 10398986240 1044066144 11.304 100 86 161 2 1146058672 85458864 16533664
02:37:04 1128 8192000000 9958926288 10327228416 1018323760 11.056 99 96 178 1 1081323408 45265904 17733744
02:38:09 15482 8192000000 10025269888 10400247808 1049236544 11.353 100 79 144 2 1146207136 83998976 12971616
02:39:31 1047 8192000000 10033084848 10408996864 1073206816 11.583 100 96 188 0 1157449856 62443264 21799776
02:40:37 1067 8192000000 10085173216 10465251328 1089940416 11.742 100 94 182 2 1205514672 93887184 21687072
02:41:45 2834 8192000000 10116081024 10509197312 1100210640 11.840 100 68 129 1 1230900688 117078320 13611728
02:42:51 1831 8192000000 10130816672 10523410432 1111311952 11.945 100 77 152 1 1251646592 123237840 17096800
02:43:58 1771 8192000000 10168684912 10563538944 1120102000 12.028 100 94 175 2 1283754928 144466208 19186720
02:45:07 4062 8192000000 10180429792 10579591168 1140899760 12.224 100 69 135 0 1301186592 145575120 14711712
02:46:14 3921 8192000000 10198795776 10605346816 1142680336 12.241 100 72 138 0 1319095456 161454448 14960672
02:47:22 867 8192000000 10231449472 10636804096 1161899952 12.421 100 97 184 0 1348843136 165767136 21176048
02:48:24 1326 8192000000 10273827712 10675253248 1172831664 12.523 100 83 161 0 1383063232 192171152 18060416
02:49:28 3021 8192000000 10333371296 10739056640 1176916144 12.561 100 83 165 1 1443566144 247371872 19278128
02:50:35 1163 8192000000 10328353888 10762235904 1186409968 12.650 100 96 189 1 1444949536 236371888 22167680
02:51:39 931 8192000000 10367589488 10776068096 1178782912 12.579 100 98 192 0 1482083984 279520496 23780576
02:52:47 1477 8192000000 10410572480 10819510272 1192946272 12.711 100 90 175 0 1516870848 302878672 21045904
02:53:54 1966 8192000000 10420986416 10828521472 1182659584 12.615 100 84 167 0 1529628352 327210640 19758128
02:54:59 835 8192000000 10450773808 10872659968 1182359312 12.612 100 98 186 1 1555467744 349868048 23240384
02:56:03 1116 8192000000 10501542848 10903859200 1179444208 12.585 100 94 184 0 1602020736 400033840 22542688
02:57:10 719 8192000000 10527637440 10944106496 1183176592 12.620 100 100 197 0 1634926944 427746256 24004096
02:58:13 789 8192000000 10556120480 10967621632 1173357008 12.528 99 98 194 0 1665212992 466779984 25076000
02:59:18 3267 8192000000 10544248864 10987196416 1173239968 12.527 99 80 149 1 1648974000 455970560 19763472
03:00:22 6685 8192000000 10533128944 10999881728 1180171600 12.592 100 45 90 0 1650682912 458497712 12013600
03:01:31 11989 8192000000 10414613456 10895585280 1151423936 12.323 99 36 72 0 1524899632 364001504 9474192
03:03:02 22818 8192000000 10282910160 10763239424 1122562304 12.051 99 26 52 1 1418730464 289021184 7146976
03:05:21 43706 8192000000 10071068352 10507554816 1136648288 12.184 100 31 58 1 1188671344 45406576 6616480
03:07:12 88047 8192000000 10072760320 10517209088 1095569056 11.796 97 32 57 1 1155380256 54699760 5111440
03:09:43 15064 8192000000 10063986512 10515140608 1117575776 12.004 100 33 58 3 1154549920 29612768 7361376
03:11:01 75223 8192000000 10113374672 10562813952 1130978288 12.131 100 30 60 3 1210563136 72609808 6975040
03:13:18 26571 8192000000 10120447168 10587095040 1138034944 12.197 100 28 56 0 1228097392 84178608 5883840
03:14:45 67964 8192000000 10117440320 10565292032 1122422528 12.050 98 23 46 0 1199152992 71621312 5109152
03:16:53 82873 8192000000 10172044928 10626367488 1128804288 12.110 99 28 55 0 1247246976 111993936 6448752
03:19:20 91253 8192000000 10106706096 10609934336 1114568896 11.976 98 15 30 0 1202661520 84993008 3099616
03:21:51 39361 8192000000 10095082704 10525777920 1138288000 12.199 100 28 53 0 1176577248 32917984 5371264
03:23:50 49802 8192000000 10134581392 10598735872 1124016272 12.065 98 30 60 0 1220459888 90668048 5775568
03:25:51 72179 8192000000 10109096032 10591797248 1081156640 11.658 95 16 31 0 1137943792 53899856 2887296
03:28:04 60127 8192000000 10149634224 10615644160 1129307888 12.115 100 21 42 0 1246366928 112545440 4513600
03:31:02 32719 8192000000 10102857120 10528804864 1114646288 11.976 98 50 95 0 1185260240 60912272 9701680
03:32:36 119362 8192000000 10069404240 10507694080 1122083712 12.047 99 21 43 0 1181314160 54356336 4874112
03:35:36 12272 8192000000 10081102912 10516545536 1136941328 12.187 100 53 99 1 1183856384 36098448 10816608
03:36:54 41769 8192000000 10048579040 10511855616 1134855984 12.167 99 11 22 0 1181889600 44762096 2271520
03:38:36 35124 8192000000 10077440000 10528550912 1139819936 12.214 100 15 26 0 1180724704 38752400 2152368
03:42:59 93597 8192000000 10114700992 10568155136 1127037024 12.093 98 16 32 0 1167202160 36875008 3290128
03:45:35 34618 8192000000 10138436032 10585866240 1143747280 12.251 99 25 49 0 1225817136 76785520 5284336
03:47:17 26074 8192000000 10174726896 10652979200 1161837536 12.420 100 25 50 0 1273676304 106343024 5495744
03:48:43 40582 8192000000 10145437616 10600321024 1123448944 12.060 97 19 38 0 1222496112 94677680 4369488
03:50:25 26838 8192000000 10165552448 10600333312 1157858400 12.383 100 45 81 2 1258013136 91154800 8999936
03:51:54 47959 8192000000 10114966800 10581123072 1111324336 11.945 96 24 46 0 1174852384 58866912 4661136
03:53:49 8521 8192000000 10130013488 10551271424 1138355728 12.200 100 54 105 1 1212053216 62753664 10943824
03:55:01 35171 8192000000 10131631952 10564960256 1108383008 11.917 97 54 106 0 1194774512 75413248 10978256
03:56:59 88920 8192000000 10056271440 10514395136 1112644816 11.957 98 19 38 0 1168309808 51039392 4625600
03:59:33 49580 8192000000 10064796288 10511138816 1119133040 12.019 99 40 76 0 1167049504 40457824 7458640
04:01:25 27485 8192000000 10063395472 10498584576 1084763056 11.693 96 22 44 0 1130604976 41307488 4534432
04:02:59 45680 8192000000 10081374864 10542686208 1096196720 11.802 98 13 26 0 1160004944 60933520 2874704
04:04:48 55508 8192000000 10118124560 10574700544 1124079008 12.066 100 17 34 0 1217072992 88942368 4051616
04:06:44 59873 8192000000 10112628544 10564263936 1115760336 11.987 99 18 36 0 1197241632 77489632 3991664
04:08:56 47896 8192000000 10160848544 10601316352 1115738096 11.987 98 32 65 1 1239866624 116849904 7278624
04:10:49 35206 8192000000 10221846832 10660913152 1132327184 12.143 99 34 68 0 1299811280 160205520 7278576
04:12:37 46108 8192000000 10148211312 10598760448 1133061664 12.150 99 34 64 1 1225124864 85444656 6618544
04:15:11 45007 8192000000 10083856672 10530091008 1072369552 11.575 94 36 72 1 1123332432 44246480 6716400
04:16:56 10499 8192000000 10097178288 10514255872 1117720928 12.005 99 55 108 1 1185562656 57430320 10411408
04:18:15 11033 8192000000 10103739600 10512367616 1096514992 11.805 98 67 134 1 1175865056 66444080 12905984
04:19:30 69735 8192000000 10135447968 10560081920 1120955488 12.036 100 58 114 0 1218917072 86576016 11385568
04:21:41 15862 8192000000 10066431472 10514329600 1128383344 12.106 100 28 56 0 1169966384 36132592 5450448
04:22:58 43205 8192000000 10070706544 10525315072 1122808448 12.054 100 24 47 0 1181956096 54837664 4309984
04:24:42 45736 8192000000 10066701536 10509082624 1110567616 11.938 98 12 22 0 1170432144 57456912 2407616
04:26:28 74777 8192000000 10125071856 10589491200 1136661008 12.184 100 10 20 1 1218547984 79593984 2292992
04:29:02 52101 8192000000 10060579440 10501197824 1080766640 11.655 95 15 29 0 1136009536 52275648 2967248
04:30:58 21334 8192000000 10106236464 10547564544 1123699392 12.062 99 31 62 0 1197189584 67309520 6180672
04:32:20 39147 8192000000 10081675280 10581512192 1124371728 12.068 100 24 48 0 1215167184 85792256 5003200
04:34:01 38154 8192000000 10096104304 10540081152 1118818928 12.016 99 35 68 1 1182217408 56661424 6737056
04:35:40 62270 8192000000 10085769872 10524626944 1138509088 12.202 100 28 56 0 1192731024 48571968 5649968

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 25-07-2022 04:38:15 to 25-07-2022 04:38:31.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 115 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 25-07-2022 04:38:32 to 25-07-2022 04:38:34.

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          216855
MON$OLDEST_ACTIVE               216856
MON$OLDEST_SNAPSHOT             216856
MON$NEXT_TRANSACTION            216856
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                       2020608
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             768
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2807 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2807 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2807 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2807 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 ---
:69: 
:70: mscs-test-161.budzdorov.ru    Mon Jul 25 04:37:33 2022
:71:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:72: 
--- end of diff output ---

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

25.07.22 04:38:35. 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-07-25 04:38:38 No severe PSQL-related problems occured
25.07.22 04:38:39. End of report.