Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 24.02.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 14331456 kB
MemAvailable 30429700 kB
Buffers 0 kB
Cached 17373220 kB
SwapCached 3140 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-02-24 07:37:25
DTS_END                         2023-02-24 07:37:25.6300
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/33024
TRN_ID                          208641
ATT_ID                          571
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: 8740.39 25142 1048847
customer order: creation 161.88 528 19426
customer order: refuse 14.33 506 1719
order to supplier: creation 43.03 1390 5163
order to supplier: removal 3.42 192645 410
invoice (draft): creation 43.15 3662 5178
invoice (draft): removal 49.13 1215 5895
invoice accept: apply 39.62 29743 4754
invoice accept: cancel 44.45 1523 5334
customer reserve: creation 3723.68 275 446842
customer reserve: removal 4344.93 117 521392
realization accept: apply 77.78 364 9333
realization accept: cancel 73.23 209 8788
payment from customer: creation 55.22 214 6626
payment from customer: removal 5.78 162 693
payment to supplier: creation 45.73 234 5488
payment to supplier: removal 3.33 165 399
service: total inventory turnovers 3.34 5402 401
service: total monetary turnovers 7.43 452 892
service: aggregate perf. data 0.91 17876 109
service: refresh index statistics 0.04 246149 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 24-02-2023 07:39:46 to 24-02-2023 07:39:49.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6567
TEST_TIME 2 7162
TEST_TIME 3 7012
TEST_TIME 4 6967
TEST_TIME 5 6786
TEST_TIME 6 6318
TEST_TIME 7 6600
TEST_TIME 8 6547
TEST_TIME 9 6931
TEST_TIME 10 6718
TEST_TIME 11 6661
TEST_TIME 12 6876
TEST_TIME 13 6874
TEST_TIME 14 7167
TEST_TIME 15 7205
TEST_TIME 16 7342
TEST_TIME 17 7460
TEST_TIME 18 7500
TEST_TIME 19 7480
TEST_TIME 20 7430
TEST_TIME 21 7463
TEST_TIME 22 7496
TEST_TIME 23 7433
TEST_TIME 24 7437
TEST_TIME 25 7689
TEST_TIME 26 7669
TEST_TIME 27 7961
TEST_TIME 28 7956
TEST_TIME 29 7989
TEST_TIME 30 7896
TEST_TIME 31 8547
TEST_TIME 32 8731
TEST_TIME 33 9069
TEST_TIME 34 9712
TEST_TIME 35 9701
TEST_TIME 36 9892
TEST_TIME 37 10133
TEST_TIME 38 10253
TEST_TIME 39 10384
TEST_TIME 40 10363
TEST_TIME 41 10307
TEST_TIME 42 10410
TEST_TIME 43 10465
TEST_TIME 44 10265
TEST_TIME 45 10146
TEST_TIME 46 10041
TEST_TIME 47 10031
TEST_TIME 48 10093
TEST_TIME 49 10187
TEST_TIME 50 10323
TEST_TIME 51 10238
TEST_TIME 52 10282
TEST_TIME 53 10192
TEST_TIME 54 10159
TEST_TIME 55 10008
TEST_TIME 56 9888
TEST_TIME 57 9848
TEST_TIME 58 9895
TEST_TIME 59 9797
TEST_TIME 60 9791
TEST_TIME 61 9657
TEST_TIME 62 9756
TEST_TIME 63 9704
TEST_TIME 64 9644
TEST_TIME 65 9607
TEST_TIME 66 9497
TEST_TIME 67 9436
TEST_TIME 68 9368
TEST_TIME 69 9407
TEST_TIME 70 9341
TEST_TIME 71 9299
TEST_TIME 72 9265
TEST_TIME 73 9191
TEST_TIME 74 9111
TEST_TIME 75 9191
TEST_TIME 76 9164
TEST_TIME 77 9143
TEST_TIME 78 9113
TEST_TIME 79 9134
TEST_TIME 80 9094
TEST_TIME 81 9140
TEST_TIME 82 9137
TEST_TIME 83 9154
TEST_TIME 84 9177
TEST_TIME 85 9148
TEST_TIME 86 9204
TEST_TIME 87 9199
TEST_TIME 88 9190
TEST_TIME 89 9098
TEST_TIME 90 9112
TEST_TIME 91 9059
TEST_TIME 92 9018
TEST_TIME 93 9007
TEST_TIME 94 8949
TEST_TIME 95 8954
TEST_TIME 96 8912
TEST_TIME 97 8876
TEST_TIME 98 8900
TEST_TIME 99 8890
TEST_TIME 100 8876
TEST_TIME 101 8863
TEST_TIME 102 8841
TEST_TIME 103 8855
TEST_TIME 104 8836
TEST_TIME 105 8934
TEST_TIME 106 8965
TEST_TIME 107 8977
TEST_TIME 108 8969
TEST_TIME 109 9041
TEST_TIME 110 9017
TEST_TIME 111 9002
TEST_TIME 112 9080
TEST_TIME 113 9007
TEST_TIME 114 8945
TEST_TIME 115 8916
TEST_TIME 116 8919
TEST_TIME 117 8868
TEST_TIME 118 8838
TEST_TIME 119 8808
TEST_TIME 120 8766

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 24-02-2023 07:39:50 to 24-02-2023 07:39:54.

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 1458947 1458947 0 0.00 0 29014 16 0 0
doc_list_biud 1458946 1458946 0 0.00 0 117742 49 0 0
sp_add_invoice_to_stock 4870 4754 116 2.38 54 212150 29744 0 116
sp_cancel_adding_invoice 5334 5334 0 0.00 7 82654 1523 0 0
sp_cancel_client_order 1719 1719 0 0.00 7 11207 506 0 0
sp_cancel_customer_reserve 521392 521392 0 0.00 1 37141 117 0 0
sp_cancel_pay_from_customer 693 693 0 0.00 4 6507 162 0 0
sp_cancel_pay_to_supplier 399 399 0 0.00 4 3927 166 0 0
sp_cancel_supplier_invoice 5906 5895 11 0.18 4 117782 1216 0 11
sp_cancel_supplier_order 410 410 0 0.00 7 1544470 192645 0 0
sp_cancel_write_off 8788 8788 0 0.00 2 27863 210 0 0
sp_client_order 19426 19426 0 0.00 6 32895 529 0 0
sp_customer_reserve 446842 446842 0 0.00 1 51487 275 0 0
sp_fill_shopping_cart 29766 29766 0 0.00 1 44680 99 0 0
sp_fill_shopping_cart_clo_res 446818 446818 0 0.00 0 12807 19 0 0
sp_get_clo_for_invoice 4701 4701 0 0.00 0 44448 371 0 0
sp_kill_cost_storno 15214 15214 0 0.00 0 28897 102 0 0
sp_kill_qstorno_ret_qs2qd 1057877 1057877 0 0.00 0 117734 51 0 0
sp_kill_qty_storno 557569 557569 0 0.00 0 117739 119 0 0
sp_lock_selected_doc 19639 19639 0 0.00 0 7806 10 0 0
sp_make_cost_storno 26145 26145 0 0.00 0 14960 94 0 0
sp_multiply_rows_for_pdistr 26145 26145 0 0.00 0 23490 44 0 0
sp_multiply_rows_for_qdistr 19426 19426 0 0.00 0 11850 183 0 0
sp_pay_from_customer 6626 6626 0 0.00 5 22721 214 0 0
sp_pay_to_supplier 5488 5488 0 0.00 6 18865 234 0 0
sp_payment_common 12114 12114 0 0.00 2 15312 56 0 0
sp_qd_handle_on_cancel_clo 1719 1719 0 0.00 1 11199 409 0 0
sp_qd_handle_on_invoice_upd_sts 10032 10032 0 0.00 0 33557 686 0 0
sp_qd_handle_on_reserve_upd_sts 9333 9333 0 0.00 0 32292 100 0 0
sp_reserve_write_off 9333 9333 0 0.00 8 32447 365 0 0
sp_supplier_invoice 5178 5178 0 0.00 10 131714 3662 0 0
sp_supplier_order 5163 5163 0 0.00 7 33359 1390 0 0
srv_aggregate_perf_data 109 109 0 0.00 2057 221262 17877 0 0
srv_fill_mon_cache_memory 72 72 0 0.00 839 181655 31878 0 0
srv_find_qd_qs_mism 1001662 1001662 0 0.00 0 20411 11 0 0
srv_make_invnt_saldo 401 401 0 0.00 48 58253 5402 0 0
srv_make_money_saldo 892 892 0 0.00 1 16370 453 0 0
srv_recalc_idx_stat 5 5 0 0.00 64239 341879 246149 0 0
t$perf-abend:sp_add_invoice_to_stock 116 116 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4754 4754 0 0.00 1 23966 1222 0 0
t$perf-norm:sp_cancel_adding_invoice 399 399 0 0.00 1 12009 499 0 0
t$perf-norm:sp_cancel_client_order 1719 1719 0 0.00 0 6651 38 0 0
t$perf-norm:sp_cancel_customer_reserve 653 653 0 0.00 0 1185 34 0 0
t$perf-norm:sp_cancel_pay_from_customer 693 693 0 0.00 0 1616 19 0 0
t$perf-norm:sp_cancel_pay_to_supplier 399 399 0 0.00 0 1077 20 0 0
t$perf-norm:sp_cancel_supplier_invoice 370 370 0 0.00 0 693 35 0 0
t$perf-norm:sp_cancel_supplier_order 410 410 0 0.00 0 120085 8313 0 0
t$perf-norm:sp_cancel_write_off 965 965 0 0.00 0 4655 29 0 0
t$perf-norm:sp_client_order 19426 19426 0 0.00 0 12197 47 0 0
t$perf-norm:sp_customer_reserve 924 924 0 0.00 0 4280 25 0 0
t$perf-norm:sp_pay_from_customer 6626 6626 0 0.00 0 13149 27 0 0
t$perf-norm:sp_pay_to_supplier 5488 5488 0 0.00 0 5940 24 0 0
t$perf-norm:sp_reserve_write_off 9333 9333 0 0.00 0 9520 32 0 0
t$perf-norm:sp_supplier_invoice 5178 5178 0 0.00 0 2090 36 0 0
t$perf-norm:sp_supplier_order 5163 5163 0 0.00 0 4752 40 0 0
t$perf-norm:srv_aggregate_perf_data 109 109 0 0.00 0 1486 40 0 0
t$perf-norm:srv_fill_mon_cache_memory 72 72 0 0.00 0 616 17 0 0
t$perf-norm:srv_make_invnt_saldo 401 401 0 0.00 0 1504 27 0 0
t$perf-norm:srv_make_money_saldo 892 892 0 0.00 0 1097 8 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 25 212 85 0 0
v_add_invoice_to_stock 9628 9396 232 2.40 0 20249 37 0 232
v_all_customers 35318 35318 0 0.00 0 104 4 0 0
v_all_suppliers 28856 28856 0 0.00 0 105 5 0 0
v_all_wares 38852 38852 0 0.00 0 168 5 0 0
v_cancel_adding_invoice 798 798 0 0.00 0 6321 25 0 0
v_cancel_client_order 3438 3438 0 0.00 0 6388 18 0 0
v_cancel_customer_prepayment 1386 1386 0 0.00 0 6429 24 0 0
v_cancel_customer_reserve 1306 1306 0 0.00 0 15266 29 0 0
v_cancel_payment_to_supplier 798 798 0 0.00 0 2507 20 0 0
v_cancel_supplier_invoice 762 740 22 2.88 0 1899 33 0 22
v_cancel_supplier_order 820 820 0 0.00 0 1895 14 0 0
v_cancel_write_off 1930 1930 0 0.00 0 16100 46 0 0
v_max_id_clo_ord 5163 5163 0 0.00 0 581 6 0 0
v_max_id_clo_res 924 924 0 0.00 0 4150 20 0 0
v_max_id_ord_sup 5177 5177 0 0.00 0 3009 8 0 0
v_max_non_paid_invoice 1400 1400 0 0.00 0 6147 13 0 0
v_max_non_paid_realizn 3530 3530 0 0.00 0 1259 9 0 0
v_min_id_clo_ord 5163 5163 0 0.00 0 2790 10 0 0
v_min_id_clo_res 924 924 0 0.00 0 1873 13 0 0
v_min_id_ord_sup 5177 5177 0 0.00 0 1600 7 0 0
v_min_non_paid_invoice 5488 5488 0 0.00 0 10112 20 0 0
v_min_non_paid_realizn 6626 6626 0 0.00 0 15306 23 0 0
v_our_firm 9726 9726 0 0.00 0 75 6 0 0
v_reserve_write_off 18666 18666 0 0.00 0 16301 17 0 0
x_lock_depdocs_on_canc_invoice 10859 10859 0 0.00 0 11238 143 0 0
x_lock_depdocs_on_canc_sup_ord 410 410 0 0.00 0 5374 379 0 0
x_make_qstorno_1000_1200 5163 5163 0 0.00 2 33348 1192 0 0
x_make_qstorno_1200_2000 5178 5178 0 0.00 5 131607 3389 0 0
x_make_qstorno_2100_3300 446842 446842 0 0.00 0 51485 240 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 24-02-2023 07:39:55 to 24-02-2023 07:39:59.

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:28 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
05:08:29 36107 8192000000 9715675248 10010103808 786832016 8.763 100 88 176 0 816513424 7743056 21938352
05:10:08 17668 8192000000 9746201344 10050838528 822785616 9.127 100 90 180 1 857248944 12326480 22136848
05:11:36 14997 8192000000 9804307616 10120474624 861794720 9.518 100 86 170 1 903914576 21903008 20216848
05:13:00 8209 8192000000 9849366800 10186063872 913471056 10.032 100 67 134 0 958663968 31290928 13901984
05:14:18 4151 8192000000 9911628528 10258563072 958565760 10.475 100 73 146 1 1030776336 56481680 15728896
05:15:32 3057 8192000000 9963460112 10318172160 994970368 10.830 100 87 170 0 1081458816 68827536 17660912
05:16:37 2123 8192000000 9982143488 10343292928 1019556640 11.068 100 88 167 0 1102988496 66659616 16772240
05:17:43 1325 8192000000 10012820656 10382434304 1038788784 11.253 100 93 181 0 1132374896 75294416 18291696
05:18:48 1105 8192000000 10030443072 10404773888 1049039328 11.351 100 87 164 0 1146791120 81259520 16492272
05:19:51 1851 8192000000 10043687056 10418024448 1056711504 11.425 100 93 182 0 1160662784 85922240 18029040
05:20:57 1088 8192000000 10039862560 10416013312 1064308960 11.498 100 95 178 0 1159532240 76946560 18276720
05:22:03 1352 8192000000 10028205632 10407288832 1069541456 11.548 100 79 152 0 1150917488 66266336 15109696
05:23:08 1319 8192000000 10029753056 10409414656 1072957376 11.580 100 88 171 0 1147485568 57326496 17201696
05:24:10 1118 8192000000 10037076368 10422566912 1082931072 11.675 100 93 174 6 1160231088 58061648 19238368
05:25:13 1278 8192000000 10055091152 10438520832 1088305936 11.727 100 98 188 2 1179157184 71559824 19291424
05:26:15 1013 8192000000 10069806128 10460745728 1092620080 11.768 100 89 173 2 1191592144 81559760 17412304
05:27:17 1161 8192000000 10066240912 10456006656 1099507872 11.833 100 90 172 2 1190253520 73544208 17201440
05:28:20 1024 8192000000 10055412016 10446659584 1091083568 11.753 99 88 172 1 1179728144 70646144 17998432
05:29:22 1127 8192000000 10071493488 10464317440 1106162080 11.896 100 91 173 4 1198423728 74225920 18035728
05:30:24 3491 8192000000 10025717696 10419163136 1085794736 11.703 99 68 129 0 1152910960 53707616 13408608
05:31:28 3425 8192000000 9992435552 10375364608 1052878880 11.388 98 65 117 4 1114428912 51075104 10474928
05:32:32 3890 8192000000 10038492576 10430967808 1078071952 11.629 100 70 131 0 1167962992 77369104 12521936
05:33:38 2393 8192000000 9994951264 10380476416 1056028416 11.418 100 69 130 1 1115010448 47133664 11848368
05:34:41 4751 8192000000 10008289392 10389864448 1034341680 11.210 98 61 114 2 1107446832 61368160 11736992
05:35:47 966 8192000000 9960141200 10325229568 1018663504 11.059 100 93 173 0 1081629456 46264752 16701200
05:36:50 1118 8192000000 9990431632 10363518976 1039610896 11.261 100 90 170 1 1108782192 53272720 15898576
05:37:57 11858 8192000000 10005739424 10379563008 1047417792 11.336 100 77 143 0 1128717744 66751920 14548032
05:39:19 1762 8192000000 10038220304 10420154368 1070115696 11.553 100 77 147 2 1162037696 76760944 15161056
05:40:25 8951 8192000000 10075445968 10461544448 1082314016 11.670 100 67 130 3 1201444608 105380256 13750336
05:41:41 15844 8192000000 10086831616 10483154944 1097368816 11.813 100 65 126 2 1205896224 95304896 13222512
05:43:00 8088 8192000000 10101824304 10496413696 1113623232 11.967 100 82 150 0 1224168656 94552800 15992624
05:44:11 2972 8192000000 10156329104 10545225728 1116976512 11.998 100 83 154 0 1273724160 139808384 16939264
05:45:22 1999 8192000000 10178170736 10570743808 1133187488 12.151 100 82 162 1 1292587456 142385808 17014160
05:46:27 1532 8192000000 10187468720 10591694848 1136655408 12.184 100 87 168 0 1301206080 146448768 18101904
05:47:33 4286 8192000000 10229981216 10625507328 1141673744 12.231 100 93 177 1 1336371392 175024880 19672768
05:48:43 1538 8192000000 10233647008 10654363648 1148143136 12.292 100 92 174 1 1351065392 183055200 19867056
05:49:50 1038 8192000000 10263256784 10662244352 1162777152 12.429 100 93 177 2 1376181728 193690080 19714496
05:50:53 1669 8192000000 10300128112 10702835712 1161497088 12.417 100 98 187 6 1407250464 225114912 20638464
05:51:59 908 8192000000 10325572224 10728517632 1170110800 12.498 100 96 184 3 1431729984 241181600 20437584
05:53:04 1047 8192000000 10353971168 10761138176 1169996480 12.497 100 95 186 0 1468086672 275084144 23006048
05:54:10 840 8192000000 10380929392 10801803264 1185309456 12.640 100 97 189 0 1488971552 282091264 21570832
05:55:14 2076 8192000000 10411083712 10815475712 1188324416 12.668 100 94 178 0 1520111008 310752288 21034304
05:56:22 1539 8192000000 10414829872 10819493888 1181927648 12.608 99 94 186 2 1522269744 319545440 20796656
05:57:30 2092 8192000000 10431360144 10849095680 1188590896 12.670 99 90 176 2 1539480960 329219248 21670816
05:58:37 981 8192000000 10511192384 10918633472 1207212480 12.843 100 98 191 1 1616277600 386088576 22976544
05:59:42 1088 8192000000 10549286288 10965860352 1213343552 12.900 100 99 191 0 1656195424 419059024 23792848
06:00:47 826 8192000000 10552085792 11006865408 1209799376 12.867 100 97 189 0 1655305456 421265280 24240800
06:01:51 906 8192000000 10580227520 11023478784 1193181392 12.713 100 89 176 0 1682555696 467829408 21544896
06:02:56 1312 8192000000 10596413216 11029336064 1199142432 12.768 100 95 183 0 1698765760 477320704 22302624
06:04:00 5072 8192000000 10607724240 11077820416 1197104736 12.749 100 59 116 0 1709432256 497104336 15223184
06:05:09 8959 8192000000 10602218288 11061297152 1197454848 12.753 100 46 90 1 1719075760 508067760 13553152
06:06:26 2808 8192000000 10618951856 11061673984 1186597600 12.652 100 67 130 0 1725177904 522334704 16245600
06:07:45 12329 8192000000 10601608048 11105239040 1176454144 12.557 99 49 92 3 1710791408 522594640 11742624
06:08:59 9618 8192000000 10500451152 11011194880 1164786512 12.448 100 34 68 0 1638477504 464371488 9319504
06:10:17 10372 8192000000 10412015376 10898132992 1129416912 12.116 99 43 86 0 1530231776 389029760 11785104
06:11:40 6428 8192000000 10286217280 10752892928 1154002128 12.347 100 44 84 2 1390152272 226253760 9896384
06:12:53 2892 8192000000 10292420784 10747404288 1146600768 12.278 100 84 166 0 1412031840 246731248 18699824
06:13:59 7693 8192000000 10278024096 10748649472 1154452672 12.351 100 51 103 1 1382905824 216970992 11482160
06:15:09 8807 8192000000 10207982048 10695229440 1148474928 12.295 99 58 112 0 1336028224 175309504 12243792
06:16:20 7670 8192000000 10198169248 10631061504 1134597792 12.165 99 45 87 1 1295409040 151495312 9315936
06:17:31 12111 8192000000 10258259744 10674085888 1169014688 12.488 100 55 110 1 1354211328 172765120 12431520
06:18:46 8304 8192000000 10322371184 10762846208 1152894864 12.337 100 50 100 1 1423478256 258308112 12275280
06:19:58 181654 8192000000 10308575136 10760499200 1146089680 12.273 99 47 94 1 1433992624 275985056 11917888
06:25:04 43499 8192000000 10225056192 10706587648 1121366096 12.040 98 28 52 1 1315190928 187900352 5924480
06:26:52 52900 8192000000 10146854464 10673831936 1126390208 12.087 100 17 34 0 1291675600 161087296 4198096
06:28:46 83776 8192000000 10086554768 10575458304 1121251904 12.039 99 11 22 0 1189791072 66310976 2228192
06:31:10 88882 8192000000 10111951424 10557972480 1141011360 12.225 100 19 35 0 1188956960 44659552 3286048
06:34:58 67615 8192000000 10114831360 10626805760 1125909712 12.083 99 15 30 0 1210731680 81580064 3241904
06:37:06 76206 8192000000 10123594784 10600964096 1134336320 12.162 99 18 36 0 1216555600 78603136 3616144
06:39:58 74683 8192000000 10063405536 10529959936 1124927360 12.074 99 18 35 1 1174706016 46380240 3398416
06:42:13 33313 8192000000 10066700048 10509549568 1120146704 12.028 99 33 63 0 1171237296 44345104 6745488
06:43:49 25270 8192000000 10072550336 10504634368 1133363744 12.153 100 61 112 3 1174673056 30368160 10941152
06:45:16 26330 8192000000 10097285088 10532868096 1136143136 12.179 100 34 66 1 1193434960 51175216 6116608
06:46:44 70268 8192000000 10097324432 10587119616 1144396960 12.257 100 19 38 0 1232521200 84140112 3984128
06:49:25 94675 8192000000 10088712048 10564816896 1125393104 12.078 98 17 34 0 1189426048 60583184 3449760
06:53:13 80855 8192000000 10059979968 10522619904 1120234688 12.029 98 26 51 1 1147378368 21942704 5200976
06:55:35 21770 8192000000 10099011216 10556096512 1138877776 12.205 100 37 72 1 1196654832 50582144 7194912
06:57:35 56421 8192000000 10117148240 10604662784 1140979424 12.225 99 25 46 1 1225822752 79553936 5289392
06:59:32 96962 8192000000 10082268688 10573340672 1132038768 12.141 99 19 38 0 1204631072 68524608 4067696
07:02:09 7875 8192000000 10093079200 10528358400 1146005616 12.272 100 38 74 1 1195332336 41783856 7542864
07:03:18 12835 8192000000 10113739264 10560757760 1101839728 11.855 97 41 76 1 1203562288 93707424 8015136
07:04:36 24701 8192000000 10055094160 10480021504 1086995248 11.714 98 32 64 0 1133392080 39607424 6789408
07:06:02 94924 8192000000 10054103904 10526638080 1108549536 11.919 99 28 56 0 1168792064 53992960 6249568
07:08:37 57420 8192000000 10095503200 10525073408 1112568544 11.957 98 52 104 0 1188647376 64713488 11365344
07:10:38 78795 8192000000 10079436160 10539102208 1106865824 11.903 98 10 20 0 1162608992 53606080 2137088
07:13:11 66181 8192000000 10101065248 10560065536 1135644816 12.175 100 19 37 0 1216399472 76719776 4034880
07:15:55 58303 8192000000 10043495008 10494410752 1061344960 11.469 94 13 26 0 1105885424 42077296 2463168
07:17:54 29030 8192000000 10114438992 10556760064 1115600800 11.985 99 49 98 0 1185816848 60133424 10082624
07:19:27 18488 8192000000 10147650768 10580897792 1143165280 12.245 100 45 90 0 1243270016 90491424 9613312
07:20:46 48961 8192000000 10188690352 10636636160 1134420592 12.163 99 34 68 0 1283892912 142352176 7120144
07:22:42 24514 8192000000 10128678720 10582331392 1134086464 12.160 100 33 66 0 1232413424 91795376 6531584
07:24:08 37120 8192000000 10130321360 10573176832 1126261728 12.086 99 34 66 0 1219543024 86346848 6934448
07:26:01 74984 8192000000 10065708304 10521063424 1127742432 12.100 99 16 32 0 1167774784 36533632 3498720
07:28:33 32678 8192000000 10170087712 10600726528 1136213264 12.180 99 42 85 1 1235270064 90751376 8305424
07:30:09 101874 8192000000 10058805728 10499444736 1108599136 11.919 98 36 72 1 1150288528 33588672 8100720
07:32:57 66161 8192000000 10051868864 10527756288 1126397376 12.087 100 17 34 0 1185939824 55632976 3909472
07:35:04 65127 8192000000 10092158688 10566115328 1103060768 11.867 97 18 35 0 1173052128 66321024 3670336
07:37:09 41639 8192000000 9099308736 9386389504 241039808 2.858 22 3 6 0 287760032 46040944 679280

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 24-02-2023 07:40:01 to 24-02-2023 07:40:17.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 116 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:03, from 24-02-2023 07:40:18 to 24-02-2023 07:40:21.

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          209463
MON$OLDEST_ACTIVE               209464
MON$OLDEST_SNAPSHOT             209464
MON$NEXT_TRANSACTION            209464
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                       2150272
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             756
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

24.02.23 07:40:22. 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-02-24 07:40:24 No severe PSQL-related problems occured
24.02.23 07:40:26. End of report.