Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 11.05.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 16431588 kB
MemAvailable 31505644 kB
Buffers 0 kB
Cached 15308968 kB
SwapCached 2596 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-11 13:37:12
DTS_END                         2022-05-11 13:37:12.2240
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/51648
TRN_ID                          211535
ATT_ID                          626
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: 8734.40 23034 1048128
customer order: creation 159.27 465 19112
customer order: refuse 13.68 422 1642
order to supplier: creation 43.48 1348 5217
order to supplier: removal 3.33 193330 399
invoice (draft): creation 41.84 3349 5021
invoice (draft): removal 50.43 997 6051
invoice accept: apply 38.17 26804 4580
invoice accept: cancel 45.79 1311 5495
customer reserve: creation 3569.53 253 428344
customer reserve: removal 4488.81 113 538657
realization accept: apply 77.27 283 9272
realization accept: cancel 84.95 173 10194
payment from customer: creation 53.13 176 6375
payment from customer: removal 4.90 125 588
payment to supplier: creation 44.92 194 5390
payment to supplier: removal 3.08 129 369
service: total inventory turnovers 3.86 3931 463
service: total monetary turnovers 7.03 406 843
service: aggregate perf. data 0.93 15685 111
service: refresh index statistics 0.04 211183 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 11-05-2022 13:39:12 to 11-05-2022 13:39:14.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 11108
TEST_TIME 2 9235
TEST_TIME 3 9021
TEST_TIME 4 7835
TEST_TIME 5 7240
TEST_TIME 6 7187
TEST_TIME 7 6899
TEST_TIME 8 7349
TEST_TIME 9 7437
TEST_TIME 10 7384
TEST_TIME 11 7208
TEST_TIME 12 7313
TEST_TIME 13 7332
TEST_TIME 14 7199
TEST_TIME 15 7119
TEST_TIME 16 7072
TEST_TIME 17 7009
TEST_TIME 18 7499
TEST_TIME 19 7557
TEST_TIME 20 7599
TEST_TIME 21 7740
TEST_TIME 22 8003
TEST_TIME 23 7948
TEST_TIME 24 8015
TEST_TIME 25 8171
TEST_TIME 26 8372
TEST_TIME 27 9049
TEST_TIME 28 9481
TEST_TIME 29 10095
TEST_TIME 30 10063
TEST_TIME 31 10608
TEST_TIME 32 11314
TEST_TIME 33 11254
TEST_TIME 34 11072
TEST_TIME 35 10806
TEST_TIME 36 10656
TEST_TIME 37 10589
TEST_TIME 38 10440
TEST_TIME 39 10289
TEST_TIME 40 10230
TEST_TIME 41 10104
TEST_TIME 42 9963
TEST_TIME 43 9880
TEST_TIME 44 9778
TEST_TIME 45 9769
TEST_TIME 46 9625
TEST_TIME 47 9559
TEST_TIME 48 9406
TEST_TIME 49 9359
TEST_TIME 50 9513
TEST_TIME 51 9620
TEST_TIME 52 9537
TEST_TIME 53 9420
TEST_TIME 54 9323
TEST_TIME 55 9262
TEST_TIME 56 9178
TEST_TIME 57 9079
TEST_TIME 58 9071
TEST_TIME 59 9025
TEST_TIME 60 9026
TEST_TIME 61 9044
TEST_TIME 62 9060
TEST_TIME 63 9096
TEST_TIME 64 9131
TEST_TIME 65 9260
TEST_TIME 66 9233
TEST_TIME 67 9196
TEST_TIME 68 9192
TEST_TIME 69 9183
TEST_TIME 70 9333
TEST_TIME 71 9280
TEST_TIME 72 9315
TEST_TIME 73 9354
TEST_TIME 74 9230
TEST_TIME 75 9183
TEST_TIME 76 9161
TEST_TIME 77 9145
TEST_TIME 78 9087
TEST_TIME 79 9069
TEST_TIME 80 9010
TEST_TIME 81 8974
TEST_TIME 82 8966
TEST_TIME 83 9037
TEST_TIME 84 9028
TEST_TIME 85 8991
TEST_TIME 86 8943
TEST_TIME 87 8914
TEST_TIME 88 8897
TEST_TIME 89 8891
TEST_TIME 90 8917
TEST_TIME 91 8940
TEST_TIME 92 8906
TEST_TIME 93 9014
TEST_TIME 94 9176
TEST_TIME 95 9210
TEST_TIME 96 9147
TEST_TIME 97 9127
TEST_TIME 98 9053
TEST_TIME 99 9002
TEST_TIME 100 8978
TEST_TIME 101 8947
TEST_TIME 102 8907
TEST_TIME 103 8930
TEST_TIME 104 8884
TEST_TIME 105 8875
TEST_TIME 106 8838
TEST_TIME 107 8807
TEST_TIME 108 8851
TEST_TIME 109 8867
TEST_TIME 110 8976
TEST_TIME 111 9014
TEST_TIME 112 8969
TEST_TIME 113 8957
TEST_TIME 114 8905
TEST_TIME 115 8857
TEST_TIME 116 8869
TEST_TIME 117 8832
TEST_TIME 118 8845
TEST_TIME 119 8823
TEST_TIME 120 8757

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 11-05-2022 13:39:15 to 11-05-2022 13:39:19.

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 1443789 1443789 0 0.00 0 16524 13 0 0
doc_list_biud 1443784 1443784 0 0.00 0 43623 48 0 0
sp_add_invoice_to_stock 4696 4580 116 2.47 47 251679 26804 0 116
sp_cancel_adding_invoice 5495 5495 0 0.00 6 82568 1311 0 0
sp_cancel_client_order 1642 1642 0 0.00 6 12269 423 0 0
sp_cancel_customer_reserve 538657 538657 0 0.00 2 43624 114 0 0
sp_cancel_pay_from_customer 588 588 0 0.00 4 5304 126 0 0
sp_cancel_pay_to_supplier 369 369 0 0.00 3 4112 130 0 0
sp_cancel_supplier_invoice 6059 6051 8 0.13 3 35211 997 0 8
sp_cancel_supplier_order 399 399 0 0.00 14 1639130 193331 0 0
sp_cancel_write_off 10194 10194 0 0.00 2 20341 174 0 0
sp_client_order 19112 19112 0 0.00 6 28005 465 0 0
sp_customer_reserve 428344 428344 0 0.00 1 54423 254 0 0
sp_fill_shopping_cart 29337 29337 0 0.00 1 16978 84 0 0
sp_fill_shopping_cart_clo_res 428312 428312 0 0.00 0 17171 21 0 0
sp_get_clo_for_invoice 4540 4540 0 0.00 0 32217 379 0 0
sp_kill_cost_storno 16646 16646 0 0.00 0 9296 78 0 0
sp_kill_qstorno_ret_qs2qd 1093958 1093958 0 0.00 0 43621 49 0 0
sp_kill_qty_storno 576250 576250 0 0.00 0 43623 111 0 0
sp_lock_selected_doc 19060 19060 0 0.00 0 7858 7 0 0
sp_make_cost_storno 25577 25577 0 0.00 0 12228 80 0 0
sp_multiply_rows_for_pdistr 25577 25577 0 0.00 0 8699 36 0 0
sp_multiply_rows_for_qdistr 19112 19112 0 0.00 0 27480 191 0 0
sp_pay_from_customer 6375 6375 0 0.00 6 19972 177 0 0
sp_pay_to_supplier 5390 5390 0 0.00 7 22719 194 0 0
sp_payment_common 11764 11764 0 0.00 2 8857 46 0 0
sp_qd_handle_on_cancel_clo 1642 1642 0 0.00 1 12259 343 0 0
sp_qd_handle_on_invoice_upd_sts 10035 10035 0 0.00 0 18321 539 0 0
sp_qd_handle_on_reserve_upd_sts 9272 9272 0 0.00 0 18203 64 0 0
sp_reserve_write_off 9272 9272 0 0.00 9 18415 284 0 0
sp_supplier_invoice 5021 5021 0 0.00 11 78825 3349 0 0
sp_supplier_order 5217 5217 0 0.00 8 49027 1348 0 0
srv_aggregate_perf_data 111 111 0 0.00 2457 154020 15686 0 0
srv_fill_mon_cache_memory 68 68 0 0.00 769 140417 38163 0 0
srv_find_qd_qs_mism 1003677 1003677 0 0.00 0 16523 10 0 0
srv_make_invnt_saldo 463 463 0 0.00 76 65741 3932 0 0
srv_make_money_saldo 843 843 0 0.00 0 26767 406 0 0
srv_recalc_idx_stat 5 5 0 0.00 50560 459330 211184 0 0
t$perf-abend:sp_add_invoice_to_stock 116 116 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4584 4584 0 0.00 1 18299 924 0 0
t$perf-norm:sp_cancel_adding_invoice 364 364 0 0.00 1 18466 584 0 0
t$perf-norm:sp_cancel_client_order 1642 1642 0 0.00 0 1889 27 0 0
t$perf-norm:sp_cancel_customer_reserve 651 651 0 0.00 0 622 23 0 0
t$perf-norm:sp_cancel_pay_from_customer 588 588 0 0.00 0 3989 17 0 0
t$perf-norm:sp_cancel_pay_to_supplier 369 369 0 0.00 0 808 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 349 349 0 0.00 0 1323 34 0 0
t$perf-norm:sp_cancel_supplier_order 399 399 0 0.00 0 86281 6974 0 0
t$perf-norm:sp_cancel_write_off 888 888 0 0.00 0 843 16 0 0
t$perf-norm:sp_client_order 19112 19112 0 0.00 0 8294 38 0 0
t$perf-norm:sp_customer_reserve 866 866 0 0.00 0 1107 16 0 0
t$perf-norm:sp_pay_from_customer 6375 6375 0 0.00 0 16690 23 0 0
t$perf-norm:sp_pay_to_supplier 5390 5390 0 0.00 0 14387 23 0 0
t$perf-norm:sp_reserve_write_off 9272 9272 0 0.00 0 15262 24 0 0
t$perf-norm:sp_supplier_invoice 5021 5021 0 0.00 0 14222 35 0 0
t$perf-norm:sp_supplier_order 5217 5217 0 0.00 0 4548 31 0 0
t$perf-norm:srv_aggregate_perf_data 111 111 0 0.00 0 5467 59 0 0
t$perf-norm:srv_fill_mon_cache_memory 68 68 0 0.00 0 209 7 0 0
t$perf-norm:srv_make_invnt_saldo 463 463 0 0.00 0 373 14 0 0
t$perf-norm:srv_make_money_saldo 843 843 0 0.00 0 867 5 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 5 116 32 0 0
v_add_invoice_to_stock 9308 9076 232 2.49 0 13889 29 0 232
v_all_customers 34436 34436 0 0.00 0 238 4 0 0
v_all_suppliers 28418 28418 0 0.00 0 67 4 0 0
v_all_wares 38222 38222 0 0.00 0 92 4 0 0
v_cancel_adding_invoice 728 728 0 0.00 0 1100 14 0 0
v_cancel_client_order 3284 3284 0 0.00 0 4609 14 0 0
v_cancel_customer_prepayment 1176 1176 0 0.00 0 629 10 0 0
v_cancel_customer_reserve 1302 1302 0 0.00 0 2411 12 0 0
v_cancel_payment_to_supplier 738 738 0 0.00 0 1050 11 0 0
v_cancel_supplier_invoice 714 698 16 2.24 0 851 23 0 16
v_cancel_supplier_order 798 798 0 0.00 0 6006 23 0 0
v_cancel_write_off 1776 1776 0 0.00 0 20042 33 0 0
v_max_id_clo_ord 5212 5212 0 0.00 0 2419 5 0 0
v_max_id_clo_res 866 866 0 0.00 0 4828 14 0 0
v_max_id_ord_sup 5013 5013 0 0.00 0 5301 6 0 0
v_max_non_paid_invoice 1404 1404 0 0.00 0 466 7 0 0
v_max_non_paid_realizn 3407 3407 0 0.00 0 3076 8 0 0
v_min_id_clo_ord 5212 5212 0 0.00 0 3574 8 0 0
v_min_id_clo_res 866 866 0 0.00 0 3227 16 0 0
v_min_id_ord_sup 5013 5013 0 0.00 0 3119 10 0 0
v_min_non_paid_invoice 5390 5390 0 0.00 0 4112 16 0 0
v_min_non_paid_realizn 6374 6374 0 0.00 0 8851 20 0 0
v_our_firm 9720 9720 0 0.00 0 66 4 0 0
v_reserve_write_off 18544 18544 0 0.00 0 6073 12 0 0
x_lock_depdocs_on_canc_invoice 11197 11197 0 0.00 0 12831 143 0 0
x_lock_depdocs_on_canc_sup_ord 399 399 0 0.00 0 10489 417 0 0
x_make_qstorno_1000_1200 5217 5217 0 0.00 3 49007 1180 0 0
x_make_qstorno_1200_2000 5021 5021 0 0.00 6 78810 3113 0 0
x_make_qstorno_2100_3300 428344 428344 0 0.00 0 54383 221 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 11-05-2022 13:39:20 to 11-05-2022 13:39:24.

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:16 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:16 28153 8192000000 9729051008 10030100480 800398832 8.900 100 82 164 0 827135552 7168256 19568464
11:09:51 22103 8192000000 9770333536 10081955840 842113168 9.321 100 86 172 0 874277680 11981920 20182592
11:11:23 17690 8192000000 9820996704 10144907264 877355520 9.673 100 80 160 0 924402272 28945232 18101520
11:12:46 7299 8192000000 9885807280 10220441600 916888544 10.065 100 79 157 0 988223888 54023264 17312080
11:13:58 5456 8192000000 9915345152 10264346624 960234528 10.491 100 81 162 0 1036223568 59083456 16905584
11:15:05 2932 8192000000 9971841776 10325164032 993802832 10.818 100 92 182 0 1085755280 73734128 18218320
11:16:13 912 8192000000 10001684880 10365935616 1027531344 11.145 100 97 187 2 1120457008 73263408 19662256
11:17:15 1022 8192000000 10029025904 10398781440 1052194800 11.382 100 97 186 1 1150300304 79318384 18787120
11:18:17 1457 8192000000 10049250384 10426634240 1061698496 11.473 100 96 177 0 1171791728 90492064 19601168
11:19:19 1338 8192000000 10019138064 10398732288 1069172400 11.544 100 91 177 0 1147241840 59933120 18136320
11:20:21 1077 8192000000 10060963600 10441936896 1071873424 11.570 100 97 189 3 1181697024 91501120 18322480
11:21:24 1250 8192000000 10053361440 10434793472 1079054448 11.638 100 93 183 0 1172238592 74664192 18519952
11:22:26 1221 8192000000 10055878240 10441392128 1083172768 11.678 100 96 184 1 1172083232 69144096 19766368
11:23:31 5521 8192000000 10049380160 10437689344 1090306768 11.746 100 89 176 0 1184334592 75517760 18510064
11:24:37 2669 8192000000 10046535936 10435661824 1089271808 11.736 100 79 152 1 1162943216 58294208 15377200
11:25:40 1059 8192000000 10035469856 10426527744 1085961856 11.704 100 78 133 3 1158972624 60875968 12134800
11:26:42 1514 8192000000 10052031040 10445201408 1096811776 11.807 100 87 175 0 1178142512 63779456 17551280
11:27:44 2708 8192000000 10028427568 10419380224 1080602560 11.653 99 71 133 3 1157600976 62483808 14514608
11:28:48 1145 8192000000 10051730816 10439864320 1084901056 11.694 100 82 149 1 1175492464 72992384 17599024
11:29:50 1043 8192000000 10062056944 10446245888 1076412752 11.613 100 89 177 0 1185780048 91033152 18334144
11:30:51 2663 8192000000 10031923664 10417545216 1076438240 11.614 100 70 140 1 1147178016 57104496 13635280
11:31:55 1248 8192000000 10006574144 10381156352 1048143696 11.343 99 96 181 3 1128051600 60918368 18989536
11:32:56 2611 8192000000 9983795056 10362081280 1030800384 11.176 98 72 135 1 1099812432 54340272 14671776
11:34:00 1268 8192000000 9983060944 10353664000 1026933744 11.139 98 91 172 1 1107851824 62864352 18053728
11:35:03 804 8192000000 10002010128 10369261568 1032086752 11.189 100 96 185 0 1121643840 71350720 18206368
11:36:06 1049 8192000000 9992227856 10366214144 1024492784 11.115 100 96 185 3 1113423360 70299072 18631504
11:37:07 2163 8192000000 10001306032 10379313152 1060692064 11.463 100 88 153 2 1123950592 47928592 15329936
11:38:16 2102 8192000000 10021965776 10401779712 1075375712 11.603 100 87 160 1 1143378064 52428464 15573888
11:39:21 1117 8192000000 10072118272 10456264704 1088546160 11.729 100 95 179 1 1192880176 86171040 18162976
11:40:27 2179 8192000000 10070216992 10466217984 1106281888 11.897 100 72 136 1 1196273168 76041504 13949776
11:41:34 2495 8192000000 10097733328 10491666432 1114563312 11.976 100 79 150 3 1214725600 84508304 15653984
11:42:47 808 8192000000 10150076160 10542850048 1135664992 12.175 100 91 171 0 1263335184 109150416 18519776
11:43:53 5383 8192000000 10162480528 10558869504 1147553408 12.287 100 86 153 3 1279666224 116054016 16058800
11:45:12 1420 8192000000 10222291232 10633338880 1159559904 12.399 100 89 172 1 1333279040 153242160 20476976
11:46:16 1450 8192000000 10243363104 10636464128 1165122256 12.451 100 93 177 1 1351992064 167426048 19443760
11:47:19 1434 8192000000 10264777344 10665103360 1170496976 12.501 100 92 175 1 1379932608 190041920 19393712
11:48:25 4851 8192000000 10295378688 10697662464 1173874752 12.533 100 74 147 1 1408437664 217325120 17237792
11:49:36 2630 8192000000 10318349616 10734313472 1168647360 12.484 100 74 146 1 1431492944 245296896 17548688
11:50:42 1762 8192000000 10378394192 10783305728 1189160976 12.676 100 90 174 0 1487891536 279273328 19457232
11:51:47 4105 8192000000 10409052384 10824151040 1186051376 12.647 100 78 153 2 1517696672 313894448 17750848
11:52:55 2256 8192000000 10455348800 10866626560 1196329664 12.742 100 82 163 0 1567362352 351954480 19078208
11:54:02 954 8192000000 10509176608 10919489536 1199032432 12.767 100 93 182 0 1616987488 396122672 21832384
11:55:07 780 8192000000 10497521616 10961231872 1200185536 12.778 100 94 182 0 1605901088 382515504 23200048
11:56:11 3667 8192000000 10523185536 10953572352 1202291488 12.798 100 96 182 1 1630152880 406270832 21590560
11:57:19 839 8192000000 10540981792 10967347200 1200523424 12.781 100 90 175 0 1649213872 428157728 20532720
11:58:24 1091 8192000000 10572640720 11016785920 1205099488 12.824 100 96 184 0 1681428512 453233152 23095872
11:59:27 1565 8192000000 10560147568 11001364480 1184286976 12.630 98 79 158 0 1665722368 461178304 20257088
12:00:33 2199 8192000000 10618926240 11042226176 1194842656 12.728 100 70 137 0 1721333280 509105984 17384640
12:01:41 762 8192000000 10631152448 11065462784 1184851712 12.635 100 94 182 1 1729491536 521831392 22808432
12:02:43 6131 8192000000 10646774864 11086614528 1187103728 12.656 100 63 124 0 1757634800 554447968 16083104
12:03:52 13124 8192000000 10596400128 11087568896 1177784608 12.570 99 52 99 1 1693217888 502317232 13116048
12:05:06 21013 8192000000 10483201984 10958438400 1181251840 12.602 100 39 78 0 1599765296 407340080 11173376
12:06:32 36161 8192000000 10370023376 10869436416 1144801008 12.261 98 20 40 0 1472660448 322504752 5354688
12:08:55 25954 8192000000 10161625904 10643255296 1135595792 12.174 100 16 32 0 1314291696 174734128 3961776
12:10:22 30730 8192000000 10052097008 10480046080 1110173760 11.934 97 49 87 3 1142409392 22262928 9972704
12:11:56 78357 8192000000 10054209792 10509389824 1109671760 11.929 98 27 56 1 1140043360 24484560 5887040
12:14:17 54573 8192000000 10063076112 10519805952 1110028512 11.933 98 27 54 2 1164929680 49561648 5339520
12:16:12 81318 8192000000 10092310976 10571276288 1135983600 12.178 100 18 36 0 1188072384 47730080 4358704
12:18:40 58630 8192000000 10094209312 10547281920 1119623712 12.023 98 34 68 1 1182582896 56236960 6722224
12:20:48 34431 8192000000 10141805904 10586791936 1130999168 12.131 98 31 55 1 1212162912 75354016 5809728
12:23:01 89674 8192000000 10199960800 10665525248 1131159296 12.132 98 20 40 0 1266483392 130367264 4956832
12:26:32 32370 8192000000 10141477008 10618961920 1112467104 11.956 98 34 65 2 1227703568 108328032 6908432
12:28:05 93187 8192000000 10032771312 10496495616 1057971728 11.437 94 10 21 0 1090239600 30145840 2122032
12:30:51 56751 8192000000 10038219344 10498015232 1112201376 11.953 99 21 42 0 1136674928 20214336 4259216
12:32:51 67161 8192000000 10063875536 10528194560 1113434240 11.965 99 10 20 0 1162241440 46923200 1884000
12:34:58 78041 8192000000 10078251744 10593443840 1104697376 11.882 98 12 24 0 1189888416 82606480 2584560
12:37:18 39626 8192000000 10104698640 10554908672 1136639392 12.184 100 15 30 2 1209295904 69421072 3235440
12:39:02 46197 8192000000 10123043456 10581708800 1133866336 12.158 99 30 58 2 1202550016 62622688 6060992
12:40:49 31912 8192000000 10124392752 10586963968 1148980864 12.300 100 33 66 2 1235916048 79957120 6978064
12:42:50 49765 8192000000 10065771888 10518151168 1128258576 12.105 99 20 38 1 1172967872 41127680 3581616
12:44:40 2499 8192000000 10091786640 10507829248 1122587776 12.051 98 78 150 4 1177166480 39667984 14910720
12:45:45 12719 8192000000 10143139456 10571448320 1131708496 12.137 99 54 108 0 1233012192 90681136 10622560
12:46:58 87064 8192000000 10075644800 10531000320 1102305872 11.860 96 12 24 0 1153650992 49177792 2167328
12:49:27 49525 8192000000 10101585760 10559901696 1088358016 11.727 96 32 64 0 1180124256 84907184 6859056
12:51:24 72783 8192000000 10085891024 10521387008 1105580048 11.891 98 38 74 0 1159888176 46851888 7456240
12:53:40 16108 8192000000 10061728656 10518458368 1109269680 11.926 99 26 50 1 1152247664 37548464 5429520
12:55:00 83198 8192000000 10050153312 10524536832 1083547056 11.681 97 16 32 0 1138890000 52026832 3316112
12:57:33 81494 8192000000 10085575232 10549944320 1111871424 11.950 99 23 45 1 1177834208 60373712 5589072
12:59:56 103924 8192000000 10047915408 10508996608 1087777680 11.722 97 11 22 0 1158724224 68639872 2306672
13:02:40 29041 8192000000 10111388016 10556444672 1123786960 12.063 100 28 56 0 1213490448 83501088 6202400
13:04:09 12122 8192000000 10181934272 10601738240 1136525312 12.183 100 54 108 1 1277283904 128640320 12118272
13:05:23 22015 8192000000 10231512528 10671562752 1139731136 12.213 100 46 90 1 1321743440 171021792 10990512
13:06:59 39276 8192000000 10268507056 10704424960 1142562608 12.240 100 38 76 1 1337506384 185869824 9073952
13:08:50 51930 8192000000 10285562064 10738847744 1151382880 12.322 100 26 51 0 1350542096 193354624 5804592
13:10:51 52837 8192000000 10198872848 10665988096 1143677776 12.250 100 20 40 0 1322135648 173937152 4520720
13:12:45 69257 8192000000 10098557264 10534854656 1143030496 12.244 100 28 56 0 1195744928 46931120 5783312
13:14:59 92497 8192000000 10111120848 10570571776 1141782000 12.232 100 12 24 0 1208480512 64122448 2576064
13:17:32 69946 8192000000 10094701200 10576388096 1116750752 11.996 98 14 22 0 1180153760 61321360 2081648
13:19:42 78309 8192000000 10089237184 10539253760 1104571312 11.881 97 12 24 1 1144011904 37201232 2239360
13:22:02 38994 8192000000 10145431072 10601943040 1136878688 12.186 99 27 52 1 1224615008 81539504 6196816
13:24:01 93952 8192000000 10194789072 10717970432 1149129200 12.301 99 29 58 0 1306026592 150432128 6465264
13:27:04 140415 8192000000 10083007216 10519867392 1102608608 11.862 96 24 48 1 1136969328 29646448 4714272
13:30:25 132951 8192000000 10147658864 10614530048 1115567088 11.985 97 48 96 2 1221129920 95647088 9915744
13:34:54 54228 8192000000 10073053760 10583576576 1135856960 12.177 100 6 12 0 1200226288 62769664 1599664
13:36:52 36746 8192000000 9299412016 9653383168 432036080 5.009 39 1 2 0 452241856 20170368 35408

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 11-05-2022 13:39:26 to 11-05-2022 13:39:42.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 11-05-2022 13:39:43 to 11-05-2022 13:39:45.

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          211766
MON$OLDEST_ACTIVE               211767
MON$OLDEST_SNAPSHOT             211767
MON$NEXT_TRANSACTION            211767
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                       2049888
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             757
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2763 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2763 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2763 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2763 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 ---
:312: 
:313: mscs-test-161.budzdorov.ru    Wed May 11 13:37:37 2022
:314:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:315: 
:316: 
:317: mscs-test-161.budzdorov.ru    Wed May 11 13:37:37 2022
:318:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:319: 
:320: 
:321: mscs-test-161.budzdorov.ru    Wed May 11 13:37:37 2022
:322:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:323: 
--- end of diff output ---

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

11.05.22 13:39:46. 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-11 13:39:48 No severe PSQL-related problems occured
11.05.22 13:39:50. End of report.