Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 15.05.2022 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 16354132 kB
MemAvailable 31488316 kB
Buffers 0 kB
Cached 15385104 kB
SwapCached 2660 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-05-15 07:37:17
DTS_END                         2022-05-15 07:37:17.2250
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/52732
TRN_ID                          215486
ATT_ID                          630
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: 8723.11 23514 1046773
customer order: creation 169.44 463 20333
customer order: refuse 14.19 439 1703
order to supplier: creation 45.73 1406 5488
order to supplier: removal 3.18 189786 382
invoice (draft): creation 45.18 3339 5421
invoice (draft): removal 48.30 1069 5796
invoice accept: apply 40.42 28653 4850
invoice accept: cancel 43.77 1367 5252
customer reserve: creation 3754.63 274 450556
customer reserve: removal 4269.28 110 512314
realization accept: apply 81.43 277 9771
realization accept: cancel 80.62 189 9674
payment from customer: creation 57.48 175 6897
payment from customer: removal 5.83 194 700
payment to supplier: creation 47.71 203 5725
payment to supplier: removal 3.36 120 403
service: total inventory turnovers 4.06 4074 487
service: total monetary turnovers 7.52 411 902
service: aggregate perf. data 0.95 16247 114
service: refresh index statistics 0.04 221490 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 15-05-2022 07:39:53 to 15-05-2022 07:39:55.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5779
TEST_TIME 2 5794
TEST_TIME 3 5800
TEST_TIME 4 5910
TEST_TIME 5 5636
TEST_TIME 6 5824
TEST_TIME 7 5610
TEST_TIME 8 5845
TEST_TIME 9 5767
TEST_TIME 10 5810
TEST_TIME 11 6142
TEST_TIME 12 6151
TEST_TIME 13 6365
TEST_TIME 14 6430
TEST_TIME 15 6475
TEST_TIME 16 6738
TEST_TIME 17 6702
TEST_TIME 18 6830
TEST_TIME 19 7051
TEST_TIME 20 7018
TEST_TIME 21 6946
TEST_TIME 22 6901
TEST_TIME 23 6920
TEST_TIME 24 7106
TEST_TIME 25 7489
TEST_TIME 26 7762
TEST_TIME 27 8076
TEST_TIME 28 8421
TEST_TIME 29 8687
TEST_TIME 30 8751
TEST_TIME 31 9121
TEST_TIME 32 9633
TEST_TIME 33 10101
TEST_TIME 34 10507
TEST_TIME 35 10612
TEST_TIME 36 10560
TEST_TIME 37 10429
TEST_TIME 38 10298
TEST_TIME 39 10270
TEST_TIME 40 10044
TEST_TIME 41 10039
TEST_TIME 42 9906
TEST_TIME 43 9932
TEST_TIME 44 9780
TEST_TIME 45 9770
TEST_TIME 46 9691
TEST_TIME 47 9581
TEST_TIME 48 9441
TEST_TIME 49 9378
TEST_TIME 50 9285
TEST_TIME 51 9246
TEST_TIME 52 9173
TEST_TIME 53 9169
TEST_TIME 54 9138
TEST_TIME 55 9140
TEST_TIME 56 9060
TEST_TIME 57 9009
TEST_TIME 58 8999
TEST_TIME 59 8932
TEST_TIME 60 8862
TEST_TIME 61 8824
TEST_TIME 62 8852
TEST_TIME 63 8825
TEST_TIME 64 8820
TEST_TIME 65 8866
TEST_TIME 66 8932
TEST_TIME 67 8875
TEST_TIME 68 8871
TEST_TIME 69 8978
TEST_TIME 70 8952
TEST_TIME 71 8996
TEST_TIME 72 9027
TEST_TIME 73 9008
TEST_TIME 74 9007
TEST_TIME 75 9039
TEST_TIME 76 9085
TEST_TIME 77 8995
TEST_TIME 78 8934
TEST_TIME 79 8969
TEST_TIME 80 8931
TEST_TIME 81 8920
TEST_TIME 82 8860
TEST_TIME 83 8832
TEST_TIME 84 8771
TEST_TIME 85 8712
TEST_TIME 86 8699
TEST_TIME 87 8664
TEST_TIME 88 8637
TEST_TIME 89 8669
TEST_TIME 90 8655
TEST_TIME 91 8717
TEST_TIME 92 8714
TEST_TIME 93 8725
TEST_TIME 94 8723
TEST_TIME 95 8720
TEST_TIME 96 8739
TEST_TIME 97 8815
TEST_TIME 98 8834
TEST_TIME 99 8869
TEST_TIME 100 8892
TEST_TIME 101 8837
TEST_TIME 102 8782
TEST_TIME 103 8775
TEST_TIME 104 8776
TEST_TIME 105 8783
TEST_TIME 106 8731
TEST_TIME 107 8678
TEST_TIME 108 8724
TEST_TIME 109 8716
TEST_TIME 110 8732
TEST_TIME 111 8743
TEST_TIME 112 8739
TEST_TIME 113 8731
TEST_TIME 114 8838
TEST_TIME 115 8828
TEST_TIME 116 8809
TEST_TIME 117 8836
TEST_TIME 118 8864
TEST_TIME 119 8820
TEST_TIME 120 8754

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 15-05-2022 07:39:57 to 15-05-2022 07:40:00.

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 1461200 1461200 0 0.00 0 23661 15 0 0
doc_list_biud 1461192 1461192 0 0.00 0 41884 44 0 0
sp_add_invoice_to_stock 4973 4850 123 2.47 49 266516 28654 0 123
sp_cancel_adding_invoice 5252 5252 0 0.00 6 98142 1368 0 0
sp_cancel_client_order 1703 1703 0 0.00 5 15337 440 0 0
sp_cancel_customer_reserve 512314 512314 0 0.00 1 34819 110 0 0
sp_cancel_pay_from_customer 700 700 0 0.00 4 8866 194 0 0
sp_cancel_pay_to_supplier 403 403 0 0.00 3 2098 121 0 0
sp_cancel_supplier_invoice 5808 5796 12 0.20 2 24139 1069 0 12
sp_cancel_supplier_order 382 382 0 0.00 15 1477802 189786 0 0
sp_cancel_write_off 9674 9674 0 0.00 2 6408 190 0 0
sp_client_order 20333 20333 0 0.00 6 16435 464 0 0
sp_customer_reserve 450556 450556 0 0.00 1 65797 274 0 0
sp_fill_shopping_cart 31236 31236 0 0.00 2 24489 88 0 0
sp_fill_shopping_cart_clo_res 450523 450523 0 0.00 0 15411 20 0 0
sp_get_clo_for_invoice 4809 4809 0 0.00 0 20889 278 0 0
sp_kill_cost_storno 16029 16029 0 0.00 0 13343 88 0 0
sp_kill_qstorno_ret_qs2qd 1040480 1040480 0 0.00 0 34810 47 0 0
sp_kill_qty_storno 549701 549701 0 0.00 0 41882 108 0 0
sp_lock_selected_doc 20270 20270 0 0.00 0 5654 8 0 0
sp_make_cost_storno 27202 27202 0 0.00 0 19598 82 0 0
sp_multiply_rows_for_pdistr 27202 27202 0 0.00 0 12180 42 0 0
sp_multiply_rows_for_qdistr 20333 20333 0 0.00 0 15608 185 0 0
sp_pay_from_customer 6897 6897 0 0.00 5 18208 175 0 0
sp_pay_to_supplier 5725 5725 0 0.00 7 19612 204 0 0
sp_payment_common 12622 12622 0 0.00 2 5827 45 0 0
sp_qd_handle_on_cancel_clo 1703 1703 0 0.00 1 15302 371 0 0
sp_qd_handle_on_invoice_upd_sts 10061 10061 0 0.00 0 41881 574 0 0
sp_qd_handle_on_reserve_upd_sts 9771 9771 0 0.00 0 10388 53 0 0
sp_reserve_write_off 9771 9771 0 0.00 9 20882 277 0 0
sp_supplier_invoice 5421 5421 0 0.00 10 101602 3340 0 0
sp_supplier_order 5488 5488 0 0.00 7 31187 1406 0 0
srv_aggregate_perf_data 114 114 0 0.00 2556 267755 16248 0 0
srv_fill_mon_cache_memory 72 72 0 0.00 853 164224 32996 0 0
srv_find_qd_qs_mism 998597 998597 0 0.00 0 21676 12 0 0
srv_make_invnt_saldo 487 487 0 0.00 39 64988 4075 0 0
srv_make_money_saldo 902 902 0 0.00 1 21053 411 0 0
srv_recalc_idx_stat 5 5 0 0.00 121496 421905 221491 0 0
t$perf-abend:sp_add_invoice_to_stock 123 123 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-norm:sp_add_invoice_to_stock 4856 4856 0 0.00 1 10837 941 0 0
t$perf-norm:sp_cancel_adding_invoice 417 417 0 0.00 1 8901 447 0 0
t$perf-norm:sp_cancel_client_order 1703 1703 0 0.00 0 5418 42 0 0
t$perf-norm:sp_cancel_customer_reserve 676 676 0 0.00 0 3102 43 0 0
t$perf-norm:sp_cancel_pay_from_customer 700 700 0 0.00 0 4412 27 0 0
t$perf-norm:sp_cancel_pay_to_supplier 403 403 0 0.00 0 9332 40 0 0
t$perf-norm:sp_cancel_supplier_invoice 398 398 0 0.00 0 1243 33 0 0
t$perf-norm:sp_cancel_supplier_order 382 382 0 0.00 0 104131 7911 0 0
t$perf-norm:sp_cancel_write_off 1013 1013 0 0.00 0 915 18 0 0
t$perf-norm:sp_client_order 20333 20333 0 0.00 0 10697 40 0 0
t$perf-norm:sp_customer_reserve 953 953 0 0.00 0 1890 15 0 0
t$perf-norm:sp_pay_from_customer 6897 6897 0 0.00 0 8787 19 0 0
t$perf-norm:sp_pay_to_supplier 5725 5725 0 0.00 0 7347 22 0 0
t$perf-norm:sp_reserve_write_off 9771 9771 0 0.00 0 10219 26 0 0
t$perf-norm:sp_supplier_invoice 5421 5421 0 0.00 0 7097 35 0 0
t$perf-norm:sp_supplier_order 5488 5488 0 0.00 0 4549 33 0 0
t$perf-norm:srv_aggregate_perf_data 114 114 0 0.00 0 1498 43 0 0
t$perf-norm:srv_fill_mon_cache_memory 72 72 0 0.00 0 312 8 0 0
t$perf-norm:srv_make_invnt_saldo 487 487 0 0.00 0 1256 18 0 0
t$perf-norm:srv_make_money_saldo 902 902 0 0.00 0 764 7 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 8 157 87 0 0
v_add_invoice_to_stock 9860 9614 246 2.49 0 5560 24 0 246
v_all_customers 37372 37372 0 0.00 0 238 4 0 0
v_all_suppliers 30240 30240 0 0.00 0 148 4 0 0
v_all_wares 40664 40664 0 0.00 0 183 4 0 0
v_cancel_adding_invoice 834 834 0 0.00 0 2508 18 0 0
v_cancel_client_order 3406 3406 0 0.00 0 3355 11 0 0
v_cancel_customer_prepayment 1400 1400 0 0.00 0 2014 12 0 0
v_cancel_customer_reserve 1352 1352 0 0.00 0 3352 13 0 0
v_cancel_payment_to_supplier 806 806 0 0.00 0 1892 15 0 0
v_cancel_supplier_invoice 820 796 24 2.92 0 3347 31 0 24
v_cancel_supplier_order 764 764 0 0.00 0 993 12 0 0
v_cancel_write_off 2026 2026 0 0.00 0 5789 30 0 0
v_max_id_clo_ord 5487 5487 0 0.00 0 2431 5 0 0
v_max_id_clo_res 953 953 0 0.00 0 1635 18 0 0
v_max_id_ord_sup 5416 5416 0 0.00 0 4069 7 0 0
v_max_non_paid_invoice 1508 1508 0 0.00 0 1601 9 0 0
v_max_non_paid_realizn 3488 3488 0 0.00 0 2872 8 0 0
v_min_id_clo_ord 5487 5487 0 0.00 0 3599 11 0 0
v_min_id_clo_res 953 953 0 0.00 0 1356 10 0 0
v_min_id_ord_sup 5416 5416 0 0.00 0 7988 11 0 0
v_min_non_paid_invoice 5725 5725 0 0.00 0 5822 14 0 0
v_min_non_paid_realizn 6897 6897 0 0.00 0 5097 16 0 0
v_our_firm 10110 10110 0 0.00 0 192 4 0 0
v_reserve_write_off 19542 19542 0 0.00 0 4405 11 0 0
x_lock_depdocs_on_canc_invoice 10650 10650 0 0.00 0 24524 154 0 0
x_lock_depdocs_on_canc_sup_ord 382 382 0 0.00 0 4963 393 0 0
x_make_qstorno_1000_1200 5488 5488 0 0.00 3 31127 1226 0 0
x_make_qstorno_1200_2000 5421 5421 0 0.00 6 97716 3091 0 0
x_make_qstorno_2100_3300 450555 450555 0 0.00 0 65796 242 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 15-05-2022 07:40:01 to 15-05-2022 07:40:05.

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:19 2 8192000000 8797595104 8808550400 3189120 0.038 1 1 2 0 3286736 61840 35776
05:08:20 35125 8192000000 9734775296 10038202368 810368144 9.001 100 89 178 0 839780592 7302768 22109680
05:10:00 19284 8192000000 9773259456 10082533376 845204448 9.352 100 91 182 0 880182720 12895840 22082432
05:11:26 22832 8192000000 9814966144 10137714688 873023968 9.630 100 87 174 1 919951360 26785536 20141856
05:13:03 8524 8192000000 9859343456 10197245952 920521920 10.101 100 78 155 1 980409536 43244496 16643120
05:14:15 4748 8192000000 9917025728 10261176320 960609776 10.495 100 85 167 0 1033297408 55509632 17178000
05:15:22 2961 8192000000 9947662208 10302402560 987905216 10.761 100 84 163 1 1065080816 60829984 16345616
05:16:28 1698 8192000000 10004161344 10366169088 1014858512 11.022 100 91 177 0 1123848960 91588304 17402144
05:17:32 1100 8192000000 10003411232 10375745536 1041154848 11.276 100 96 183 2 1126730000 64978416 20596736
05:18:35 849 8192000000 10025646256 10398322688 1056816688 11.426 100 97 186 0 1143905216 68718832 18369696
05:19:37 1157 8192000000 10055521600 10433130496 1070134400 11.553 100 98 193 0 1177327280 88277952 18914928
05:20:42 1053 8192000000 10046633760 10424438784 1079630864 11.644 100 97 184 2 1168872288 69972128 19269296
05:21:44 1087 8192000000 10058853008 10444468224 1081024864 11.657 100 97 188 0 1184395792 84013760 19357168
05:22:46 1215 8192000000 10070906880 10460139520 1093378976 11.775 100 96 183 0 1203525648 90546160 19600512
05:23:49 1393 8192000000 10044286000 10429644800 1092237040 11.764 100 95 184 4 1173226992 61799488 19190464
05:24:51 1374 8192000000 10061870464 10451582976 1096395152 11.803 100 96 189 1 1186387008 71269728 18722128
05:25:54 9874 8192000000 10043616304 10441203712 1099574656 11.834 100 72 131 0 1179231728 66625232 13031840
05:27:05 1335 8192000000 10077583136 10467123200 1104987696 11.885 100 96 181 0 1203022432 79777968 18256768
05:28:07 1351 8192000000 10053546272 10446835712 1103262960 11.869 100 81 153 0 1176329216 56484752 16581504
05:29:10 1209 8192000000 10084652480 10476441600 1109513328 11.928 100 95 185 1 1205956304 77794544 18648432
05:30:13 1452 8192000000 10037663296 10424393728 1080396416 11.651 100 95 183 2 1162354448 63545824 18412208
05:31:16 1206 8192000000 10031815856 10412617728 1069729408 11.549 100 94 182 3 1152198912 63616864 18852640
05:32:18 1170 8192000000 10036940976 10414002176 1073299536 11.584 100 97 187 0 1159267744 67767312 18200896
05:33:20 1169 8192000000 10014970800 10387767296 1050998528 11.370 100 95 178 1 1142972256 72129536 19844192
05:34:22 1076 8192000000 10001282512 10376630272 1042085680 11.285 99 86 167 3 1121030800 62180416 16764704
05:35:24 1000 8192000000 10002036400 10378997760 1047784000 11.339 100 86 160 0 1121618048 56234416 17599632
05:36:27 1395 8192000000 10002692720 10376196096 1038522960 11.250 100 97 187 1 1124784032 66637456 19623616
05:37:33 10371 8192000000 10007125328 10392862720 1056597120 11.424 100 60 116 2 1128975968 60269408 12109440
05:38:51 8789 8192000000 10026691616 10409959424 1071177680 11.563 100 72 136 1 1150582448 66651328 12753440
05:40:04 1867 8192000000 10069452592 10456207360 1091176464 11.754 100 80 150 3 1178321840 72527776 14617600
05:41:11 1390 8192000000 10064043104 10444767232 1090066320 11.743 100 98 183 3 1186211312 75817232 20327760
05:42:16 7447 8192000000 10099296768 10487455744 1107398368 11.908 100 70 134 1 1221737328 100629792 13709168
05:43:29 2113 8192000000 10117084080 10505859072 1116111232 11.990 100 79 153 0 1235642608 104450928 15080448
05:44:35 1118 8192000000 10179968144 10569314304 1125881600 12.083 100 92 174 1 1291732176 147273728 18576848
05:45:41 6472 8192000000 10198743936 10604875776 1130500272 12.126 100 77 151 0 1317356544 169560432 17295840
05:46:57 3414 8192000000 10219326080 10618724352 1140688432 12.222 100 75 148 1 1334067888 176083936 17295520
05:48:06 5503 8192000000 10244915472 10658611200 1157705024 12.382 100 72 142 1 1359255728 185648400 15902304
05:49:19 1271 8192000000 10309326496 10708987904 1182390832 12.612 100 94 181 1 1420892848 218414736 20087280
05:50:27 882 8192000000 10344043312 10760663040 1180907120 12.599 100 87 169 1 1455780080 255593520 19279440
05:51:35 3216 8192000000 10350926976 10775863296 1194930848 12.729 100 75 148 1 1463292816 250286704 18075264
05:52:42 1124 8192000000 10372957312 10796982272 1193686624 12.718 100 98 192 1 1479584976 263088848 22809504
05:53:46 1317 8192000000 10427346336 10831880192 1196393872 12.743 100 95 190 2 1536241200 317666320 22181008
05:54:52 4199 8192000000 10451581776 10890985472 1206776000 12.839 100 90 178 3 1561519008 333871296 20871712
05:56:01 925 8192000000 10498818032 10930282496 1216231664 12.927 100 97 189 1 1605923568 368435408 21256496
05:57:05 845 8192000000 10532908832 10939932672 1212673152 12.894 100 95 184 1 1640159600 404933904 22552544
05:58:08 1217 8192000000 10587571376 10993758208 1218957280 12.952 100 98 193 0 1689797952 447783040 23057632
05:59:13 2009 8192000000 10612677376 11028226048 1201682992 12.792 99 88 176 1 1717678320 493396832 22598496
06:00:19 934 8192000000 10650066784 11090034688 1214822256 12.914 100 95 189 1 1755613056 517399040 23391760
06:01:23 2141 8192000000 10633943504 11085713408 1200053904 12.777 100 86 167 0 1738549136 517861232 20634000
06:02:28 948 8192000000 10659334336 11102527488 1196390704 12.743 99 97 188 4 1764007232 544564432 23052096
06:03:33 1448 8192000000 10624583968 11120291840 1182417536 12.613 100 85 163 0 1728756080 526186000 20152544
06:04:38 3934 8192000000 10588244304 11018694656 1176943248 12.562 100 54 108 0 1702367968 511119104 14305616
06:05:57 11092 8192000000 10529495328 11064680448 1181533248 12.604 100 38 76 0 1629807248 438117488 10156512
06:07:13 14825 8192000000 10515759248 10995351552 1173587072 12.530 100 29 58 2 1629275568 447791296 7897200
06:08:32 12753 8192000000 10434617472 10933231616 1155597600 12.362 99 35 68 3 1532278256 367662096 9018560
06:09:47 41728 8192000000 10266095264 10759942144 1154192496 12.349 100 25 51 1 1421246592 260712608 6341488
06:11:45 72750 8192000000 10069692336 10533220352 1067597776 11.529 93 22 41 1 1109300128 37630768 4071584
06:13:58 27600 8192000000 10106809104 10523729920 1099382336 11.832 96 51 93 3 1169599296 61314480 8902480
06:15:32 139855 8192000000 10142073472 10634686464 1140467248 12.220 100 29 58 2 1249163072 102141968 6553856
06:18:54 21065 8192000000 10093365856 10532507648 1123407344 12.059 99 26 48 0 1184759552 56349344 5002864
06:20:44 43639 8192000000 10081875664 10535489536 1115401168 11.984 98 41 76 1 1159867872 35604544 8862160
06:22:50 41349 8192000000 10065937872 10532237312 1127801552 12.101 100 19 37 1 1174451232 42579680 4070000
06:24:36 59557 8192000000 10078192592 10522693632 1108322112 11.917 98 27 53 1 1152127104 38358224 5446768
06:26:47 111597 8192000000 10170517440 10646503424 1128358176 12.106 99 21 42 1 1239057952 105548352 5151424
06:30:05 59625 8192000000 10072516288 10565152768 1123128416 12.057 99 27 54 2 1192516160 63949680 5438064
06:32:10 32916 8192000000 10097699264 10559565824 1139847744 12.214 100 39 72 2 1198019936 50566672 7605520
06:33:48 46273 8192000000 10069283744 10556108800 1125087808 12.075 99 15 30 0 1187477088 58985616 3403664
06:35:38 71409 8192000000 10042629104 10516082688 1120668368 12.033 99 18 36 1 1167943520 42878336 4396816
06:37:50 75396 8192000000 10086173520 10557288448 1134835664 12.167 99 16 30 1 1186532720 48738128 2958928
06:40:13 38839 8192000000 10073813136 10517692416 1126128752 12.085 99 38 76 0 1151003552 17422384 7452416
06:41:54 57127 8192000000 10075097152 10538852352 1089457840 11.738 96 31 62 2 1172592704 77024352 6110512
06:43:52 23937 8192000000 10072565008 10508922880 1139885328 12.214 100 37 70 0 1174321840 27520192 6916320
06:45:16 19552 8192000000 10145168176 10580238336 1141258496 12.227 100 42 84 1 1231783648 82181184 8343968
06:47:18 68180 8192000000 10114951024 10585223168 1132521504 12.145 99 30 51 0 1197741792 60123968 5096320
06:49:35 46718 8192000000 10104405728 10528067584 1102986992 11.866 96 33 64 0 1156384240 47485152 5912096
06:51:27 27285 8192000000 10110877184 10558869504 1120433088 12.031 98 37 74 0 1180159552 52598304 7128160
06:53:13 69790 8192000000 10096539056 10580779008 1083863184 11.684 96 19 38 0 1141447408 52937008 4647216
06:56:48 32163 8192000000 10068872912 10531364864 1113904656 11.969 99 24 46 1 1161266992 41754544 5607792
06:58:23 66907 8192000000 10042553456 10499223552 1118144496 12.009 99 18 36 0 1150067136 27792256 4130384
07:00:30 57064 8192000000 10040899536 10507804672 1123977552 12.065 100 9 18 1 1149244528 23335120 1931856
07:02:30 76293 8192000000 10069477264 10540351488 1110348416 11.936 98 12 24 0 1149178720 36097616 2732688
07:04:48 42645 8192000000 10128852992 10571407360 1138035056 12.197 99 29 55 0 1212002784 67202016 6765712
07:06:31 38843 8192000000 10103862144 10552295424 1122469344 12.050 98 24 48 2 1194317728 67006528 4841856
07:08:11 49505 8192000000 10079515728 10529427456 1116758480 11.996 98 28 55 1 1174110656 51374928 5977248
07:10:08 48151 8192000000 10084270320 10534588416 1085259584 11.698 96 41 82 2 1161875328 68052192 8563552
07:11:57 5889 8192000000 10113602320 10526146560 1125196848 12.076 99 76 151 0 1196790416 56872784 14720784
07:13:06 34730 8192000000 10142893472 10614026240 1130311600 12.124 100 45 90 0 1254358400 114964576 9082224
07:14:44 28662 8192000000 10151126512 10603270144 1106487424 11.899 98 40 80 1 1199163216 84733328 7942464
07:17:23 19418 8192000000 10091471088 10517889024 1132346752 12.143 100 37 72 1 1175222832 34434752 8441328
07:18:43 164224 8192000000 10116346896 10629435392 1120881376 12.035 98 17 34 1 1236948880 112243568 3823936
07:22:29 61181 8192000000 10107524096 10601553920 1131059792 12.131 99 12 24 1 1193739552 60116048 2563712
07:24:37 76129 8192000000 10116596928 10605215744 1111764912 11.949 97 14 28 1 1195866176 79974896 4126368
07:26:56 29530 8192000000 10084220896 10541715456 1106674928 11.901 98 27 49 2 1192386176 80541792 5169456
07:28:33 57845 8192000000 10139623216 10574561280 1128411600 12.106 99 33 66 0 1213061328 77388464 7261264
07:30:37 48227 8192000000 10137246816 10594840576 1115603376 11.985 98 23 46 2 1233042480 112325376 5113728
07:32:33 23764 8192000000 10051652592 10476990464 1104973072 11.885 98 45 86 2 1135925584 22199600 8752912
07:34:07 42969 8192000000 10109273296 10578432000 1090928992 11.751 97 42 84 0 1192877136 93520176 8427968
07:35:54 12607 8192000000 10073211216 10484465664 1118883552 12.016 100 79 148 1 1163272048 29042944 15345552
07:37:11 48942 8192000000 9020384000 9267519488 179874736 2.148 17 4 6 1 190474160 9975056 624368

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-05-2022 07:40:07 to 15-05-2022 07:40:23.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 123 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 15-05-2022 07:40:24 to 15-05-2022 07:40:26.

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          215485
MON$OLDEST_ACTIVE               215765
MON$OLDEST_SNAPSHOT             215765
MON$NEXT_TRANSACTION            215765
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                       2055904
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.2764 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2764 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2764 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2764 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.05.22 07:40:27. 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-05-15 07:40:30 No severe PSQL-related problems occured
15.05.22 07:40:31. End of report.