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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16555432 kB
MemAvailable 31326264 kB
Buffers 0 kB
Cached 15121972 kB
SwapCached 2436 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-29 13:37:15
DTS_END                         2022-05-29 13:37:15.2030
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(69:14) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/45112
TRN_ID                          220728
ATT_ID                          656
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: 8601.94 25839 1032233
customer order: creation 170.19 469 20423
customer order: refuse 14.26 375 1711
order to supplier: creation 45.90 1398 5508
order to supplier: removal 3.29 147067 395
invoice (draft): creation 45.42 3268 5450
invoice (draft): removal 46.96 1057 5635
invoice accept: apply 40.16 27351 4819
invoice accept: cancel 42.43 1088 5092
customer reserve: creation 3758.93 261 451071
customer reserve: removal 4142.12 90 497054
realization accept: apply 83.21 263 9985
realization accept: cancel 79.81 144 9577
payment from customer: creation 58.18 174 6982
payment from customer: removal 6.03 128 723
payment to supplier: creation 48.63 201 5835
payment to supplier: removal 3.44 94 413
service: total inventory turnovers 4.78 2922 574
service: total monetary turnovers 7.37 293 884
service: aggregate perf. data 0.81 16586 97
service: refresh index statistics 0.04 313541 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 29-05-2022 13:38:03 to 29-05-2022 13:38:05.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6649
TEST_TIME 2 6583
TEST_TIME 3 6567
TEST_TIME 4 6429
TEST_TIME 5 6652
TEST_TIME 6 6726
TEST_TIME 7 6487
TEST_TIME 8 6685
TEST_TIME 9 6682
TEST_TIME 10 6814
TEST_TIME 11 6899
TEST_TIME 12 6964
TEST_TIME 13 7153
TEST_TIME 14 7201
TEST_TIME 15 7402
TEST_TIME 16 7292
TEST_TIME 17 7533
TEST_TIME 18 7482
TEST_TIME 19 7894
TEST_TIME 20 7945
TEST_TIME 21 8422
TEST_TIME 22 8643
TEST_TIME 23 8959
TEST_TIME 24 8970
TEST_TIME 25 9275
TEST_TIME 26 9897
TEST_TIME 27 10765
TEST_TIME 28 11107
TEST_TIME 29 10969
TEST_TIME 30 10931
TEST_TIME 31 10854
TEST_TIME 32 10686
TEST_TIME 33 10603
TEST_TIME 34 10423
TEST_TIME 35 10152
TEST_TIME 36 10135
TEST_TIME 37 10150
TEST_TIME 38 10035
TEST_TIME 39 9944
TEST_TIME 40 9800
TEST_TIME 41 9635
TEST_TIME 42 9726
TEST_TIME 43 9596
TEST_TIME 44 9499
TEST_TIME 45 9537
TEST_TIME 46 9655
TEST_TIME 47 9582
TEST_TIME 48 9507
TEST_TIME 49 9561
TEST_TIME 50 9516
TEST_TIME 51 9625
TEST_TIME 52 9519
TEST_TIME 53 9463
TEST_TIME 54 9520
TEST_TIME 55 9530
TEST_TIME 56 9476
TEST_TIME 57 9419
TEST_TIME 58 9297
TEST_TIME 59 9357
TEST_TIME 60 9263
TEST_TIME 61 9189
TEST_TIME 62 9233
TEST_TIME 63 9147
TEST_TIME 64 9075
TEST_TIME 65 9128
TEST_TIME 66 9015
TEST_TIME 67 9087
TEST_TIME 68 9046
TEST_TIME 69 9018
TEST_TIME 70 9015
TEST_TIME 71 8969
TEST_TIME 72 8937
TEST_TIME 73 9006
TEST_TIME 74 8993
TEST_TIME 75 8914
TEST_TIME 76 9086
TEST_TIME 77 9168
TEST_TIME 78 9104
TEST_TIME 79 9137
TEST_TIME 80 9189
TEST_TIME 81 9210
TEST_TIME 82 9122
TEST_TIME 83 9059
TEST_TIME 84 9028
TEST_TIME 85 9053
TEST_TIME 86 9012
TEST_TIME 87 8957
TEST_TIME 88 8939
TEST_TIME 89 8881
TEST_TIME 90 8838
TEST_TIME 91 8773
TEST_TIME 92 8789
TEST_TIME 93 8777
TEST_TIME 94 8753
TEST_TIME 95 8741
TEST_TIME 96 8787
TEST_TIME 97 8785
TEST_TIME 98 8808
TEST_TIME 99 8819
TEST_TIME 100 8874
TEST_TIME 101 8862
TEST_TIME 102 8816
TEST_TIME 103 8906
TEST_TIME 104 8874
TEST_TIME 105 8846
TEST_TIME 106 8773
TEST_TIME 107 8794
TEST_TIME 108 8778
TEST_TIME 109 8725
TEST_TIME 110 8704
TEST_TIME 111 8669
TEST_TIME 112 8698
TEST_TIME 113 8700
TEST_TIME 114 8726
TEST_TIME 115 8725
TEST_TIME 116 8695
TEST_TIME 117 8695
TEST_TIME 118 8720
TEST_TIME 119 8694
TEST_TIME 120 8630

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 29-05-2022 13:38:07 to 29-05-2022 13:38:10.

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 1446272 1446272 0 0.00 0 19860 13 0 0
doc_list_biud 1446261 1446261 0 0.00 0 96737 37 0 0
sp_add_invoice_to_stock 4929 4819 110 2.23 37 307549 27352 0 110
sp_cancel_adding_invoice 5092 5092 0 0.00 6 72792 1088 0 0
sp_cancel_client_order 1711 1711 0 0.00 6 22632 376 0 0
sp_cancel_customer_reserve 497054 497054 0 0.00 1 28703 90 0 0
sp_cancel_pay_from_customer 723 723 0 0.00 4 6336 128 0 0
sp_cancel_pay_to_supplier 413 413 0 0.00 4 1354 95 0 0
sp_cancel_supplier_invoice 5642 5635 7 0.12 3 96745 1058 0 7
sp_cancel_supplier_order 395 395 0 0.00 12 1192634 147067 0 0
sp_cancel_write_off 9577 9577 0 0.00 2 11569 144 0 0
sp_client_order 20423 20423 0 0.00 6 30724 469 0 0
sp_customer_reserve 451071 451071 0 0.00 1 61422 262 0 0
sp_fill_shopping_cart 31371 31371 0 0.00 2 22537 80 0 0
sp_fill_shopping_cart_clo_res 451038 451038 0 0.00 0 20550 22 0 0
sp_get_clo_for_invoice 4773 4773 0 0.00 0 23863 320 0 0
sp_kill_cost_storno 15805 15805 0 0.00 0 13925 64 0 0
sp_kill_qstorno_ret_qs2qd 1009715 1009715 0 0.00 0 96735 40 0 0
sp_kill_qty_storno 534220 534220 0 0.00 0 96736 92 0 0
sp_lock_selected_doc 20406 20406 0 0.00 0 5373 5 0 0
sp_make_cost_storno 27575 27575 0 0.00 0 16331 82 0 0
sp_multiply_rows_for_pdistr 27574 27574 0 0.00 0 6925 35 0 0
sp_multiply_rows_for_qdistr 20423 20423 0 0.00 0 30109 185 0 0
sp_pay_from_customer 6982 6982 0 0.00 6 17628 174 0 0
sp_pay_to_supplier 5835 5835 0 0.00 6 17913 201 0 0
sp_payment_common 12817 12817 0 0.00 2 9080 49 0 0
sp_qd_handle_on_cancel_clo 1711 1711 0 0.00 1 22603 314 0 0
sp_qd_handle_on_invoice_upd_sts 9865 9865 0 0.00 0 28390 449 0 0
sp_qd_handle_on_reserve_upd_sts 9983 9983 0 0.00 0 10167 56 0 0
sp_reserve_write_off 9985 9985 0 0.00 8 19931 263 0 0
sp_supplier_invoice 5450 5450 0 0.00 9 111771 3269 0 0
sp_supplier_order 5508 5508 0 0.00 8 90158 1398 0 0
srv_aggregate_perf_data 97 97 0 0.00 2733 180746 16587 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 967 147103 39961 0 0
srv_find_qd_qs_mism 983279 983279 0 0.00 0 17136 11 0 0
srv_make_invnt_saldo 574 574 0 0.00 38 68472 2923 0 0
srv_make_money_saldo 884 884 0 0.00 1 14727 294 0 0
srv_recalc_idx_stat 5 5 0 0.00 48364 601703 313541 0 0
t$perf-abend:sp_add_invoice_to_stock 110 110 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4827 4827 0 0.00 1 16902 856 0 0
t$perf-norm:sp_cancel_adding_invoice 392 392 0 0.00 1 6912 384 0 0
t$perf-norm:sp_cancel_client_order 1711 1711 0 0.00 0 5552 27 0 0
t$perf-norm:sp_cancel_customer_reserve 635 635 0 0.00 0 498 21 0 0
t$perf-norm:sp_cancel_pay_from_customer 723 723 0 0.00 0 4046 19 0 0
t$perf-norm:sp_cancel_pay_to_supplier 413 413 0 0.00 0 507 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 375 375 0 0.00 0 494 20 0 0
t$perf-norm:sp_cancel_supplier_order 395 395 0 0.00 0 90244 5612 0 0
t$perf-norm:sp_cancel_write_off 1011 1011 0 0.00 0 2350 17 0 0
t$perf-norm:sp_client_order 20424 20424 0 0.00 0 12928 33 0 0
t$perf-norm:sp_customer_reserve 1046 1046 0 0.00 0 8734 21 0 0
t$perf-norm:sp_pay_from_customer 6982 6982 0 0.00 0 12663 20 0 0
t$perf-norm:sp_pay_to_supplier 5835 5835 0 0.00 0 9886 17 0 0
t$perf-norm:sp_reserve_write_off 9986 9986 0 0.00 0 18280 24 0 0
t$perf-norm:sp_supplier_invoice 5450 5450 0 0.00 0 11271 32 0 0
t$perf-norm:sp_supplier_order 5509 5509 0 0.00 0 11049 30 0 0
t$perf-norm:srv_aggregate_perf_data 97 97 0 0.00 0 338 21 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 187 9 0 0
t$perf-norm:srv_make_invnt_saldo 574 574 0 0.00 0 1816 12 0 0
t$perf-norm:srv_make_money_saldo 884 884 0 0.00 0 1236 7 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 2 184 52 0 0
v_add_invoice_to_stock 9756 9536 220 2.25 0 7137 28 0 220
v_all_customers 36830 36830 0 0.00 0 69 3 0 0
v_all_suppliers 30568 30568 0 0.00 0 118 4 0 0
v_all_wares 40839 40839 0 0.00 0 59 3 0 0
v_cancel_adding_invoice 784 784 0 0.00 0 2052 20 0 0
v_cancel_client_order 3422 3422 0 0.00 0 1940 9 0 0
v_cancel_customer_prepayment 1446 1446 0 0.00 0 5841 18 0 0
v_cancel_customer_reserve 1270 1270 0 0.00 1 6917 16 0 0
v_cancel_payment_to_supplier 826 826 0 0.00 0 1284 11 0 0
v_cancel_supplier_invoice 764 750 14 1.83 0 2507 31 0 14
v_cancel_supplier_order 790 790 0 0.00 0 7020 16 0 0
v_cancel_write_off 2022 2022 0 0.00 0 6726 27 0 0
v_max_id_clo_ord 5504 5504 0 0.00 0 1245 4 0 0
v_max_id_clo_res 1046 1046 0 0.00 0 3153 18 0 0
v_max_id_ord_sup 5447 5447 0 0.00 0 3265 7 0 0
v_max_non_paid_invoice 1502 1502 0 0.00 0 4093 10 0 0
v_max_non_paid_realizn 3841 3841 0 0.00 0 3229 9 0 0
v_min_id_clo_ord 5504 5504 0 0.00 0 1421 7 0 0
v_min_id_clo_res 1046 1046 0 0.00 0 3366 13 0 0
v_min_id_ord_sup 5447 5447 0 0.00 0 5388 11 0 0
v_min_non_paid_invoice 5835 5835 0 0.00 0 5679 21 0 0
v_min_non_paid_realizn 6982 6982 0 0.00 0 9037 23 0 0
v_our_firm 10288 10288 0 0.00 0 64 4 0 0
v_reserve_write_off 19965 19965 0 0.00 0 8923 13 0 0
x_lock_depdocs_on_canc_invoice 10352 10352 0 0.00 0 15126 119 0 0
x_lock_depdocs_on_canc_sup_ord 395 395 0 0.00 0 4274 307 0 0
x_make_qstorno_1000_1200 5508 5508 0 0.00 2 90124 1244 0 0
x_make_qstorno_1200_2000 5450 5450 0 0.00 4 111755 3030 0 0
x_make_qstorno_2100_3300 451071 451071 0 0.00 0 61404 229 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 29-05-2022 13:38:11 to 29-05-2022 13:38:15.

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:19 2 8192000000 8796669024 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:19 11496 8192000000 9715222736 10012147712 788618736 8.781 100 85 170 0 817415120 7289248 21507136
11:09:39 20473 8192000000 9762103744 10071977984 838314704 9.283 100 85 169 1 870010096 11590576 20104816
11:11:03 15786 8192000000 9798561248 10117165056 867545248 9.576 100 90 180 0 906594144 18112080 20936816
11:12:36 15997 8192000000 9859045600 10198933504 918628416 10.083 100 74 148 0 971237504 36508032 16101056
11:13:56 6331 8192000000 9918133792 10264940544 972150512 10.608 100 85 167 0 1029565616 40176512 17238592
11:15:06 1991 8192000000 9989306832 10340610048 1000957072 10.888 100 93 186 0 1101465856 81371136 19137648
11:16:09 2291 8192000000 10010276272 10373275648 1019503856 11.067 100 93 183 1 1130343568 91976944 18862768
11:17:14 951 8192000000 10025685792 10397564928 1039473952 11.260 100 96 181 2 1149394256 91266176 18654128
11:18:16 1006 8192000000 10027373216 10406961152 1053847680 11.398 100 95 187 1 1149703168 76887344 18968144
11:19:18 1025 8192000000 10025758576 10405122048 1066091968 11.515 100 95 182 4 1145204064 60988160 18123936
11:20:20 1289 8192000000 10046112880 10428137472 1070644304 11.558 100 97 189 0 1167273744 77076368 19553072
11:21:22 1180 8192000000 10062624400 10445991936 1074513664 11.595 100 98 191 2 1181977680 87587024 19876992
11:22:24 1011 8192000000 10036205872 10426105856 1076611968 11.615 100 88 176 1 1159731088 64908320 18210800
11:23:26 1024 8192000000 10024913840 10407923712 1083282688 11.679 100 99 193 1 1148916352 43635904 21997760
11:24:28 4526 8192000000 10030696720 10418601984 1084840096 11.694 100 78 149 0 1153057680 52266768 15950816
11:25:34 1069 8192000000 10053213088 10442788864 1093581072 11.777 100 92 184 1 1179084352 66126736 19376544
11:26:36 1112 8192000000 10063624704 10452578304 1086115792 11.706 100 88 175 1 1184498336 80801936 17580608
11:27:38 1286 8192000000 10075576816 10467229696 1089601264 11.739 100 96 184 0 1198788928 90376272 18811392
11:28:40 1162 8192000000 10056871088 10446761984 1086171760 11.706 100 95 180 0 1176885568 72840640 17873168
11:29:42 2875 8192000000 10043345920 10439540736 1083467408 11.681 100 74 141 2 1165798928 68381376 13950144
11:30:47 5592 8192000000 10016336352 10407264256 1075394336 11.604 100 59 108 1 1146980224 60799808 10786080
11:31:54 7570 8192000000 10005758000 10389610496 1045491456 11.317 99 62 120 0 1127559728 69527376 12540896
11:33:03 883 8192000000 9986673104 10359463936 1038779392 11.253 100 91 176 0 1109610352 50816112 20014848
11:34:06 1298 8192000000 9987447136 10361106432 1030930560 11.177 100 95 181 0 1106858592 57497344 18430688
11:35:08 1309 8192000000 9984780848 10353512448 1027547328 11.145 100 100 182 1 1103542416 57291392 18703696
11:36:10 5834 8192000000 9966275664 10336333824 1009690896 10.972 98 72 140 1 1091235024 67612864 13931264
11:37:17 2781 8192000000 9983082416 10352615424 1034070592 11.208 100 84 163 2 1107548176 56039568 17438016
11:38:26 13897 8192000000 10021523568 10403860480 1056687888 11.425 100 73 141 1 1140567968 69350208 14529872
11:39:47 9102 8192000000 10064549680 10453004288 1077043328 11.619 100 67 127 3 1174614064 83531904 14038832
11:41:03 1126 8192000000 10075043952 10459754496 1093608784 11.777 100 93 178 1 1195799328 82033840 20156704
11:42:07 1674 8192000000 10116541408 10508414976 1104128608 11.877 100 85 158 4 1233205632 113838624 15238400
11:43:12 1585 8192000000 10161376704 10554736640 1118826816 12.016 100 84 165 0 1284135536 148151440 17157280
11:44:18 17857 8192000000 10165718656 10563010560 1129497296 12.117 100 67 127 1 1280421616 137410432 13513888
11:45:37 3462 8192000000 10199607936 10592780288 1139729280 12.213 100 81 158 0 1304692528 148341248 16622000
11:46:46 2609 8192000000 10220680048 10620502016 1154310976 12.350 100 78 145 0 1334531696 164808672 15412048
11:47:54 958 8192000000 10277625936 10673864704 1164301680 12.444 100 93 181 0 1391184720 205961408 20921632
11:48:56 1679 8192000000 10323917120 10721660928 1173664944 12.531 100 90 174 0 1436123936 242909904 19549088
11:50:01 1290 8192000000 10361852080 10777956352 1177316896 12.565 100 92 181 0 1465904672 268029184 20558592
11:51:06 1462 8192000000 10411124512 10817531904 1189906496 12.682 100 90 178 0 1520783280 309298512 21578272
11:52:11 971 8192000000 10441938144 10858168320 1195701936 12.736 100 98 193 0 1549623520 331490048 22431536
11:53:16 1263 8192000000 10461396944 10874114048 1196264400 12.742 100 94 179 1 1571359520 352981376 22113744
11:54:22 1425 8192000000 10528246512 10961747968 1199792896 12.774 100 96 184 1 1630777344 409428224 21556224
11:55:28 1526 8192000000 10570499744 11000922112 1205295424 12.825 100 95 189 2 1674905280 446723248 22886608
11:56:33 2825 8192000000 10573844400 11001118720 1199318400 12.770 100 82 164 0 1675519776 455629056 20572320
11:57:38 2581 8192000000 10550350560 11018309632 1195280736 12.732 100 76 147 0 1657069424 443447872 18340816
11:58:42 4895 8192000000 10526517888 10994667520 1161844864 12.421 98 67 132 2 1646850480 467883024 17122592
11:59:50 10048 8192000000 10508710944 10951569408 1160743216 12.410 99 43 82 0 1610736400 439589904 10403280
12:01:02 22754 8192000000 10484813504 10941698048 1141070320 12.226 100 32 64 0 1594641840 444586096 8985424
12:02:39 19353 8192000000 10430704928 10874032128 1145034048 12.263 100 32 62 1 1550338640 397065056 8239536
12:04:01 23020 8192000000 10216320672 10770669568 1113954784 11.970 99 22 44 0 1383122336 263407504 5760048
12:05:25 45358 8192000000 10093926560 10509799424 1092851056 11.770 97 31 57 0 1152066640 53691232 5524352
12:07:12 46889 8192000000 10114936816 10557657088 1134522272 12.164 100 31 60 0 1213980768 73023376 6435120
12:09:06 25006 8192000000 10086488880 10518949888 1118223504 12.010 99 49 93 1 1180969408 52797856 9948048
12:10:32 36565 8192000000 10104134384 10567663616 1106172144 11.896 98 20 39 1 1173282768 63186224 3924400
12:12:14 57247 8192000000 10131039504 10607312896 1134137248 12.160 100 12 24 1 1227710496 91025456 2547792
12:14:14 59613 8192000000 10113942304 10578432000 1079407664 11.642 95 30 60 0 1187180720 101912944 5860112
12:16:15 120294 8192000000 10117910960 10581590016 1112539040 11.956 99 33 63 0 1209929008 90205952 7184016
12:19:52 28762 8192000000 10057256272 10503020544 1115128000 11.981 99 22 44 0 1158638304 39390288 4120016
12:21:21 88406 8192000000 10106946848 10595143680 1111308656 11.945 98 19 37 1 1202471056 86764240 4398160
12:23:52 34672 8192000000 10064942672 10501124096 1115812688 11.987 98 33 58 3 1144678528 23532672 5333168
12:25:27 83933 8192000000 10096785872 10570829824 1115465632 11.984 98 34 68 0 1190314144 67765408 7083104
12:27:59 69418 8192000000 10058272240 10525573120 1113495232 11.965 98 10 20 0 1150249776 34425968 2328576
12:30:08 23405 8192000000 10096037376 10542886912 1113948512 11.970 98 39 76 2 1170150912 48627088 7575312
12:31:39 76933 8192000000 10073589296 10509336576 1102473504 11.861 98 35 70 1 1174413488 65031264 6908720
12:33:58 147102 8192000000 10094304128 10556022784 1112822736 11.959 99 31 62 1 1197618384 78152384 6643264
12:37:34 40798 8192000000 10105403856 10555342848 1110385712 11.936 98 24 46 1 1190070768 75022896 4662160
12:39:16 35448 8192000000 10056902512 10568339456 1116451968 11.993 99 6 12 0 1168693600 51098272 1143360
12:40:52 66115 8192000000 10125262352 10576044032 1123058800 12.056 99 15 30 1 1206484800 80114496 3311504
12:43:02 96895 8192000000 10087519424 10602414080 1138288928 12.199 100 11 22 0 1192143792 51609216 2245648
12:45:52 64352 8192000000 10095313984 10550235136 1150503888 12.314 100 20 40 0 1179558352 24796768 4257696
12:47:57 37231 8192000000 10097665232 10543083520 1116685152 11.996 98 29 54 1 1172108128 48994688 6428288
12:49:36 48572 8192000000 10118014320 10605355008 1091380416 11.756 95 20 40 1 1185174336 89575456 4218464
12:51:35 66369 8192000000 10166293264 10590769152 1089263648 11.736 96 35 70 1 1203944464 107125728 7555088
12:53:43 28380 8192000000 10136132016 10569318400 1110718080 11.939 99 43 84 4 1201296160 82047344 8530736
12:55:13 8781 8192000000 10089249904 10506010624 1125152240 12.076 100 65 125 1 1179208704 40234000 13822464
12:56:23 67963 8192000000 10112580160 10585878528 1120668800 12.033 99 32 64 0 1214155872 86586992 6900080
12:58:35 4055 8192000000 10077076464 10487181312 1115378352 11.983 100 83 157 3 1164275632 34188928 14708352
12:59:42 78065 8192000000 10072786592 10504069120 1100418768 11.842 98 39 76 0 1157776448 49368320 7989360
13:02:16 95783 8192000000 10041475680 10508177408 1123996672 12.065 99 12 22 1 1159346720 32545888 2804160
13:05:21 58080 8192000000 10066382112 10528387072 1108483216 11.918 97 13 26 0 1148161408 35797808 3880384
13:07:31 60527 8192000000 10066559280 10527477760 1086454912 11.709 96 26 51 0 1140670128 48320112 5895104
13:09:37 57765 8192000000 10045742768 10503716864 1099807824 11.836 98 14 28 0 1137042112 34226528 3007760
13:11:35 15344 8192000000 10080826064 10514341888 1103843792 11.874 97 41 81 1 1166099664 54011584 8244288
13:12:55 52084 8192000000 10115713888 10576203776 1117627536 12.005 98 25 50 1 1209604848 86307760 5669552
13:14:48 34094 8192000000 10104943936 10568208384 1122495984 12.051 99 25 50 2 1210968208 83179728 5292496
13:16:22 119760 8192000000 10091379168 10544775168 1087753744 11.721 95 19 38 0 1145757520 54269424 3734352
13:19:38 19794 8192000000 10153159408 10584219648 1142294512 12.237 100 55 111 0 1238609136 85073712 11240912
13:21:04 24767 8192000000 10108681568 10538786816 1143072496 12.244 100 52 92 0 1209152432 54870224 11209712
13:22:33 91825 8192000000 10128872480 10583384064 1144885200 12.261 100 59 117 1 1241536832 83761664 12889968
13:25:05 74994 8192000000 10086794096 10574938112 1133054144 12.150 99 8 16 0 1187802400 53244256 1504000
13:27:22 52644 8192000000 10073191696 10515722240 1079677872 11.644 94 18 35 1 1122058320 38268096 4112352
13:29:17 67598 8192000000 10074845056 10506530816 1074385024 11.594 95 26 48 1 1129997616 50682336 4930256
13:31:26 46415 8192000000 10069087984 10518798336 1130512048 12.126 100 22 43 1 1185247008 50283344 4451616
13:33:15 54111 8192000000 10085907360 10532728832 1123830320 12.063 99 41 80 1 1175701968 43303520 8568128
13:35:09 94662 8192000000 10062018112 10503688192 1065139280 11.506 94 22 41 2 1123379056 53418496 4821280

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 29-05-2022 13:38:17 to 29-05-2022 13:38:33.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 110 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 29-05-2022 13:38:34 to 29-05-2022 13:38:36.

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          218692
MON$OLDEST_ACTIVE               220869
MON$OLDEST_SNAPSHOT             220869
MON$NEXT_TRANSACTION            220869
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                       2012928
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             781
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2770 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2770 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2770 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2770 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 ---
:164: 
:165: mscs-test-161.budzdorov.ru    Sun May 29 13:37:18 2022
:166:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:167: 
:168: 
:169: mscs-test-161.budzdorov.ru    Sun May 29 13:37:18 2022
:170:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:171: 
:172: 
:173: mscs-test-161.budzdorov.ru    Sun May 29 13:37:21 2022
:174:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:175: 
--- end of diff output ---

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

29.05.22 13:38:37. 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-29 13:38:39 No severe PSQL-related problems occured
29.05.22 13:38:41. End of report.