Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 19.12.2022 10: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 15058156 kB
MemAvailable 30097728 kB
Buffers 0 kB
Cached 16679144 kB
SwapCached 3500 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-12-19 10:37:21
DTS_END                         2022-12-19 10:37:21.5490
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/51198
TRN_ID                          203881
ATT_ID                          571
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: 8495.25 24646 1019430
customer order: creation 151.76 517 18211
customer order: refuse 12.66 450 1519
order to supplier: creation 40.73 1498 4887
order to supplier: removal 3.11 191333 373
invoice (draft): creation 40.28 3476 4834
invoice (draft): removal 48.35 1143 5802
invoice accept: apply 37.25 28306 4470
invoice accept: cancel 43.45 1083 5214
customer reserve: creation 3575.75 261 429090
customer reserve: removal 4280.87 107 513704
realization accept: apply 73.73 281 8848
realization accept: cancel 71.32 180 8558
payment from customer: creation 51.75 185 6210
payment from customer: removal 5.43 150 652
payment to supplier: creation 44.61 205 5353
payment to supplier: removal 3.02 158 362
service: total inventory turnovers 3.76 4053 451
service: total monetary turnovers 6.51 387 781
service: aggregate perf. data 0.89 18571 107
service: refresh index statistics 0.03 240580 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 19-12-2022 10:39:01 to 19-12-2022 10:39:03.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8918
TEST_TIME 2 6355
TEST_TIME 3 5767
TEST_TIME 4 5541
TEST_TIME 5 5492
TEST_TIME 6 5547
TEST_TIME 7 5519
TEST_TIME 8 5496
TEST_TIME 9 5521
TEST_TIME 10 5713
TEST_TIME 11 5772
TEST_TIME 12 5864
TEST_TIME 13 5877
TEST_TIME 14 5891
TEST_TIME 15 6139
TEST_TIME 16 6209
TEST_TIME 17 6205
TEST_TIME 18 6412
TEST_TIME 19 6401
TEST_TIME 20 6621
TEST_TIME 21 6891
TEST_TIME 22 6941
TEST_TIME 23 7090
TEST_TIME 24 7201
TEST_TIME 25 7238
TEST_TIME 26 7373
TEST_TIME 27 7878
TEST_TIME 28 8094
TEST_TIME 29 8578
TEST_TIME 30 9636
TEST_TIME 31 9825
TEST_TIME 32 10193
TEST_TIME 33 10433
TEST_TIME 34 10393
TEST_TIME 35 10240
TEST_TIME 36 10223
TEST_TIME 37 10117
TEST_TIME 38 10008
TEST_TIME 39 9907
TEST_TIME 40 9833
TEST_TIME 41 9630
TEST_TIME 42 9469
TEST_TIME 43 9426
TEST_TIME 44 9325
TEST_TIME 45 9317
TEST_TIME 46 9301
TEST_TIME 47 9189
TEST_TIME 48 9189
TEST_TIME 49 9144
TEST_TIME 50 9096
TEST_TIME 51 9143
TEST_TIME 52 8990
TEST_TIME 53 8951
TEST_TIME 54 8913
TEST_TIME 55 8909
TEST_TIME 56 8840
TEST_TIME 57 8845
TEST_TIME 58 8911
TEST_TIME 59 8856
TEST_TIME 60 8860
TEST_TIME 61 8848
TEST_TIME 62 8935
TEST_TIME 63 8947
TEST_TIME 64 9011
TEST_TIME 65 8994
TEST_TIME 66 8945
TEST_TIME 67 8986
TEST_TIME 68 8941
TEST_TIME 69 8867
TEST_TIME 70 8825
TEST_TIME 71 8706
TEST_TIME 72 8809
TEST_TIME 73 8773
TEST_TIME 74 8770
TEST_TIME 75 8701
TEST_TIME 76 8764
TEST_TIME 77 8670
TEST_TIME 78 8669
TEST_TIME 79 8616
TEST_TIME 80 8574
TEST_TIME 81 8604
TEST_TIME 82 8591
TEST_TIME 83 8605
TEST_TIME 84 8671
TEST_TIME 85 8620
TEST_TIME 86 8603
TEST_TIME 87 8647
TEST_TIME 88 8691
TEST_TIME 89 8670
TEST_TIME 90 8708
TEST_TIME 91 8722
TEST_TIME 92 8728
TEST_TIME 93 8768
TEST_TIME 94 8690
TEST_TIME 95 8648
TEST_TIME 96 8595
TEST_TIME 97 8618
TEST_TIME 98 8562
TEST_TIME 99 8544
TEST_TIME 100 8526
TEST_TIME 101 8490
TEST_TIME 102 8462
TEST_TIME 103 8495
TEST_TIME 104 8478
TEST_TIME 105 8507
TEST_TIME 106 8515
TEST_TIME 107 8488
TEST_TIME 108 8571
TEST_TIME 109 8576
TEST_TIME 110 8620
TEST_TIME 111 8619
TEST_TIME 112 8594
TEST_TIME 113 8674
TEST_TIME 114 8661
TEST_TIME 115 8599
TEST_TIME 116 8618
TEST_TIME 117 8571
TEST_TIME 118 8536
TEST_TIME 119 8563
TEST_TIME 120 8526

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 19-12-2022 10:39:04 to 19-12-2022 10:39:08.

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 1412474 1412474 0 0.00 0 23664 13 0 0
doc_list_biud 1412469 1412469 0 0.00 0 70788 45 0 0
sp_add_invoice_to_stock 4551 4470 81 1.77 47 224526 28307 0 81
sp_cancel_adding_invoice 5214 5214 0 0.00 7 72811 1083 0 0
sp_cancel_client_order 1519 1519 0 0.00 6 10488 451 0 0
sp_cancel_customer_reserve 513704 513704 0 0.00 1 70790 108 0 0
sp_cancel_pay_from_customer 652 652 0 0.00 4 9788 151 0 0
sp_cancel_pay_to_supplier 362 362 0 0.00 4 9651 159 0 0
sp_cancel_supplier_invoice 5805 5802 3 0.05 2 33387 1143 0 3
sp_cancel_supplier_order 373 373 0 0.00 6 1420805 191334 0 0
sp_cancel_write_off 8558 8558 0 0.00 2 12285 181 0 0
sp_client_order 18211 18211 0 0.00 5 25871 517 0 0
sp_customer_reserve 429090 429090 0 0.00 0 52532 261 0 0
sp_fill_shopping_cart 27928 27928 0 0.00 1 15845 87 0 0
sp_fill_shopping_cart_clo_res 429059 429059 0 0.00 0 15642 24 0 0
sp_get_clo_for_invoice 4432 4432 0 0.00 0 53885 427 0 0
sp_kill_cost_storno 14786 14786 0 0.00 0 9641 80 0 0
sp_kill_qstorno_ret_qs2qd 1042141 1042141 0 0.00 0 70786 48 0 0
sp_kill_qty_storno 548450 548450 0 0.00 0 70788 108 0 0
sp_lock_selected_doc 18399 18399 0 0.00 0 6972 6 0 0
sp_make_cost_storno 24843 24843 0 0.00 0 14932 85 0 0
sp_multiply_rows_for_pdistr 24843 24843 0 0.00 0 9144 39 0 0
sp_multiply_rows_for_qdistr 18211 18211 0 0.00 0 25802 203 0 0
sp_pay_from_customer 6210 6210 0 0.00 5 10362 186 0 0
sp_pay_to_supplier 5353 5353 0 0.00 6 27529 205 0 0
sp_payment_common 11563 11563 0 0.00 2 10348 45 0 0
sp_qd_handle_on_cancel_clo 1519 1519 0 0.00 0 10475 371 0 0
sp_qd_handle_on_invoice_upd_sts 9646 9646 0 0.00 0 43766 528 0 0
sp_qd_handle_on_reserve_upd_sts 8848 8848 0 0.00 0 8791 62 0 0
sp_reserve_write_off 8848 8848 0 0.00 9 21737 281 0 0
sp_supplier_invoice 4834 4834 0 0.00 8 77680 3477 0 0
sp_supplier_order 4887 4887 0 0.00 8 28749 1499 0 0
srv_aggregate_perf_data 107 107 0 0.00 2276 189512 18572 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 847 117189 38425 0 0
srv_find_qd_qs_mism 974180 974180 0 0.00 0 12371 10 0 0
srv_make_invnt_saldo 451 451 0 0.00 113 80595 4054 0 0
srv_make_money_saldo 781 781 0 0.00 1 14609 388 0 0
srv_recalc_idx_stat 4 4 0 0.00 77022 411817 240581 0 0
t$perf-abend:sp_add_invoice_to_stock 81 81 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 3 3 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4475 4475 0 0.00 1 19604 882 0 0
t$perf-norm:sp_cancel_adding_invoice 341 341 0 0.00 1 5476 372 0 0
t$perf-norm:sp_cancel_client_order 1519 1519 0 0.00 0 5993 35 0 0
t$perf-norm:sp_cancel_customer_reserve 653 653 0 0.00 0 1507 28 0 0
t$perf-norm:sp_cancel_pay_from_customer 652 652 0 0.00 0 1909 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 362 362 0 0.00 0 957 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 371 371 0 0.00 0 1062 28 0 0
t$perf-norm:sp_cancel_supplier_order 373 373 0 0.00 0 106142 7357 0 0
t$perf-norm:sp_cancel_write_off 851 851 0 0.00 0 2106 24 0 0
t$perf-norm:sp_client_order 18211 18211 0 0.00 0 9682 36 0 0
t$perf-norm:sp_customer_reserve 909 909 0 0.00 0 5956 21 0 0
t$perf-norm:sp_pay_from_customer 6210 6210 0 0.00 0 10116 28 0 0
t$perf-norm:sp_pay_to_supplier 5353 5353 0 0.00 0 2721 18 0 0
t$perf-norm:sp_reserve_write_off 8848 8848 0 0.00 0 5383 23 0 0
t$perf-norm:sp_supplier_invoice 4834 4834 0 0.00 0 7413 32 0 0
t$perf-norm:sp_supplier_order 4888 4888 0 0.00 0 10501 34 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 273 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 1132 21 0 0
t$perf-norm:srv_make_invnt_saldo 451 451 0 0.00 0 744 18 0 0
t$perf-norm:srv_make_money_saldo 781 781 0 0.00 0 8838 19 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 11 174 63 0 0
v_add_invoice_to_stock 9020 8858 162 1.79 0 6376 29 0 162
v_all_customers 33318 33318 0 0.00 0 65 4 0 0
v_all_suppliers 27892 27892 0 0.00 0 57 4 0 0
v_all_wares 36422 36422 0 0.00 0 72 4 0 0
v_cancel_adding_invoice 682 682 0 0.00 0 741 12 0 0
v_cancel_client_order 3038 3038 0 0.00 0 6677 16 0 0
v_cancel_customer_prepayment 1304 1304 0 0.00 0 9706 20 0 0
v_cancel_customer_reserve 1306 1306 0 0.00 0 2129 14 0 0
v_cancel_payment_to_supplier 724 724 0 0.00 0 3532 20 0 0
v_cancel_supplier_invoice 748 742 6 0.80 0 4495 29 0 6
v_cancel_supplier_order 746 746 0 0.00 0 211 9 0 0
v_cancel_write_off 1702 1702 0 0.00 0 5110 29 0 0
v_max_id_clo_ord 4887 4887 0 0.00 0 4166 6 0 0
v_max_id_clo_res 909 909 0 0.00 0 1704 15 0 0
v_max_id_ord_sup 4830 4830 0 0.00 0 5835 8 0 0
v_max_non_paid_invoice 1124 1124 0 0.00 0 57 7 0 0
v_max_non_paid_realizn 3308 3308 0 0.00 0 1438 9 0 0
v_min_id_clo_ord 4887 4887 0 0.00 0 4329 9 0 0
v_min_id_clo_res 909 909 0 0.00 0 2525 11 0 0
v_min_id_ord_sup 4830 4830 0 0.00 0 5591 9 0 0
v_min_non_paid_invoice 5352 5352 0 0.00 0 6119 18 0 0
v_min_non_paid_realizn 6210 6210 0 0.00 0 5263 17 0 0
v_our_firm 8908 8908 0 0.00 0 138 4 0 0
v_reserve_write_off 17696 17696 0 0.00 0 6721 14 0 0
x_lock_depdocs_on_canc_invoice 10645 10645 0 0.00 0 8798 128 0 0
x_lock_depdocs_on_canc_sup_ord 373 373 0 0.00 0 3643 413 0 0
x_make_qstorno_1000_1200 4887 4887 0 0.00 2 28722 1331 0 0
x_make_qstorno_1200_2000 4834 4834 0 0.00 5 77628 3217 0 0
x_make_qstorno_2100_3300 429089 429089 0 0.00 0 52522 225 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 19-12-2022 10:39:09 to 19-12-2022 10:39:13.

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
08:07:25 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
08:08:26 24293 8192000000 9719345984 10016595968 794018064 8.836 100 90 181 0 824094464 7887872 22188528
08:09:58 23142 8192000000 9753582576 10060406784 828888432 9.188 100 90 180 0 863438176 12712384 21837360
08:11:29 10217 8192000000 9795062960 10111176704 861477152 9.515 100 82 163 0 898935056 18431936 19025968
08:12:48 10936 8192000000 9871919232 10203123712 915071712 10.047 100 86 170 0 980405840 46078464 19255664
08:14:03 5891 8192000000 9908611888 10247626752 941957952 10.312 100 87 173 2 1024721424 63680976 19082496
08:15:14 3074 8192000000 9963872160 10310569984 977406672 10.659 100 91 180 1 1080001344 83835744 18758928
08:16:21 1424 8192000000 9984502736 10342805504 1005576048 10.933 100 86 163 2 1096761808 74946128 16239632
08:17:25 970 8192000000 9988724128 10356973568 1032344240 11.191 100 96 188 1 1111795264 60521456 18929568
08:18:28 1045 8192000000 10023024560 10389127168 1046935232 11.331 100 100 199 1 1142329664 75867280 19527152
08:19:31 1036 8192000000 10034593328 10411282432 1055897408 11.417 100 96 190 1 1157599648 81886352 19815888
08:20:36 1130 8192000000 10044246032 10420162560 1062838960 11.484 100 99 194 1 1169297104 86865040 19593104
08:21:39 1070 8192000000 10051970464 10426785792 1068838928 11.541 100 95 178 3 1175820416 88311664 18669824
08:22:42 1180 8192000000 10035684928 10418065408 1077484928 11.623 100 97 189 0 1159475984 62383984 19607072
08:23:45 1309 8192000000 10063012832 10448953344 1086807984 11.712 100 95 182 0 1185189504 79745520 18636000
08:24:48 1400 8192000000 10077851856 10468261888 1097515392 11.814 100 97 191 0 1205379840 88427184 19437264
08:25:53 1264 8192000000 10077140256 10464641024 1102948784 11.866 100 99 195 0 1201877488 79155728 19772976
08:26:56 1179 8192000000 10099344912 10486423552 1101162816 11.849 100 97 190 2 1218249200 98006336 19080048
08:27:58 1297 8192000000 10045815040 10435428352 1099106624 11.829 100 84 161 1 1173934816 59441808 15386384
08:29:02 3899 8192000000 10059675072 10444627968 1091130080 11.753 100 88 168 0 1192531424 84031472 17369872
08:30:07 804 8192000000 10045443008 10432106496 1089218976 11.735 100 89 171 1 1165674048 58660912 17794160
08:31:10 1159 8192000000 10039102608 10425126912 1072245456 11.574 100 95 182 3 1167815312 75899312 19670544
08:32:12 1192 8192000000 10039784080 10421977088 1074552848 11.596 100 93 178 0 1165049312 71412144 19084320
08:33:15 3369 8192000000 10011847136 10396958720 1036741984 11.233 99 72 137 2 1129469296 78296672 14430640
08:34:19 1184 8192000000 10015762832 10393100288 1043242528 11.296 100 87 165 4 1139863792 78014608 18606656
08:35:22 1202 8192000000 10004287968 10388000768 1053304016 11.392 100 93 174 1 1127848224 56899984 17644224
08:36:24 1299 8192000000 10009348096 10383970304 1044309008 11.306 100 87 169 0 1130351296 69667136 16375152
08:37:31 34007 8192000000 9985877168 10369245184 1050488704 11.365 100 50 93 2 1113144144 53978272 8677168
08:39:12 6242 8192000000 10027225184 10411642880 1070715152 11.559 100 75 146 3 1152001968 66849776 14437040
08:40:23 8624 8192000000 10050514912 10440384512 1079508416 11.643 100 79 150 1 1172069984 76204960 16356608
08:41:36 14762 8192000000 10101808080 10489982976 1101829104 11.855 100 67 129 2 1215939120 100661984 13448032
08:42:55 4693 8192000000 10124766320 10521018368 1111343280 11.945 100 70 134 2 1248576160 121673152 15559728
08:44:04 2326 8192000000 10158265712 10556293120 1126306192 12.087 100 81 142 1 1275383040 133898752 15178096
08:45:15 940 8192000000 10193267600 10588618752 1137277952 12.190 100 75 143 2 1308888208 156025184 15585072
08:46:21 1521 8192000000 10227440560 10617929728 1144221424 12.255 100 88 168 1 1337207952 174843968 18142560
08:47:27 2910 8192000000 10249153840 10651516928 1154282960 12.350 100 67 129 0 1368084192 199359664 14441568
08:48:33 3290 8192000000 10285310784 10686562304 1157847632 12.383 100 75 143 1 1390164592 216082880 16234080
08:49:43 1007 8192000000 10333302912 10735534080 1170929872 12.506 100 97 190 0 1438032752 246087920 21014960
08:50:48 1203 8192000000 10385756976 10782347264 1184870944 12.636 100 89 173 0 1488231008 283061472 20298592
08:51:53 1844 8192000000 10403869488 10829074432 1193223824 12.713 100 92 180 0 1514713184 300810016 20679344
08:53:01 2170 8192000000 10449536880 10862325760 1200585936 12.782 100 94 179 1 1557248752 335490864 21171952
08:54:07 929 8192000000 10498568448 10899398656 1198836800 12.766 100 90 164 0 1604629088 385834896 19957392
08:55:10 1236 8192000000 10556957104 10984419328 1218105664 12.944 100 97 192 0 1664948848 423516080 23327104
08:56:16 833 8192000000 10594003824 11007561728 1215156400 12.917 100 100 191 1 1699555408 461871440 22527568
08:57:21 1538 8192000000 10642391504 11083706368 1222284096 12.983 100 98 195 2 1746766112 500629024 23852992
08:58:25 1430 8192000000 10656989984 11096264704 1229796224 13.052 100 98 194 0 1755310592 502131712 23382656
08:59:30 1586 8192000000 10643924688 11083718656 1235550384 13.105 100 88 174 0 1750573552 493673328 21349840
09:00:35 4498 8192000000 10663480976 11118374912 1230827920 13.062 100 62 124 0 1763020544 515857024 16335600
09:01:49 11101 8192000000 10678714720 11132649472 1217899504 12.942 100 52 104 0 1784814640 552736688 14178448
09:03:11 12055 8192000000 10681189408 11147231232 1190557920 12.689 99 53 106 0 1779506768 574912384 14036464
09:04:24 9667 8192000000 10639572304 11109011456 1180653472 12.596 100 43 86 0 1746947744 554444096 11850176
09:06:39 20431 8192000000 10469836000 11001475072 1157315024 12.378 100 33 66 0 1606034768 440083520 8636224
09:08:02 23753 8192000000 10252135456 10708127744 1153984576 12.347 100 21 42 0 1356271536 197637424 4649536
09:09:54 1958 8192000000 10144443536 10626953216 1166087152 12.460 100 67 123 1 1244840512 65641712 13111648
09:10:58 842 8192000000 10163383488 10574123008 1157269808 12.378 100 96 190 3 1273175456 94215600 21690048
09:12:00 71670 8192000000 10156807600 10624311296 1156232400 12.368 99 44 88 0 1283986608 118649504 9104704
09:14:16 60521 8192000000 10106788000 10597515264 1135839008 12.176 98 22 40 1 1221329648 80797968 4692672
09:16:18 52267 8192000000 10087564224 10552303616 1143587712 12.249 99 44 84 0 1202664576 49716832 9360032
09:18:19 35156 8192000000 10097845472 10544934912 1154211040 12.349 100 25 47 0 1197187520 38059072 4917408
09:19:56 38336 8192000000 10120055152 10574413824 1142571120 12.240 99 34 68 0 1240313600 90526672 7215808
09:22:24 86592 8192000000 10110032320 10614566912 1135241504 12.171 98 29 58 0 1198773952 57211984 6320464
09:24:52 27842 8192000000 10163450144 10627358720 1162808544 12.430 99 27 52 0 1246620240 77647488 6164208
09:26:38 73819 8192000000 10148345600 10665562112 1163708880 12.438 100 10 20 0 1267985696 102040240 2236576
09:28:53 99409 8192000000 10089628336 10576949248 1123868640 12.064 97 23 46 0 1162736704 33857776 5010288
09:31:39 72547 8192000000 10062282464 10535735296 1105599072 11.891 97 37 73 1 1152538336 38267856 8671408
09:34:07 66156 8192000000 10069186288 10554945536 1104551008 11.881 97 37 74 1 1181880896 69216816 8113072
09:36:23 86411 8192000000 10082038288 10561470464 1123682496 12.062 98 29 57 0 1178710240 49206928 5820816
09:38:50 91497 8192000000 10108776960 10604212224 1138913120 12.205 99 12 19 0 1204510800 64197488 1400192
09:41:25 109097 8192000000 10066166240 10520526848 1119776400 12.025 98 14 27 1 1156766768 34258976 2731392
09:44:14 47722 8192000000 10095961984 10572193792 1136157216 12.179 99 25 48 0 1185751664 44506096 5088352
09:46:26 117186 8192000000 10189565936 10658373632 1149141344 12.301 100 46 92 0 1275530336 116881184 9507808
09:50:13 80583 8192000000 10100637056 10614919168 1134940208 12.168 99 14 28 0 1243020928 104647248 3433472
09:53:08 61603 8192000000 10050268592 10525368320 1125325120 12.077 99 12 24 1 1167884384 40084512 2474752
09:55:11 71008 8192000000 10051010448 10524524544 1110742064 11.939 98 13 24 0 1137287248 24468880 2076304
09:57:28 51432 8192000000 10083881424 10531770368 1110723600 11.939 98 13 24 0 1156639232 43252768 2662864
09:59:21 30797 8192000000 10095208096 10536198144 1126666160 12.090 99 27 54 0 1176581760 44457072 5458528
10:00:56 60749 8192000000 10061411376 10545917952 1120406896 12.031 99 15 26 0 1184468560 61392192 2669472
10:03:43 17183 8192000000 10131331232 10571096064 1136768688 12.185 100 44 88 0 1227146288 81569024 8808576
10:05:35 56227 8192000000 10079905216 10522914816 1084858528 11.694 96 36 72 0 1154386336 62460032 7067776
10:07:35 56686 8192000000 10074507632 10528309248 1071469712 11.566 95 40 80 0 1149246304 69839440 7937152
10:09:33 29185 8192000000 10110325728 10544713728 1103199008 11.868 98 47 91 1 1194347408 82338640 8809760
10:11:02 85364 8192000000 10082967664 10542223360 1127874656 12.101 100 18 36 1 1189284112 58262560 3146896
10:13:51 44023 8192000000 10060636656 10535542784 1107552848 11.909 98 35 70 0 1189543392 75375904 6614640
10:15:36 44378 8192000000 10070872400 10554576896 1106433168 11.899 99 9 18 0 1175043408 66664784 1945456
10:17:20 57973 8192000000 10060286480 10539921408 1105460944 11.889 98 21 39 0 1151560896 41920768 4179184
10:19:39 57816 8192000000 10098744048 10577985536 1124986752 12.074 99 30 58 0 1182156912 50687904 6482256
10:22:06 34449 8192000000 10137904704 10592133120 1127858768 12.101 99 38 76 0 1235097040 98598448 8639824
10:23:42 36744 8192000000 10128045952 10565042176 1111904560 11.950 98 28 56 0 1218868400 100998352 5965488
10:25:21 44211 8192000000 10097214368 10547871744 1120293872 12.030 99 20 40 0 1203817696 79069328 4454496
10:27:12 79084 8192000000 10069304192 10521231360 1088387088 11.727 96 25 48 0 1143521760 50056736 5077936
10:29:32 35769 8192000000 10151193840 10593107968 1115468432 11.984 99 45 89 0 1225261664 100729904 9063328
10:31:12 114358 8192000000 10086654256 10534334464 1092485056 11.766 97 28 56 0 1155454528 57363840 5605632
10:34:12 38813 8192000000 10071986128 10523611136 1133977584 12.159 100 9 17 0 1183279792 47135248 2166960
10:35:51 72165 8192000000 10076524944 10575175680 1082274704 11.669 95 13 26 0 1151480016 66309824 2895488

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 19-12-2022 10:39:15 to 19-12-2022 10:39:30.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 81 v_add_invoice_to_stock 335544517
except 3 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 19-12-2022 10:39:31 to 19-12-2022 10:39:33.

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          202594
MON$OLDEST_ACTIVE               203993
MON$OLDEST_SNAPSHOT             203993
MON$NEXT_TRANSACTION            203993
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                       2036064
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             751
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2885 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2885 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2885 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2885 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 ---
:215: 
:216: mscs-test-161.budzdorov.ru    Mon Dec 19 10:37:23 2022
:217:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:218: 
:219: 
:220: mscs-test-161.budzdorov.ru    Mon Dec 19 10:37:24 2022
:221:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:222: 
--- end of diff output ---

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

19.12.22 10:39:34. 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-12-19 10:39:37 No severe PSQL-related problems occured
19.12.22 10:39:38. End of report.