Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 01.01.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 16562220 kB
MemAvailable 31382636 kB
Buffers 0 kB
Cached 15157896 kB
SwapCached 1652 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.1
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-01-01 13:37:11
DTS_END                         2022-01-01 13:37:11.1520
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_RESERVE_WRITE_OFF(34:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/60702
TRN_ID                          230490
ATT_ID                          639
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: 8573.36 24270 1028803
customer order: creation 164.50 393 19740
customer order: refuse 14.28 337 1713
order to supplier: creation 43.64 1377 5237
order to supplier: removal 3.26 117796 391
invoice (draft): creation 44.96 3732 5395
invoice (draft): removal 47.73 1084 5728
invoice accept: apply 39.92 19360 4791
invoice accept: cancel 42.85 833 5142
customer reserve: creation 3694.04 191 443285
customer reserve: removal 4199.45 71 503934
realization accept: apply 80.93 186 9711
realization accept: cancel 73.13 133 8775
payment from customer: creation 57.85 112 6942
payment from customer: removal 5.71 110 685
payment to supplier: creation 47.10 128 5652
payment to supplier: removal 3.29 66 395
service: total inventory turnovers 3.81 3199 457
service: total monetary turnovers 6.09 542 731
service: aggregate perf. data 0.79 21139 95
service: refresh index statistics 0.03 314620 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 01-01-2022 13:39:02 to 01-01-2022 13:39:04.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2947
TEST_TIME 2 3909
TEST_TIME 3 3952
TEST_TIME 4 4635
TEST_TIME 5 4784
TEST_TIME 6 5011
TEST_TIME 7 4854
TEST_TIME 8 4708
TEST_TIME 9 5002
TEST_TIME 10 5385
TEST_TIME 11 5906
TEST_TIME 12 6121
TEST_TIME 13 6576
TEST_TIME 14 6680
TEST_TIME 15 6738
TEST_TIME 16 7300
TEST_TIME 17 7844
TEST_TIME 18 7964
TEST_TIME 19 7913
TEST_TIME 20 8746
TEST_TIME 21 8814
TEST_TIME 22 8832
TEST_TIME 23 8996
TEST_TIME 24 9192
TEST_TIME 25 9446
TEST_TIME 26 10854
TEST_TIME 27 11440
TEST_TIME 28 11228
TEST_TIME 29 10884
TEST_TIME 30 10575
TEST_TIME 31 10491
TEST_TIME 32 10291
TEST_TIME 33 10310
TEST_TIME 34 10132
TEST_TIME 35 10050
TEST_TIME 36 9953
TEST_TIME 37 9795
TEST_TIME 38 9773
TEST_TIME 39 9559
TEST_TIME 40 9660
TEST_TIME 41 9530
TEST_TIME 42 9376
TEST_TIME 43 9277
TEST_TIME 44 9156
TEST_TIME 45 9107
TEST_TIME 46 9190
TEST_TIME 47 9158
TEST_TIME 48 9170
TEST_TIME 49 9178
TEST_TIME 50 9131
TEST_TIME 51 9188
TEST_TIME 52 9128
TEST_TIME 53 9180
TEST_TIME 54 9186
TEST_TIME 55 9254
TEST_TIME 56 9210
TEST_TIME 57 9193
TEST_TIME 58 9258
TEST_TIME 59 9241
TEST_TIME 60 9103
TEST_TIME 61 9006
TEST_TIME 62 9072
TEST_TIME 63 8996
TEST_TIME 64 8935
TEST_TIME 65 8874
TEST_TIME 66 8900
TEST_TIME 67 8975
TEST_TIME 68 8922
TEST_TIME 69 8882
TEST_TIME 70 8998
TEST_TIME 71 9082
TEST_TIME 72 9106
TEST_TIME 73 9041
TEST_TIME 74 9090
TEST_TIME 75 9066
TEST_TIME 76 9094
TEST_TIME 77 9063
TEST_TIME 78 9037
TEST_TIME 79 8995
TEST_TIME 80 8948
TEST_TIME 81 8913
TEST_TIME 82 8874
TEST_TIME 83 8825
TEST_TIME 84 8802
TEST_TIME 85 8844
TEST_TIME 86 8799
TEST_TIME 87 8782
TEST_TIME 88 8802
TEST_TIME 89 8770
TEST_TIME 90 8764
TEST_TIME 91 8938
TEST_TIME 92 8937
TEST_TIME 93 9024
TEST_TIME 94 8993
TEST_TIME 95 9065
TEST_TIME 96 8977
TEST_TIME 97 8904
TEST_TIME 98 8873
TEST_TIME 99 8831
TEST_TIME 100 8825
TEST_TIME 101 8791
TEST_TIME 102 8774
TEST_TIME 103 8754
TEST_TIME 104 8767
TEST_TIME 105 8733
TEST_TIME 106 8754
TEST_TIME 107 8780
TEST_TIME 108 8741
TEST_TIME 109 8775
TEST_TIME 110 8762
TEST_TIME 111 8768
TEST_TIME 112 8834
TEST_TIME 113 8801
TEST_TIME 114 8820
TEST_TIME 115 8806
TEST_TIME 116 8753
TEST_TIME 117 8725
TEST_TIME 118 8680
TEST_TIME 119 8643
TEST_TIME 120 8576

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 01-01-2022 13:39:06 to 01-01-2022 13:39:09.

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 1438335 1438335 0 0.00 0 17485 7 0 0
doc_list_biud 1438335 1438335 0 0.00 0 64471 32 0 0
sp_add_invoice_to_stock 4898 4791 107 2.18 45 228673 19360 0 107
sp_cancel_adding_invoice 5142 5142 0 0.00 6 74574 834 0 0
sp_cancel_client_order 1713 1713 0 0.00 4 11816 337 0 0
sp_cancel_customer_reserve 503934 503934 0 0.00 1 64472 72 0 0
sp_cancel_pay_from_customer 685 685 0 0.00 3 9221 110 0 0
sp_cancel_pay_to_supplier 395 395 0 0.00 3 2017 67 0 0
sp_cancel_supplier_invoice 5733 5728 5 0.08 2 18134 1085 0 5
sp_cancel_supplier_order 391 391 0 0.00 5 1296024 117797 0 0
sp_cancel_write_off 8775 8775 0 0.00 1 13297 134 0 0
sp_client_order 19740 19740 0 0.00 4 20153 394 0 0
sp_customer_reserve 443285 443285 0 0.00 0 211979 192 0 0
sp_fill_shopping_cart 30356 30356 0 0.00 1 18773 52 0 0
sp_fill_shopping_cart_clo_res 443280 443280 0 0.00 0 16008 17 0 0
sp_get_clo_for_invoice 4786 4786 0 0.00 0 21578 428 0 0
sp_kill_cost_storno 14997 14997 0 0.00 0 13260 63 0 0
sp_kill_qstorno_ret_qs2qd 1022762 1022762 0 0.00 0 64469 40 0 0
sp_kill_qty_storno 540179 540179 0 0.00 0 64471 84 0 0
sp_lock_selected_doc 20148 20148 0 0.00 0 9939 5 0 0
sp_make_cost_storno 27090 27090 0 0.00 0 16703 56 0 0
sp_multiply_rows_for_pdistr 27090 27090 0 0.00 0 8387 33 0 0
sp_multiply_rows_for_qdistr 19740 19740 0 0.00 0 19534 220 0 0
sp_pay_from_customer 6942 6942 0 0.00 4 12885 112 0 0
sp_pay_to_supplier 5652 5652 0 0.00 5 13720 129 0 0
sp_payment_common 12594 12594 0 0.00 1 9583 22 0 0
sp_qd_handle_on_cancel_clo 1713 1713 0 0.00 0 11804 313 0 0
sp_qd_handle_on_invoice_upd_sts 9927 9927 0 0.00 0 26565 338 0 0
sp_qd_handle_on_reserve_upd_sts 9711 9711 0 0.00 0 14505 53 0 0
sp_reserve_write_off 9711 9711 0 0.00 6 17524 186 0 0
sp_supplier_invoice 5395 5395 0 0.00 7 105516 3733 0 0
sp_supplier_order 5237 5237 0 0.00 6 73137 1378 0 0
srv_aggregate_perf_data 95 95 0 0.00 2063 334620 21139 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1003 222344 45333 0 0
srv_find_qd_qs_mism 982425 982425 0 0.00 0 13553 6 0 0
srv_make_invnt_saldo 457 457 0 0.00 62 96735 3199 0 0
srv_make_money_saldo 731 731 0 0.00 1 24995 543 0 0
srv_recalc_idx_stat 4 4 0 0.00 268152 381208 314621 0 0
t$perf-abend:sp_add_invoice_to_stock 107 107 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4791 4791 0 0.00 0 11965 154 0 0
t$perf-norm:sp_cancel_adding_invoice 400 400 0 0.00 1 2677 72 0 0
t$perf-norm:sp_cancel_client_order 1713 1713 0 0.00 0 5253 13 0 0
t$perf-norm:sp_cancel_customer_reserve 689 689 0 0.00 0 1153 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 685 685 0 0.00 0 5077 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 395 395 0 0.00 0 280 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 394 394 0 0.00 0 3261 21 0 0
t$perf-norm:sp_cancel_supplier_order 391 391 0 0.00 0 22100 1379 0 0
t$perf-norm:sp_cancel_write_off 985 985 0 0.00 0 1330 4 0 0
t$perf-norm:sp_client_order 19740 19740 0 0.00 0 11087 17 0 0
t$perf-norm:sp_customer_reserve 1015 1015 0 0.00 0 12354 17 0 0
t$perf-norm:sp_pay_from_customer 6942 6942 0 0.00 0 6479 10 0 0
t$perf-norm:sp_pay_to_supplier 5652 5652 0 0.00 0 10226 11 0 0
t$perf-norm:sp_reserve_write_off 9711 9711 0 0.00 0 11147 12 0 0
t$perf-norm:sp_supplier_invoice 5395 5395 0 0.00 0 5696 15 0 0
t$perf-norm:sp_supplier_order 5237 5237 0 0.00 0 3383 14 0 0
t$perf-norm:srv_aggregate_perf_data 95 95 0 0.00 0 220 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 909 15 0 0
t$perf-norm:srv_make_invnt_saldo 457 457 0 0.00 0 1965 13 0 0
t$perf-norm:srv_make_money_saldo 731 731 0 0.00 0 812 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 3 0 0
v_add_invoice_to_stock 9784 9570 214 2.18 0 5050 14 0 214
v_all_customers 35850 35850 0 0.00 0 19 1 0 0
v_all_suppliers 29494 29494 0 0.00 0 88 1 0 0
v_all_wares 39480 39480 0 0.00 0 32 1 0 0
v_cancel_adding_invoice 800 800 0 0.00 0 1978 13 0 0
v_cancel_client_order 3426 3426 0 0.00 0 5622 7 0 0
v_cancel_customer_prepayment 1370 1370 0 0.00 0 1986 7 0 0
v_cancel_customer_reserve 1378 1378 0 0.00 0 1742 4 0 0
v_cancel_payment_to_supplier 790 790 0 0.00 0 1987 8 0 0
v_cancel_supplier_invoice 798 788 10 1.25 0 807 13 0 10
v_cancel_supplier_order 782 782 0 0.00 0 1515 5 0 0
v_cancel_write_off 1970 1970 0 0.00 0 1861 14 0 0
v_max_id_clo_ord 5237 5237 0 0.00 0 1285 2 0 0
v_max_id_clo_res 1015 1015 0 0.00 0 6374 27 0 0
v_max_id_ord_sup 5379 5379 0 0.00 0 7074 9 0 0
v_max_non_paid_invoice 1521 1521 0 0.00 0 1747 3 0 0
v_max_non_paid_realizn 3697 3697 0 0.00 0 2940 4 0 0
v_min_id_clo_ord 5237 5237 0 0.00 0 4674 6 0 0
v_min_id_clo_res 1015 1015 0 0.00 0 8709 17 0 0
v_min_id_ord_sup 5379 5379 0 0.00 0 11337 15 0 0
v_min_non_paid_invoice 5652 5652 0 0.00 0 9292 12 0 0
v_min_non_paid_realizn 6942 6942 0 0.00 0 9550 15 0 0
v_our_firm 10120 10120 0 0.00 0 20 1 0 0
v_reserve_write_off 19422 19422 0 0.00 0 6132 7 0 0
x_lock_depdocs_on_canc_invoice 10476 10476 0 0.00 0 12493 122 0 0
x_lock_depdocs_on_canc_sup_ord 391 391 0 0.00 0 11411 366 0 0
x_make_qstorno_1000_1200 5237 5237 0 0.00 2 73117 1305 0 0
x_make_qstorno_1200_2000 5395 5395 0 0.00 2 105121 3511 0 0
x_make_qstorno_2100_3300 443285 443285 0 0.00 0 211978 172 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 01-01-2022 13:39:10 to 01-01-2022 13:39:14.

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:14 1 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:15 24222 8192000000 9715413120 10012954624 795242160 8.848 100 88 176 0 824227504 7187008 21798336
11:09:53 18158 8192000000 9759921536 10068647936 838896704 9.289 100 84 167 1 870985552 12078720 20010128
11:11:21 15777 8192000000 9813525056 10132873216 878204736 9.682 100 81 162 0 917508176 20712032 18591408
11:12:42 19098 8192000000 9871006400 10208116736 926697568 10.162 100 78 156 0 974764352 31195408 16871376
11:14:06 6749 8192000000 9944627792 10290077696 965715104 10.545 100 79 157 1 1050002608 68617168 15670336
11:15:18 6064 8192000000 9963349200 10317897728 1001366672 10.892 100 89 176 1 1076190384 56715088 18108624
11:16:28 2995 8192000000 10007268784 10367606784 1030578112 11.174 100 87 171 1 1121296336 73970960 16747264
11:17:33 2157 8192000000 10017924304 10389483520 1051317520 11.373 100 87 173 1 1124663664 55965168 17380976
11:18:36 1353 8192000000 10056463776 10434142208 1069032960 11.543 100 84 169 2 1164540208 78859600 16647648
11:19:38 4142 8192000000 10028872336 10424922112 1074135424 11.592 100 45 92 0 1145642080 62306192 9200464
11:20:42 3733 8192000000 9998422032 10390208512 1084503232 11.690 100 63 126 1 1113737552 15392512 13841808
11:21:50 9485 8192000000 9992261392 10399326208 1088917072 11.732 100 24 48 1 1127129264 33491776 4720416
11:23:01 27961 8192000000 9997324272 10400608256 1093444704 11.775 100 46 92 0 1110224896 6069776 10710416
11:24:30 4638 8192000000 10033479680 10433908736 1096595344 11.805 100 51 101 2 1143508800 36071776 10841680
11:25:35 6355 8192000000 10039746464 10447151104 1101699056 11.854 100 27 53 1 1145073488 37757824 5616608
11:26:42 6761 8192000000 10019727856 10418806784 1100335248 11.841 100 33 66 0 1125213136 18131312 6746576
11:27:52 36837 8192000000 9974469056 10373795840 1023230000 11.103 94 30 60 1 1046301856 16223376 6848480
11:29:29 6812 8192000000 9991142752 10386337792 1045552880 11.318 99 31 61 0 1096715104 45114688 6047536
11:30:38 5565 8192000000 9941157680 10317750272 1005477056 10.932 97 36 71 0 1036303744 22956160 7870528
11:31:46 8192 8192000000 9949301792 10329956352 1018206320 11.055 99 31 63 1 1049741328 25118080 6416928
11:32:57 25060 8192000000 9958621408 10336083968 1027354768 11.143 98 59 117 2 1051116560 11319824 12441968
11:34:22 5590 8192000000 9963677392 10347417600 1047141680 11.333 100 50 100 0 1072664832 13688752 11834400
11:35:30 4411 8192000000 9971814560 10363310080 1060012736 11.457 100 39 77 1 1090188592 21571216 8604640
11:36:38 7835 8192000000 9991648048 10383826944 1067008704 11.524 100 43 86 2 1101815840 25549472 9257664
11:37:49 53095 8192000000 10017258576 10415099904 1088558528 11.729 100 43 84 1 1116338848 19317312 8463008
11:39:58 30232 8192000000 10074076928 10472480768 1112021744 11.952 100 46 90 0 1175615600 54949120 8644736
11:41:34 27682 8192000000 10127194000 10530115584 1127863984 12.101 100 52 104 2 1215149872 76323328 10962560
11:43:03 4996 8192000000 10165102624 10571657216 1147471248 12.286 100 53 103 2 1266947536 108123440 11352848
11:44:16 19100 8192000000 10180344128 10598334464 1153798944 12.345 100 41 82 1 1289392352 126698960 8894448
11:45:39 15898 8192000000 10237812432 10657210368 1170855920 12.505 100 45 90 0 1344559584 163403344 10300320
11:47:04 3273 8192000000 10288742496 10691739648 1176426000 12.557 99 73 145 1 1391452048 198992128 16033920
11:48:10 3660 8192000000 10322792432 10732023808 1174989376 12.543 99 73 144 0 1415858144 224008272 16860496
11:49:20 6579 8192000000 10359443328 10765938688 1190152416 12.685 100 68 136 1 1456445696 250890848 15402432
11:50:32 1666 8192000000 10356789488 10789289984 1197741616 12.755 100 75 148 1 1457658192 242216336 17700240
11:51:38 2284 8192000000 10430437872 10841608192 1196523712 12.744 100 74 149 1 1529579360 315968736 17086912
11:52:48 1809 8192000000 10451528816 10848698368 1152791744 12.336 99 87 170 1 1548482736 375092224 20598768
11:53:53 999 8192000000 10433956608 10834374656 1170631584 12.503 100 95 187 0 1527255472 334418864 22205024
11:54:58 16881 8192000000 10399808096 10821509120 1173441648 12.529 99 45 90 1 1507370128 322385392 11543088
11:56:19 14650 8192000000 10433069488 10860101632 1170744592 12.504 100 41 81 0 1530771184 349377008 10649584
11:57:42 9839 8192000000 10409063360 10835292160 1161194848 12.414 99 46 92 0 1498815648 326384352 11236448
11:59:10 8529 8192000000 10426424640 10843136000 1143477424 12.248 98 62 123 1 1500573936 341538912 15557600
12:00:32 17524 8192000000 10435997312 10885378048 1135354384 12.172 100 30 59 1 1541711888 398039744 8317760
12:01:53 23143 8192000000 10377058608 10815635456 1095278960 11.793 99 29 59 1 1479712128 376132752 8300416
12:03:16 39602 8192000000 10125893760 10656780288 1067849584 11.532 96 17 34 1 1327778192 255181616 4746992
12:04:57 90569 8192000000 10043652464 10469367808 1112075296 11.952 99 37 73 2 1137760096 18091920 7592880
12:07:32 14838 8192000000 10056224720 10476744704 1113629856 11.967 99 36 72 0 1144216304 23328736 7257712
12:08:47 47648 8192000000 10079901152 10502942720 1107273648 11.907 98 33 66 1 1163902416 49640992 6987776
12:10:35 23476 8192000000 10042125520 10495737856 1120760272 12.034 99 13 26 0 1146278816 23231632 2286912
12:12:57 25893 8192000000 10074310080 10532110336 1123488816 12.060 100 10 20 0 1183269488 57825728 1954944
12:14:23 222344 8192000000 10073346224 10571980800 1133650128 12.156 99 23 46 1 1210268176 71637200 4980848
12:19:07 35487 8192000000 10061951328 10499289088 1138364528 12.200 100 39 78 1 1170576688 23309856 8902304
12:20:43 65382 8192000000 10108331712 10561196032 1132096224 12.141 99 18 36 0 1200927200 65338976 3492000
12:22:52 67039 8192000000 10097643024 10607509504 1144264128 12.256 100 15 30 0 1231393664 83549952 3579584
12:25:05 44906 8192000000 10079412976 10549002240 1137895376 12.196 99 13 26 1 1193593296 52921888 2776032
12:27:01 28624 8192000000 10120836592 10559209472 1151410464 12.323 100 38 76 0 1211583888 52259712 7913712
12:28:30 46949 8192000000 10125220928 10548228096 1132285024 12.143 98 37 73 0 1197065568 56686048 8094496
12:30:21 60614 8192000000 10116779168 10550509568 1116671904 11.996 97 19 38 3 1160432208 40047024 3713280
12:32:32 54546 8192000000 10074347936 10525843456 1153771824 12.345 100 28 54 1 1225259664 65421552 6066288
12:34:27 24289 8192000000 10121917968 10555265024 1141753232 12.232 99 36 72 1 1212973376 64279024 6941120
12:35:54 69970 8192000000 10056049232 10522419200 1109565664 11.928 97 16 32 0 1159256400 46098256 3592480
12:38:27 40810 8192000000 10077826624 10526756864 1124527904 12.070 99 34 68 1 1183542192 51653744 7360544
12:40:09 46067 8192000000 10045034768 10501287936 1126094976 12.085 99 4 8 0 1150667584 23949728 622880
12:41:55 35276 8192000000 10080357552 10528030720 1109529200 11.928 97 11 22 1 1159423184 47535424 2358560
12:43:32 73552 8192000000 10085129696 10559676416 1110963088 11.942 97 21 42 0 1168792112 53181376 4647648
12:45:46 55307 8192000000 10135251504 10571345920 1084761600 11.693 95 38 76 1 1164678960 71800304 8117056
12:47:47 118191 8192000000 10102762608 10564407296 1107300224 11.907 98 18 36 2 1182067136 70670784 4096128
12:50:46 67538 8192000000 10073701136 10529005568 1087642624 11.720 96 27 54 0 1143805056 50745632 5416800
12:52:54 17841 8192000000 10105308208 10521870336 1103971952 11.875 99 40 80 2 1185054432 73397104 7685376
12:54:13 6854 8192000000 10081750288 10490269696 1112602416 11.957 100 55 111 1 1165150784 41780960 10767408
12:55:22 98862 8192000000 10054018224 10536161280 1098638000 11.825 99 46 92 2 1165597872 56121312 10838560
12:58:02 32399 8192000000 10019779952 10470191104 1086487152 11.709 99 4 8 0 1124763360 37524080 752128
12:59:36 54597 8192000000 10025294080 10470436864 1053951280 11.399 96 4 8 0 1093871056 38891936 1027840
13:01:32 35963 8192000000 10070767440 10502275072 1094597824 11.786 99 24 48 1 1156841440 57319072 4924544
13:03:12 59420 8192000000 10100312240 10522501120 1098713712 11.825 98 27 54 0 1158093888 53590032 5790144
13:05:18 105118 8192000000 10170378256 10604265472 1126325600 12.087 100 19 38 0 1265100320 134289744 4484976
13:08:04 49182 8192000000 10100197808 10572656640 1122882816 12.054 99 16 32 1 1169240816 42790496 3567504
13:09:55 67168 8192000000 10102082848 10590097408 1131161824 12.132 99 19 38 0 1214356288 79536720 3657744
13:12:03 24652 8192000000 10115395088 10540269568 1137885200 12.196 100 31 62 1 1177639296 33795552 5958544
13:13:43 94471 8192000000 10096256720 10532388864 1141976976 12.234 100 22 44 2 1195193584 48401664 4814944
13:16:18 48208 8192000000 10104145728 10592378880 1136696768 12.184 99 6 12 0 1194450720 56493776 1260176
13:18:06 52301 8192000000 10056067312 10559516672 1125452672 12.078 99 7 14 0 1158891056 32139968 1298416
13:20:06 54798 8192000000 10080000688 10524807168 1141706000 12.232 100 13 26 1 1188125248 42956688 3462560
13:22:05 61788 8192000000 10101015328 10537816064 1134495040 12.164 99 21 42 0 1193734800 54950688 4289072
13:24:28 85097 8192000000 10092042320 10524807168 1122772336 12.053 98 10 20 1 1159797072 35031312 1993424
13:26:54 73717 8192000000 10083987280 10518138880 1084026208 11.686 95 16 32 0 1111890976 24734608 3130160
13:29:09 81084 8192000000 10071493136 10548473856 1108862704 11.922 98 4 8 0 1206432192 96657424 912064
13:31:35 77159 8192000000 10072532272 10559492096 1108819056 11.921 98 30 57 5 1175126320 60564544 5742720
13:34:36 74537 8192000000 10047602256 10498478080 1120457360 12.031 99 9 19 0 1148581776 26338048 1786368
13:36:55 30522 8192000000 8969093808 9182384128 138131072 1.658 13 3 6 0 143600528 5193008 276448

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 01-01-2022 13:39:16 to 01-01-2022 13:39:31.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 107 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 01-01-2022 13:39:32 to 01-01-2022 13:39:34.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          230489
MON$OLDEST_ACTIVE               230772
MON$OLDEST_SNAPSHOT             230772
MON$NEXT_TRANSACTION            230772
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                       2037728
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             786
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

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

01.01.22 13:39:35. There are 3 logs in /var/tmp/logs-oltp40 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2022-01-01 13:39:38 No severe PSQL-related problems occured
01.01.22 13:39:39. End of report.