Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 27.09.2022 13:38
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15462968 kB
MemAvailable 30617732 kB
Buffers 0 kB
Cached 16283220 kB
SwapCached 2068 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.3
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3400
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-09-27 13:37:20
DTS_END                         2022-09-27 13:37:20.0120
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/46254
TRN_ID                          217036
ATT_ID                          661
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: 8587.83 28537 1030539
customer order: creation 158.45 512 19014
customer order: refuse 13.63 382 1636
order to supplier: creation 43.05 1523 5166
order to supplier: removal 3.28 163392 393
invoice (draft): creation 43.51 3475 5221
invoice (draft): removal 46.22 1011 5546
invoice accept: apply 39.78 26474 4773
invoice accept: cancel 41.98 1273 5037
customer reserve: creation 3760.03 250 451203
customer reserve: removal 4160.83 101 499300
realization accept: apply 79.39 303 9527
realization accept: cancel 72.37 177 8684
payment from customer: creation 57.41 175 6889
payment from customer: removal 5.37 133 644
payment to supplier: creation 47.16 217 5659
payment to supplier: removal 3.24 130 389
service: total inventory turnovers 3.93 3896 472
service: total monetary turnovers 7.33 384 879
service: aggregate perf. data 0.86 16540 103
service: refresh index statistics 0.03 350392 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 27-09-2022 13:39:17 to 27-09-2022 13:39:20.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5841
TEST_TIME 2 6115
TEST_TIME 3 6408
TEST_TIME 4 6621
TEST_TIME 5 6211
TEST_TIME 6 6427
TEST_TIME 7 6168
TEST_TIME 8 6493
TEST_TIME 9 6829
TEST_TIME 10 6799
TEST_TIME 11 6781
TEST_TIME 12 6794
TEST_TIME 13 6826
TEST_TIME 14 7028
TEST_TIME 15 7193
TEST_TIME 16 7122
TEST_TIME 17 7261
TEST_TIME 18 7456
TEST_TIME 19 7391
TEST_TIME 20 7477
TEST_TIME 21 7934
TEST_TIME 22 8247
TEST_TIME 23 8466
TEST_TIME 24 8779
TEST_TIME 25 8769
TEST_TIME 26 9115
TEST_TIME 27 9481
TEST_TIME 28 9574
TEST_TIME 29 9682
TEST_TIME 30 9702
TEST_TIME 31 10154
TEST_TIME 32 10622
TEST_TIME 33 10959
TEST_TIME 34 10713
TEST_TIME 35 10489
TEST_TIME 36 10665
TEST_TIME 37 10565
TEST_TIME 38 10425
TEST_TIME 39 10391
TEST_TIME 40 10480
TEST_TIME 41 10288
TEST_TIME 42 10076
TEST_TIME 43 9951
TEST_TIME 44 9937
TEST_TIME 45 9838
TEST_TIME 46 9717
TEST_TIME 47 9589
TEST_TIME 48 9526
TEST_TIME 49 9556
TEST_TIME 50 9449
TEST_TIME 51 9395
TEST_TIME 52 9294
TEST_TIME 53 9172
TEST_TIME 54 9076
TEST_TIME 55 9064
TEST_TIME 56 8966
TEST_TIME 57 8916
TEST_TIME 58 8865
TEST_TIME 59 8972
TEST_TIME 60 9029
TEST_TIME 61 8988
TEST_TIME 62 8935
TEST_TIME 63 9037
TEST_TIME 64 9065
TEST_TIME 65 9074
TEST_TIME 66 9031
TEST_TIME 67 9012
TEST_TIME 68 9096
TEST_TIME 69 9176
TEST_TIME 70 9173
TEST_TIME 71 9168
TEST_TIME 72 9073
TEST_TIME 73 8972
TEST_TIME 74 8990
TEST_TIME 75 8942
TEST_TIME 76 8891
TEST_TIME 77 8874
TEST_TIME 78 8817
TEST_TIME 79 8827
TEST_TIME 80 8776
TEST_TIME 81 8741
TEST_TIME 82 8735
TEST_TIME 83 8703
TEST_TIME 84 8750
TEST_TIME 85 8809
TEST_TIME 86 8863
TEST_TIME 87 8903
TEST_TIME 88 8938
TEST_TIME 89 8892
TEST_TIME 90 8955
TEST_TIME 91 8927
TEST_TIME 92 8905
TEST_TIME 93 8837
TEST_TIME 94 8848
TEST_TIME 95 8802
TEST_TIME 96 8781
TEST_TIME 97 8741
TEST_TIME 98 8709
TEST_TIME 99 8727
TEST_TIME 100 8740
TEST_TIME 101 8694
TEST_TIME 102 8747
TEST_TIME 103 8712
TEST_TIME 104 8724
TEST_TIME 105 8706
TEST_TIME 106 8758
TEST_TIME 107 8767
TEST_TIME 108 8813
TEST_TIME 109 8841
TEST_TIME 110 8820
TEST_TIME 111 8807
TEST_TIME 112 8897
TEST_TIME 113 8833
TEST_TIME 114 8783
TEST_TIME 115 8756
TEST_TIME 116 8738
TEST_TIME 117 8714
TEST_TIME 118 8695
TEST_TIME 119 8650
TEST_TIME 120 8605

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 27-09-2022 13:39:21 to 27-09-2022 13:39:24.

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 1443168 1443168 0 0.00 0 21836 13 0 0
doc_list_biud 1443152 1443152 0 0.00 0 35648 41 0 0
sp_add_invoice_to_stock 4930 4773 157 3.18 64 302821 26474 0 157
sp_cancel_adding_invoice 5037 5037 0 0.00 6 89732 1273 0 0
sp_cancel_client_order 1636 1636 0 0.00 6 22816 382 0 0
sp_cancel_customer_reserve 499300 499300 0 0.00 1 35651 101 0 0
sp_cancel_pay_from_customer 644 644 0 0.00 4 4927 134 0 0
sp_cancel_pay_to_supplier 389 389 0 0.00 4 8831 130 0 0
sp_cancel_supplier_invoice 5562 5546 16 0.28 2 26009 1011 0 16
sp_cancel_supplier_order 393 393 0 0.00 15 1258686 163392 0 0
sp_cancel_write_off 8684 8684 0 0.00 2 22695 178 0 0
sp_client_order 19014 19014 0 0.00 5 37489 513 0 0
sp_customer_reserve 451203 451203 0 0.00 0 44537 250 0 0
sp_fill_shopping_cart 29388 29388 0 0.00 1 22866 84 0 0
sp_fill_shopping_cart_clo_res 451175 451175 0 0.00 0 12534 17 0 0
sp_get_clo_for_invoice 4735 4735 0 0.00 0 17999 328 0 0
sp_kill_cost_storno 14754 14754 0 0.00 0 8957 79 0 0
sp_kill_qstorno_ret_qs2qd 1013223 1013223 0 0.00 0 35625 44 0 0
sp_kill_qty_storno 534855 534855 0 0.00 0 35648 101 0 0
sp_lock_selected_doc 19673 19673 0 0.00 0 11382 6 0 0
sp_make_cost_storno 26810 26810 0 0.00 0 16355 78 0 0
sp_multiply_rows_for_pdistr 26807 26807 0 0.00 0 9431 40 0 0
sp_multiply_rows_for_qdistr 19012 19012 0 0.00 0 33000 190 0 0
sp_pay_from_customer 6889 6889 0 0.00 5 18969 175 0 0
sp_pay_to_supplier 5659 5659 0 0.00 6 15447 218 0 0
sp_payment_common 12546 12546 0 0.00 2 10230 45 0 0
sp_qd_handle_on_cancel_clo 1636 1636 0 0.00 1 22783 319 0 0
sp_qd_handle_on_invoice_upd_sts 9772 9772 0 0.00 0 26436 519 0 0
sp_qd_handle_on_reserve_upd_sts 9524 9524 0 0.00 0 22677 89 0 0
sp_reserve_write_off 9527 9527 0 0.00 9 22901 304 0 0
sp_supplier_invoice 5221 5221 0 0.00 7 78305 3476 0 0
sp_supplier_order 5166 5166 0 0.00 7 44080 1523 0 0
srv_aggregate_perf_data 103 103 0 0.00 1974 109128 16540 0 0
srv_fill_mon_cache_memory 71 71 0 0.00 830 119632 36297 0 0
srv_find_qd_qs_mism 982231 982231 0 0.00 0 21833 10 0 0
srv_make_invnt_saldo 472 472 0 0.00 50 71534 3897 0 0
srv_make_money_saldo 879 879 0 0.00 1 9990 385 0 0
srv_recalc_idx_stat 4 4 0 0.00 236918 471637 350392 0 0
t$perf-abend:sp_add_invoice_to_stock 157 157 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 16 16 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4776 4776 0 0.00 1 11954 850 0 0
t$perf-norm:sp_cancel_adding_invoice 391 391 0 0.00 1 6813 388 0 0
t$perf-norm:sp_cancel_client_order 1636 1636 0 0.00 0 4005 29 0 0
t$perf-norm:sp_cancel_customer_reserve 660 660 0 0.00 0 8987 40 0 0
t$perf-norm:sp_cancel_pay_from_customer 644 644 0 0.00 0 329 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 389 389 0 0.00 0 893 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 383 383 0 0.00 0 5984 54 0 0
t$perf-norm:sp_cancel_supplier_order 393 393 0 0.00 0 100500 7264 0 0
t$perf-norm:sp_cancel_write_off 923 923 0 0.00 0 1460 17 0 0
t$perf-norm:sp_client_order 19015 19015 0 0.00 0 11263 35 0 0
t$perf-norm:sp_customer_reserve 901 901 0 0.00 0 1167 13 0 0
t$perf-norm:sp_pay_from_customer 6889 6889 0 0.00 0 6514 20 0 0
t$perf-norm:sp_pay_to_supplier 5659 5659 0 0.00 0 5745 20 0 0
t$perf-norm:sp_reserve_write_off 9527 9527 0 0.00 0 8505 25 0 0
t$perf-norm:sp_supplier_invoice 5221 5221 0 0.00 0 4881 28 0 0
t$perf-norm:sp_supplier_order 5166 5166 0 0.00 0 6648 36 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 346 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 71 71 0 0.00 0 3145 65 0 0
t$perf-norm:srv_make_invnt_saldo 472 472 0 0.00 0 1776 26 0 0
t$perf-norm:srv_make_money_saldo 879 879 0 0.00 0 7428 13 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 4 142 48 0 0
v_add_invoice_to_stock 9774 9460 314 3.21 0 8745 27 0 314
v_all_customers 35454 35454 0 0.00 0 71 3 0 0
v_all_suppliers 29298 29298 0 0.00 0 84 4 0 0
v_all_wares 38013 38013 0 0.00 0 78 4 0 0
v_cancel_adding_invoice 782 782 0 0.00 0 11623 26 0 0
v_cancel_client_order 3272 3272 0 0.00 0 1524 10 0 0
v_cancel_customer_prepayment 1288 1288 0 0.00 0 4859 14 0 0
v_cancel_customer_reserve 1320 1320 0 0.00 0 11473 20 0 0
v_cancel_payment_to_supplier 778 778 0 0.00 0 8756 22 0 0
v_cancel_supplier_invoice 798 766 32 4.01 0 1431 28 0 32
v_cancel_supplier_order 786 786 0 0.00 0 5611 20 0 0
v_cancel_write_off 1846 1846 0 0.00 0 1298 25 0 0
v_max_id_clo_ord 5164 5164 0 0.00 0 22845 9 0 0
v_max_id_clo_res 901 901 0 0.00 0 2780 16 0 0
v_max_id_ord_sup 5215 5215 0 0.00 0 4714 9 0 0
v_max_non_paid_invoice 1388 1388 0 0.00 0 306 8 0 0
v_max_non_paid_realizn 3426 3426 0 0.00 0 2798 8 0 0
v_min_id_clo_ord 5164 5164 0 0.00 0 3191 9 0 0
v_min_id_clo_res 901 901 0 0.00 0 2835 11 0 0
v_min_id_ord_sup 5215 5215 0 0.00 0 5021 13 0 0
v_min_non_paid_invoice 5658 5658 0 0.00 0 7321 17 0 0
v_min_non_paid_realizn 6888 6888 0 0.00 0 9174 20 0 0
v_our_firm 9482 9482 0 0.00 0 52 4 0 0
v_reserve_write_off 19046 19046 0 0.00 0 10822 12 0 0
x_lock_depdocs_on_canc_invoice 10200 10200 0 0.00 0 21657 133 0 0
x_lock_depdocs_on_canc_sup_ord 393 393 0 0.00 0 2811 356 0 0
x_make_qstorno_1000_1200 5166 5166 0 0.00 2 43866 1365 0 0
x_make_qstorno_1200_2000 5221 5221 0 0.00 3 73242 3232 0 0
x_make_qstorno_2100_3300 451203 451203 0 0.00 0 44534 222 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 27-09-2022 13:39:25 to 27-09-2022 13:39:29.

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
11:07:23 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
11:08:24 34630 8192000000 9731348288 10031431680 809430816 8.992 100 90 180 0 838642512 7725552 21486144
11:10:11 30693 8192000000 9771523520 10082025472 840750992 9.307 100 94 188 0 878117232 15289696 22076544
11:11:46 12966 8192000000 9816284784 10136805376 881932112 9.719 100 85 170 0 932240704 31239696 19068896
11:12:59 8402 8192000000 9877191472 10207866880 915987648 10.056 100 91 179 0 988776432 53194800 19593984
11:14:13 2510 8192000000 9943592672 10288431104 955923200 10.449 100 84 167 0 1052842688 78510000 18409488
11:15:18 1786 8192000000 9954500336 10310230016 990690512 10.788 100 93 176 2 1067738640 59631104 17417024
11:16:23 1478 8192000000 10017151424 10383450112 1019658752 11.069 100 88 172 0 1131939904 95268608 17012544
11:17:25 1267 8192000000 9992465056 10365616128 1042739728 11.291 100 92 177 2 1117022560 55953200 18329632
11:18:28 705 8192000000 10045917936 10419785728 1052064576 11.380 100 95 176 1 1165200160 94877344 18258240
11:19:32 1094 8192000000 10039228608 10415235072 1058770928 11.445 100 97 187 2 1164289520 86051264 19467328
11:20:34 1322 8192000000 10039326704 10418049024 1064534144 11.500 100 95 182 1 1159627072 77456928 17636000
11:21:36 1166 8192000000 10044921824 10433904640 1069709264 11.549 100 93 172 1 1165495856 78335680 17450912
11:22:39 1444 8192000000 10060766848 10443386880 1075309792 11.603 100 100 186 2 1175157664 80375088 19472784
11:23:42 1086 8192000000 10056539568 10440077312 1081695872 11.664 100 92 180 0 1176635984 75507696 19432416
11:24:44 1154 8192000000 10034860560 10417930240 1086927504 11.713 100 96 183 1 1156596800 50623424 19045872
11:25:46 1085 8192000000 10057590848 10448347136 1085037376 11.695 100 97 181 4 1180692656 77757568 17897712
11:26:49 1086 8192000000 10026116704 10418626560 1082338992 11.670 100 88 157 0 1152617776 54615248 15663536
11:27:51 1356 8192000000 10031455760 10421112832 1087541024 11.719 100 84 157 4 1154422288 50601648 16279616
11:28:53 2576 8192000000 10049558528 10437545984 1088791760 11.731 100 81 156 2 1165462976 61064672 15606544
11:29:57 2243 8192000000 10044453456 10431062016 1076488544 11.614 100 85 164 0 1165687328 73773184 15425600
11:31:00 1228 8192000000 10032347072 10413019136 1057017248 11.428 99 94 177 1 1155672592 81463328 17192016
11:32:02 1118 8192000000 10009217616 10384015360 1033894768 11.206 100 89 170 3 1131032720 79890768 17247184
11:33:04 1702 8192000000 9986919088 10370363392 1036676736 11.233 100 78 150 2 1109886368 57994608 15215024
11:34:07 1315 8192000000 9987412528 10359869440 1038154032 11.247 100 93 172 2 1111347216 54930592 18262592
11:35:09 1643 8192000000 9962436272 10332987392 1027790928 11.147 99 75 140 1 1086474928 44210816 14473184
11:36:11 10154 8192000000 9994287840 10384183296 1041238640 11.277 100 49 96 1 1117928688 67246288 9443760
11:37:23 1837 8192000000 9967832160 10346192896 1034699248 11.214 100 77 141 1 1085736240 36773744 14263248
11:38:34 1685 8192000000 10017478368 10394996736 1060530896 11.462 100 86 163 1 1140423600 62788832 17103872
11:39:42 1113 8192000000 10023805248 10405801984 1067305888 11.526 100 90 177 3 1151586208 65066128 19214192
11:40:47 6751 8192000000 10063797808 10453442560 1080301664 11.650 100 74 147 0 1180280128 85054256 14924208
11:42:03 5862 8192000000 10095936704 10493788160 1098177296 11.820 100 64 128 0 1215336816 103519680 13639840
11:43:20 7171 8192000000 10126593824 10531692544 1107088800 11.905 100 69 135 0 1239625264 118386352 14150112
11:44:29 4874 8192000000 10148071440 10542292992 1118992736 12.017 100 84 160 0 1265699152 129678544 17027872
11:45:46 2571 8192000000 10167519760 10582761472 1121378432 12.040 100 78 153 1 1280487632 142935360 16173840
11:46:54 6863 8192000000 10184089952 10587201536 1145228976 12.265 100 72 137 0 1299695136 139985264 14480896
11:48:06 1766 8192000000 10225389344 10628837376 1156784208 12.373 100 90 177 0 1339328384 161471200 21072976
11:49:12 1551 8192000000 10287350064 10684809216 1183481008 12.623 100 91 172 1 1397408432 195414224 18513200
11:50:16 1304 8192000000 10340432736 10746089472 1195430224 12.734 100 93 179 0 1454019712 237012384 21577104
11:51:20 2423 8192000000 10352091568 10761695232 1193737168 12.718 100 86 170 0 1459855872 246655680 19463024
11:52:25 5934 8192000000 10386862016 10807914496 1203689664 12.811 100 76 147 0 1500380400 278702784 17987952
11:53:36 823 8192000000 10441746224 10866016256 1219391424 12.956 100 98 189 3 1548527200 307278688 21857088
11:54:38 984 8192000000 10479552944 10903781376 1215541328 12.920 100 96 192 1 1586458432 348747680 22169424
11:55:43 944 8192000000 10530519392 10956783616 1217422288 12.938 100 99 196 0 1637334048 395631360 24280400
11:56:48 898 8192000000 10516757072 10941636608 1204279712 12.816 100 98 192 0 1624445856 396079376 24086768
11:57:53 1570 8192000000 10545070208 10990653440 1205448352 12.827 100 96 189 0 1650433296 421545616 23439328
11:58:58 1312 8192000000 10524082016 10965839872 1204326080 12.816 100 91 177 0 1631326272 405547968 21452224
12:00:08 4123 8192000000 10504573328 10947551232 1177540352 12.567 99 73 144 1 1608763200 413642560 17580288
12:01:28 12512 8192000000 10452688320 10893041664 1159978096 12.403 99 45 86 1 1559820336 388032608 11809632
12:03:05 6991 8192000000 10464034800 10916405248 1182163520 12.610 100 50 101 1 1557546144 362493504 12889120
12:04:16 6292 8192000000 10399896560 10871128064 1185562464 12.642 100 46 88 0 1509753648 312987984 11203200
12:05:35 15777 8192000000 10383866896 10847735808 1138005968 12.197 97 29 56 1 1467125216 321108336 8010912
12:06:52 27855 8192000000 10396607280 10942406656 1170735904 12.504 100 25 50 0 1521503216 344110512 6656800
12:08:22 24916 8192000000 10318620768 10808754176 1138161280 12.198 99 17 30 0 1427334816 285026608 4146928
12:09:57 20684 8192000000 10171503168 10685636608 1132058752 12.141 99 22 44 0 1305191968 166938352 6194864
12:11:31 56700 8192000000 10171980688 10594725888 1117389248 12.002 98 59 115 0 1252036480 121955344 12691888
12:13:28 17161 8192000000 10170308944 10662801408 1131556000 12.136 100 25 50 0 1264712032 127731440 5424592
12:14:48 77169 8192000000 10115135872 10583035904 1118645808 12.014 99 11 22 0 1249399520 128192736 2560976
12:17:17 76583 8192000000 10064526400 10509512704 1116557024 11.994 98 28 53 1 1138307936 16271696 5479216
12:19:35 24776 8192000000 10072852624 10497273856 1124197056 12.067 99 70 122 3 1162789856 25486832 13105968
12:21:00 119614 8192000000 10080094816 10527969280 1068099360 11.534 94 24 48 0 1124813712 52263296 4451056
12:24:01 24127 8192000000 10079050768 10505682944 1117194000 12.000 98 52 91 1 1168409328 42673232 8542096
12:25:27 49750 8192000000 10072327536 10532229120 1116376464 11.993 99 35 68 4 1190030000 65496848 8156688
12:27:28 47870 8192000000 10058627248 10503782400 1107914960 11.913 99 29 57 1 1147398688 33657152 5826576
12:30:03 49954 8192000000 10074967184 10533158912 1115577376 11.985 99 9 18 1 1173821072 56420832 1822864
12:31:59 43651 8192000000 10091525456 10531262464 1119893008 12.026 99 51 99 0 1187946752 57197968 10855776
12:33:49 13573 8192000000 10111883808 10566799360 1141010336 12.225 100 46 91 1 1229519344 78938480 9570528
12:35:29 81945 8192000000 10110427104 10612690944 1145087840 12.263 100 14 26 0 1259668960 111886976 2694144
12:37:51 68912 8192000000 10071620432 10519080960 1105566288 11.890 97 17 31 0 1158014576 49111728 3336560
12:40:01 79513 8192000000 10071990000 10588262400 1126954096 12.093 99 18 36 0 1182213376 51572784 3686496
12:42:26 35465 8192000000 10079885248 10522959872 1131220448 12.133 100 37 74 0 1189457872 50824656 7412768
12:44:13 51414 8192000000 10147623488 10614448128 1143658352 12.250 100 43 87 1 1258486448 105496336 9331760
12:46:05 70533 8192000000 10081628992 10528866304 1126276352 12.086 99 22 44 0 1177580448 47308416 3995680
12:48:22 68840 8192000000 10078803648 10523512832 1117612672 12.004 98 35 70 0 1180133936 55788192 6733072
12:50:41 34773 8192000000 10068749056 10528763904 1097197984 11.811 97 44 86 1 1161569328 55098496 9272848
12:52:22 70681 8192000000 10075833440 10546528256 1109349056 11.926 98 10 20 0 1177461312 65865136 2247120
12:54:33 63824 8192000000 10104645920 10563219456 1131787920 12.138 100 14 28 0 1205870976 70771744 3311312
12:56:40 58685 8192000000 10121106752 10571964416 1126053296 12.084 98 24 48 0 1211526240 80469104 5003840
12:58:49 45489 8192000000 10147207728 10590744576 1138115520 12.198 99 24 48 0 1240221456 96874608 5231328
13:00:39 36417 8192000000 10188404208 10623320064 1134439648 12.163 98 33 64 0 1276883792 135226112 7218032
13:02:22 58621 8192000000 10131154432 10593050624 1112610224 11.957 98 16 32 0 1254596880 138096624 3890032
13:04:26 40115 8192000000 10115574752 10542514176 1118796624 12.016 99 36 69 0 1178251232 52757920 6696688
13:06:09 17697 8192000000 10105635728 10525495296 1127221392 12.095 100 61 121 0 1205078384 64878400 12978592
13:07:30 38593 8192000000 10103834720 10538008576 1105802000 11.893 99 53 104 0 1186401248 70018288 10580960
13:09:09 25078 8192000000 10086369104 10529624064 1110010096 11.933 100 61 119 1 1186095152 64629408 11455648
13:10:41 21271 8192000000 10092285536 10505498624 1121726160 12.043 100 73 130 4 1192421632 57466320 13229152
13:12:04 61007 8192000000 10046048720 10495283200 1102138192 11.858 98 12 24 0 1136637872 31631872 2867808
13:14:05 73939 8192000000 10066615440 10530312192 1096052480 11.800 97 12 24 0 1135354048 36953984 2347584
13:16:19 63358 8192000000 10042544576 10479558656 1039158832 11.257 92 18 36 1 1079716592 36399312 4158448
13:18:23 50231 8192000000 10085301984 10518179840 1098981280 11.828 98 40 75 0 1162846144 56769968 7094896
13:20:14 13765 8192000000 10057304112 10479943680 1132261040 12.143 100 63 114 1 1157043104 11918256 12863808
13:21:30 78873 8192000000 10110855120 10572345344 1073803120 11.588 95 21 42 0 1154451248 75995760 4652368
13:23:50 26819 8192000000 10084998768 10520305664 1100717056 11.844 98 47 91 0 1143568704 32593984 10257664
13:25:21 113156 8192000000 10060831616 10524340224 1096750336 11.807 98 18 33 0 1132459744 31804784 3904624
13:28:15 108019 8192000000 10078242848 10592681984 1107383296 11.908 98 38 76 0 1180255136 65663456 7208384
13:31:03 89606 8192000000 10060007312 10524278784 1091436720 11.756 96 25 51 0 1135441712 38751504 5253488
13:33:53 78774 8192000000 10072303808 10534645760 1118335632 12.011 99 17 32 0 1169101808 46320544 4445632
13:36:12 73034 8192000000 9695327056 10123624448 767579568 8.567 67 15 29 0 828808416 58116128 3112720

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 27-09-2022 13:39:31 to 27-09-2022 13:39:48.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 157 v_add_invoice_to_stock 335544517
except 16 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 27-09-2022 13:39:49 to 27-09-2022 13:39:51.

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          215731
MON$OLDEST_ACTIVE               217226
MON$OLDEST_SNAPSHOT             217226
MON$NEXT_TRANSACTION            217226
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                       2053344
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             769
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2850 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2850 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2850 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2850 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 ---
:32: 
:33: mscs-test-161.budzdorov.ru    Tue Sep 27 13:37:43 2022
:34:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:35: 
--- end of diff output ---

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

27.09.22 13:39:52. 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-09-27 13:39:54 No severe PSQL-related problems occured
27.09.22 13:39:56. End of report.