Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 28.05.2022 07: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 16006344 kB
MemAvailable 31408236 kB
Buffers 0 kB
Cached 15724680 kB
SwapCached 2452 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-28 07:37:17
DTS_END                         2022-05-28 07:37:17.0300
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_RESERVE_WRITE_OFF(34:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/53994
TRN_ID                          221809
ATT_ID                          663
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: 9069.00 26028 1088280
customer order: creation 171.43 482 20571
customer order: refuse 14.91 376 1789
order to supplier: creation 44.27 1391 5312
order to supplier: removal 3.46 169247 415
invoice (draft): creation 44.93 3222 5391
invoice (draft): removal 51.46 1004 6175
invoice accept: apply 40.68 26665 4882
invoice accept: cancel 46.15 1257 5538
customer reserve: creation 3856.18 248 462741
customer reserve: removal 4500.39 101 540047
realization accept: apply 82.09 285 9851
realization accept: cancel 84.38 177 10125
payment from customer: creation 58.26 171 6991
payment from customer: removal 5.68 110 682
payment to supplier: creation 48.73 185 5848
payment to supplier: removal 3.25 130 390
service: total inventory turnovers 4.16 4272 499
service: total monetary turnovers 7.70 344 924
service: aggregate perf. data 0.86 19723 103
service: refresh index statistics 0.05 291178 6

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 28-05-2022 07:38:52 to 28-05-2022 07:38:54.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5603
TEST_TIME 2 3984
TEST_TIME 3 3743
TEST_TIME 4 4541
TEST_TIME 5 4676
TEST_TIME 6 5930
TEST_TIME 7 6047
TEST_TIME 8 5968
TEST_TIME 9 6289
TEST_TIME 10 6745
TEST_TIME 11 6664
TEST_TIME 12 6741
TEST_TIME 13 6891
TEST_TIME 14 7072
TEST_TIME 15 7333
TEST_TIME 16 7244
TEST_TIME 17 7273
TEST_TIME 18 7605
TEST_TIME 19 7624
TEST_TIME 20 7880
TEST_TIME 21 7957
TEST_TIME 22 8238
TEST_TIME 23 8363
TEST_TIME 24 8300
TEST_TIME 25 8289
TEST_TIME 26 8429
TEST_TIME 27 8505
TEST_TIME 28 8727
TEST_TIME 29 9388
TEST_TIME 30 9677
TEST_TIME 31 9725
TEST_TIME 32 10215
TEST_TIME 33 10547
TEST_TIME 34 10695
TEST_TIME 35 10885
TEST_TIME 36 11068
TEST_TIME 37 11017
TEST_TIME 38 10817
TEST_TIME 39 10807
TEST_TIME 40 10657
TEST_TIME 41 10526
TEST_TIME 42 10486
TEST_TIME 43 10448
TEST_TIME 44 10401
TEST_TIME 45 10349
TEST_TIME 46 10232
TEST_TIME 47 10203
TEST_TIME 48 10241
TEST_TIME 49 10311
TEST_TIME 50 10224
TEST_TIME 51 10091
TEST_TIME 52 9953
TEST_TIME 53 9927
TEST_TIME 54 9918
TEST_TIME 55 9786
TEST_TIME 56 9711
TEST_TIME 57 9708
TEST_TIME 58 9651
TEST_TIME 59 9649
TEST_TIME 60 9750
TEST_TIME 61 9616
TEST_TIME 62 9585
TEST_TIME 63 9720
TEST_TIME 64 9633
TEST_TIME 65 9644
TEST_TIME 66 9662
TEST_TIME 67 9613
TEST_TIME 68 9560
TEST_TIME 69 9660
TEST_TIME 70 9675
TEST_TIME 71 9647
TEST_TIME 72 9637
TEST_TIME 73 9667
TEST_TIME 74 9653
TEST_TIME 75 9720
TEST_TIME 76 9778
TEST_TIME 77 9765
TEST_TIME 78 9730
TEST_TIME 79 9831
TEST_TIME 80 9770
TEST_TIME 81 9757
TEST_TIME 82 9699
TEST_TIME 83 9653
TEST_TIME 84 9621
TEST_TIME 85 9610
TEST_TIME 86 9553
TEST_TIME 87 9526
TEST_TIME 88 9497
TEST_TIME 89 9470
TEST_TIME 90 9525
TEST_TIME 91 9460
TEST_TIME 92 9407
TEST_TIME 93 9374
TEST_TIME 94 9341
TEST_TIME 95 9311
TEST_TIME 96 9273
TEST_TIME 97 9220
TEST_TIME 98 9182
TEST_TIME 99 9150
TEST_TIME 100 9139
TEST_TIME 101 9186
TEST_TIME 102 9142
TEST_TIME 103 9183
TEST_TIME 104 9210
TEST_TIME 105 9220
TEST_TIME 106 9199
TEST_TIME 107 9239
TEST_TIME 108 9222
TEST_TIME 109 9177
TEST_TIME 110 9201
TEST_TIME 111 9373
TEST_TIME 112 9348
TEST_TIME 113 9311
TEST_TIME 114 9255
TEST_TIME 115 9234
TEST_TIME 116 9187
TEST_TIME 117 9177
TEST_TIME 118 9135
TEST_TIME 119 9153
TEST_TIME 120 9088

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 28-05-2022 07:38:55 to 28-05-2022 07:38:59.

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 1513870 1513870 0 0.00 0 25997 13 0 0
doc_list_biud 1513869 1513869 0 0.00 0 32522 42 0 0
sp_add_invoice_to_stock 5029 4882 147 2.92 79 188900 26665 0 147
sp_cancel_adding_invoice 5538 5538 0 0.00 7 71745 1258 0 0
sp_cancel_client_order 1789 1789 0 0.00 6 7203 376 0 0
sp_cancel_customer_reserve 540047 540047 0 0.00 1 32157 102 0 0
sp_cancel_pay_from_customer 682 682 0 0.00 3 3381 110 0 0
sp_cancel_pay_to_supplier 390 390 0 0.00 4 5364 131 0 0
sp_cancel_supplier_invoice 6179 6175 4 0.06 3 32707 1005 0 4
sp_cancel_supplier_order 415 415 0 0.00 10 1456750 169248 0 0
sp_cancel_write_off 10125 10125 0 0.00 2 16301 178 0 0
sp_client_order 20571 20571 0 0.00 6 29820 483 0 0
sp_customer_reserve 462741 462741 0 0.00 1 109218 248 0 0
sp_fill_shopping_cart 31264 31264 0 0.00 2 20201 83 0 0
sp_fill_shopping_cart_clo_res 462722 462722 0 0.00 0 18240 20 0 0
sp_get_clo_for_invoice 4849 4849 0 0.00 0 19639 305 0 0
sp_kill_cost_storno 16735 16735 0 0.00 0 16297 80 0 0
sp_kill_qstorno_ret_qs2qd 1096809 1096809 0 0.00 0 32395 43 0 0
sp_kill_qty_storno 578788 578788 0 0.00 0 32521 101 0 0
sp_lock_selected_doc 20448 20448 0 0.00 0 11449 8 0 0
sp_make_cost_storno 27538 27538 0 0.00 0 12600 88 0 0
sp_multiply_rows_for_pdistr 27538 27538 0 0.00 0 9331 37 0 0
sp_multiply_rows_for_qdistr 20571 20571 0 0.00 0 13051 178 0 0
sp_pay_from_customer 6991 6991 0 0.00 5 10284 172 0 0
sp_pay_to_supplier 5848 5848 0 0.00 6 14411 185 0 0
sp_payment_common 12839 12839 0 0.00 2 8284 49 0 0
sp_qd_handle_on_cancel_clo 1789 1789 0 0.00 0 7195 304 0 0
sp_qd_handle_on_invoice_upd_sts 10386 10386 0 0.00 0 32519 547 0 0
sp_qd_handle_on_reserve_upd_sts 9851 9851 0 0.00 0 12552 69 0 0
sp_reserve_write_off 9851 9851 0 0.00 8 12827 286 0 0
sp_supplier_invoice 5391 5391 0 0.00 11 88885 3222 0 0
sp_supplier_order 5312 5312 0 0.00 9 28011 1391 0 0
srv_aggregate_perf_data 103 103 0 0.00 2225 219550 19724 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 758 144157 36432 0 0
srv_find_qd_qs_mism 1039385 1039385 0 0.00 0 25803 10 0 0
srv_make_invnt_saldo 499 499 0 0.00 84 132345 4272 0 0
srv_make_money_saldo 924 924 0 0.00 1 11530 345 0 0
srv_recalc_idx_stat 6 6 0 0.00 109367 444921 291179 0 0
t$perf-abend:sp_add_invoice_to_stock 147 147 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 4 4 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4882 4882 0 0.00 1 12285 1000 0 0
t$perf-norm:sp_cancel_adding_invoice 399 399 0 0.00 1 6867 480 0 0
t$perf-norm:sp_cancel_client_order 1789 1789 0 0.00 0 4360 28 0 0
t$perf-norm:sp_cancel_customer_reserve 678 678 0 0.00 0 6608 40 0 0
t$perf-norm:sp_cancel_pay_from_customer 682 682 0 0.00 0 2210 17 0 0
t$perf-norm:sp_cancel_pay_to_supplier 390 390 0 0.00 0 3413 31 0 0
t$perf-norm:sp_cancel_supplier_invoice 416 416 0 0.00 0 1116 37 0 0
t$perf-norm:sp_cancel_supplier_order 415 415 0 0.00 0 101654 7408 0 0
t$perf-norm:sp_cancel_write_off 980 980 0 0.00 0 1684 21 0 0
t$perf-norm:sp_client_order 20571 20571 0 0.00 0 7752 36 0 0
t$perf-norm:sp_customer_reserve 1015 1015 0 0.00 0 632 13 0 0
t$perf-norm:sp_pay_from_customer 6991 6991 0 0.00 0 3611 22 0 0
t$perf-norm:sp_pay_to_supplier 5848 5848 0 0.00 0 3180 20 0 0
t$perf-norm:sp_reserve_write_off 9851 9851 0 0.00 0 4510 23 0 0
t$perf-norm:sp_supplier_invoice 5391 5391 0 0.00 0 13281 33 0 0
t$perf-norm:sp_supplier_order 5312 5312 0 0.00 0 8598 32 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 242 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 505 14 0 0
t$perf-norm:srv_make_invnt_saldo 499 499 0 0.00 0 438 12 0 0
t$perf-norm:srv_make_money_saldo 924 924 0 0.00 0 2087 7 0 0
t$perf-norm:srv_recalc_idx_stat 6 6 0 0.00 4 184 101 0 0
v_add_invoice_to_stock 9990 9696 294 2.94 0 5737 25 0 294
v_all_customers 37642 37642 0 0.00 0 215 4 0 0
v_all_suppliers 30256 30256 0 0.00 0 121 4 0 0
v_all_wares 41142 41142 0 0.00 0 119 4 0 0
v_cancel_adding_invoice 798 798 0 0.00 0 2897 19 0 0
v_cancel_client_order 3578 3578 0 0.00 0 4374 12 0 0
v_cancel_customer_prepayment 1364 1364 0 0.00 0 954 11 0 0
v_cancel_customer_reserve 1356 1356 0 0.00 0 583 10 0 0
v_cancel_payment_to_supplier 780 780 0 0.00 0 868 12 0 0
v_cancel_supplier_invoice 840 832 8 0.95 0 1271 30 0 8
v_cancel_supplier_order 830 830 0 0.00 0 2849 22 0 0
v_cancel_write_off 1960 1960 0 0.00 0 2984 25 0 0
v_max_id_clo_ord 5312 5312 0 0.00 0 1943 5 0 0
v_max_id_clo_res 1015 1015 0 0.00 0 2458 16 0 0
v_max_id_ord_sup 5379 5379 0 0.00 0 5187 8 0 0
v_max_non_paid_invoice 1411 1411 0 0.00 0 317 8 0 0
v_max_non_paid_realizn 3517 3517 0 0.00 0 751 7 0 0
v_min_id_clo_ord 5312 5312 0 0.00 0 1921 10 0 0
v_min_id_clo_res 1015 1015 0 0.00 0 4541 20 0 0
v_min_id_ord_sup 5379 5379 0 0.00 0 5846 10 0 0
v_min_non_paid_invoice 5848 5848 0 0.00 0 7080 19 0 0
v_min_non_paid_realizn 6991 6991 0 0.00 0 8115 18 0 0
v_our_firm 10448 10448 0 0.00 0 75 5 0 0
v_reserve_write_off 19702 19702 0 0.00 0 4514 10 0 0
x_lock_depdocs_on_canc_invoice 11297 11297 0 0.00 0 13887 122 0 0
x_lock_depdocs_on_canc_sup_ord 415 415 0 0.00 0 3989 356 0 0
x_make_qstorno_1000_1200 5312 5312 0 0.00 3 27991 1219 0 0
x_make_qstorno_1200_2000 5391 5391 0 0.00 5 88582 2999 0 0
x_make_qstorno_2100_3300 462741 462741 0 0.00 0 109204 216 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 28-05-2022 07:39:00 to 28-05-2022 07:39:04.

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
05:07:20 2 8192000000 8796669024 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
05:08:21 32284 8192000000 9717246736 10013159424 793878656 8.834 100 85 170 0 822347344 7729984 20738704
05:09:56 27340 8192000000 9756676896 10060361728 829866944 9.198 100 91 183 0 865500272 14111664 21521664
05:11:32 14468 8192000000 9801958224 10119389184 861962704 9.520 100 80 160 0 905341968 24891520 18487744
05:12:52 12130 8192000000 9863285744 10196762624 915525536 10.052 100 77 154 0 972129536 39550240 17053760
05:14:09 2575 8192000000 9922231920 10263093248 965026864 10.538 100 93 186 0 1038703040 54697824 18978352
05:15:14 2192 8192000000 9982435824 10333605888 996387232 10.843 100 91 181 2 1100720784 85845808 18487744
05:16:20 1307 8192000000 10009944672 10371280896 1029560736 11.164 100 94 186 1 1126035520 75980192 20494592
05:17:23 952 8192000000 10022425312 10390966272 1046068704 11.323 100 94 188 2 1141458448 75507408 19882336
05:18:26 1196 8192000000 10035068640 10408394752 1054168336 11.401 100 94 182 1 1152364160 80325152 17870672
05:19:29 1249 8192000000 10037643840 10417246208 1063852016 11.493 100 92 183 0 1161555440 78694400 19009024
05:20:32 1233 8192000000 10049494976 10429321216 1073086304 11.582 100 96 190 0 1172004384 79513248 19404832
05:21:34 5481 8192000000 10037690832 10424184832 1076123056 11.611 100 67 132 1 1173173632 84323424 12727152
05:22:41 10488 8192000000 10034440208 10423148544 1081480960 11.662 100 57 112 1 1157521168 63794928 12245280
05:23:52 1136 8192000000 10038583968 10426515456 1091704192 11.759 100 90 168 2 1160641536 50971488 17965856
05:24:54 1163 8192000000 10067390416 10459406336 1097986352 11.819 100 97 190 0 1189206720 72059968 19160400
05:25:56 1289 8192000000 10065878288 10459983872 1100052192 11.838 100 97 188 0 1189059808 69649312 19358304
05:26:58 1329 8192000000 10077510176 10469445632 1103919808 11.875 100 94 189 2 1196476592 74038048 18518736
05:28:01 1162 8192000000 10067540096 10454814720 1098931760 11.828 100 97 186 1 1193270992 75145984 19193248
05:29:03 1290 8192000000 10069072592 10451398656 1088222784 11.726 100 97 186 1 1187798752 80184880 19391088
05:30:06 1881 8192000000 10033041248 10424676352 1075231104 11.602 99 81 157 1 1149906288 58359920 16315264
05:31:09 825 8192000000 10032490000 10420252672 1070227408 11.554 100 81 157 0 1154308864 68352432 15729024
05:32:11 1464 8192000000 10018608960 10398281728 1051575856 11.376 99 83 159 2 1143377232 74501440 17299936
05:33:14 1293 8192000000 9990569504 10366599168 1038919216 11.254 100 89 160 4 1116513920 60341696 17253008
05:34:16 1134 8192000000 9968875216 10338283520 1025775152 11.128 100 91 172 2 1091168688 48581648 16811888
05:35:18 1190 8192000000 9983529792 10354757632 1026650640 11.136 99 90 167 2 1104479072 60575952 17252480
05:36:20 1135 8192000000 9989993936 10362662912 1033533472 11.202 100 95 180 0 1112237632 58484288 20219872
05:37:22 2417 8192000000 9978326992 10355875840 1045886800 11.321 100 80 128 7 1096205536 39019072 11299664
05:38:34 9069 8192000000 9992977104 10369622016 1068297568 11.536 100 75 131 5 1109418368 27479056 13641744
05:39:53 7773 8192000000 10040331840 10427121664 1089029120 11.733 100 68 126 1 1162997152 61772608 12195424
05:41:06 8974 8192000000 10089917856 10479517696 1097522480 11.814 100 79 152 1 1212549328 98909456 16117392
05:42:25 1957 8192000000 10121472464 10506686464 1110901232 11.941 100 85 163 0 1240990400 112909056 17180112
05:43:34 1124 8192000000 10167101328 10570543104 1125658544 12.080 100 96 188 0 1287635136 142055712 19920880
05:44:38 1165 8192000000 10154504960 10548346880 1128322448 12.106 100 89 167 1 1268355280 121461920 18570912
05:45:44 2414 8192000000 10185918848 10585374720 1140746096 12.223 100 84 162 0 1300651344 142408560 17496688
05:46:50 1116 8192000000 10204715616 10621857792 1153526368 12.343 100 90 180 0 1322459952 147865648 21067936
05:47:55 9622 8192000000 10221757152 10624589824 1172792000 12.523 100 77 144 4 1332827568 145601792 14433776
05:49:07 821 8192000000 10271637168 10677600256 1181960848 12.608 100 89 169 2 1388086064 187019360 19105856
05:50:12 1303 8192000000 10331893744 10738196480 1194311808 12.723 100 87 164 0 1443474224 230248576 18913840
05:51:19 1067 8192000000 10342243872 10763411456 1206322832 12.835 100 96 185 0 1456096112 229319216 20454064
05:52:22 1271 8192000000 10396330688 10806693888 1215973872 12.924 100 94 186 1 1509427568 272200464 21253232
05:53:27 920 8192000000 10430521072 10835415040 1205731712 12.830 100 96 188 0 1536437072 308252384 22452976
05:54:32 1398 8192000000 10434433616 10871914496 1199878848 12.775 100 89 173 1 1541361120 321047536 20434736
05:55:37 1026 8192000000 10453481280 10894860288 1183538544 12.623 100 91 178 1 1559785296 353593440 22653312
05:56:42 2480 8192000000 10451013744 10888802304 1186841904 12.654 100 78 153 1 1559379936 354079584 18458448
05:57:52 1310 8192000000 10453254832 10902007808 1202518016 12.800 100 94 182 0 1560234016 335419728 22296272
05:58:57 2931 8192000000 10509357696 10953158656 1208730416 12.857 100 97 189 1 1611802928 380478640 22593872
06:00:03 2208 8192000000 10490522224 10929111040 1204970880 12.822 100 75 148 0 1597255952 374341696 17943376
06:01:08 1805 8192000000 10486676128 10923679744 1194818656 12.728 99 80 156 0 1590708000 377348512 18540832
06:02:16 754 8192000000 10573344320 10991157248 1213917664 12.905 100 89 177 0 1676139760 440308416 21913680
06:03:19 3654 8192000000 10620242048 11050602496 1209464592 12.864 100 73 146 0 1726975904 498664976 18846336
06:04:31 1232 8192000000 10575248096 11022422016 1202523072 12.800 100 76 153 1 1675178448 453575216 19080160
06:05:34 11937 8192000000 10573071600 11058503680 1190344288 12.687 99 53 106 0 1679429568 475135792 13949488
06:06:55 12496 8192000000 10480324000 10947538944 1177834288 12.570 99 38 76 0 1565812832 378134144 9844400
06:08:37 23904 8192000000 10432297232 10902831104 1157190864 12.377 99 31 62 0 1535311056 369613776 8506416
06:10:02 30341 8192000000 10309057968 10854338560 1127174384 12.095 98 24 48 0 1417889040 284627392 6087264
06:11:33 7554 8192000000 10225473376 10687336448 1142654992 12.240 100 54 101 4 1322762080 169460368 10646720
06:13:00 32629 8192000000 10128489424 10577625088 1142459920 12.239 100 32 63 2 1233899840 85271968 6167952
06:15:04 65449 8192000000 10085234496 10520150016 1088398928 11.727 95 37 71 0 1123722016 28589712 6733376
06:17:20 79505 8192000000 10052069408 10496954368 1062322512 11.479 94 18 35 1 1095061056 29608832 3129712
06:19:44 30126 8192000000 10078166256 10545410048 1125798432 12.082 100 17 34 0 1162994304 33729280 3466592
06:21:16 119726 8192000000 10097263936 10552086528 1084115376 11.687 96 16 32 2 1131452560 43792416 3544768
06:24:22 37194 8192000000 10116362784 10565636096 1122963296 12.055 99 18 36 0 1204427616 77772336 3691984
06:26:27 78453 8192000000 10053252864 10486194176 1067998832 11.533 94 16 30 1 1106575248 35560960 3015456
06:28:46 92929 8192000000 10047065232 10483642368 1109145648 11.924 99 33 57 0 1146404752 30482864 6776240
06:31:19 1660 8192000000 10069330288 10481934336 1112645536 11.957 100 76 141 2 1158295168 31385248 14264384
06:32:26 60455 8192000000 10100694640 10524753920 1123443136 12.060 99 38 71 2 1168869840 37269584 8157120
06:34:28 48580 8192000000 10154508880 10599698432 1137935424 12.196 99 31 62 1 1242961840 98139888 6886528
06:36:23 31863 8192000000 10195315840 10656817152 1135900016 12.177 99 61 119 2 1294708656 144578272 14230368
06:38:06 60666 8192000000 10164484656 10611015680 1141578448 12.230 100 12 24 0 1249625984 105681488 2366048
06:40:49 88062 8192000000 10097213376 10565459968 1127357552 12.096 98 13 26 0 1180531488 50225152 2948784
06:43:27 48212 8192000000 10109954288 10555060224 1146215776 12.274 100 23 44 2 1171315120 20648720 4450624
06:45:20 49441 8192000000 10124260960 10587783168 1110896240 11.941 96 32 63 1 1192769760 73582320 8291200
06:47:14 45477 8192000000 10078373360 10521972736 1110412128 11.936 97 26 51 0 1154755600 39644448 4699024
06:49:01 12188 8192000000 10125808768 10549215232 1135606448 12.174 99 81 153 5 1213311376 61936592 15768336
06:50:29 58804 8192000000 10133685840 10563731456 1103595552 11.872 97 37 74 0 1191448480 79449168 8403760
06:52:32 103900 8192000000 10101087280 10583670784 1088602864 11.729 96 14 28 1 1161912080 70144944 3164272
06:55:16 85203 8192000000 10108096448 10623766528 1120095616 12.028 99 27 54 1 1209679936 84406288 5178032
06:57:52 32735 8192000000 10099363632 10527821824 1116294496 11.992 99 41 82 0 1164978896 39980480 8703920
06:59:25 144156 8192000000 10075679392 10532335616 1110358256 11.936 98 27 54 0 1184526912 68614816 5553840
07:02:54 27806 8192000000 10057722576 10496589824 1100705168 11.844 98 27 54 0 1143093808 37168928 5219712
07:04:24 42434 8192000000 10104669328 10553360384 1105105920 11.886 98 34 68 0 1222309760 110275824 6928016
07:06:08 52745 8192000000 10094235568 10539601920 1113082544 11.962 99 33 66 1 1204265200 83901408 7281248
07:08:01 58345 8192000000 10055679664 10519502848 1117286032 12.001 99 14 28 0 1160046688 39233600 3527056
07:10:08 74808 8192000000 10060069040 10502987776 1109371776 11.926 98 20 39 1 1164181600 50893120 3916704
07:12:37 85556 8192000000 10046846048 10498568192 1098990560 11.828 97 18 36 0 1136490064 34054864 3444640
07:15:11 83965 8192000000 10122502720 10586230784 1114467760 11.975 98 18 36 0 1206949296 88718864 3762672
07:18:19 68256 8192000000 10082718688 10524016640 1101144848 11.849 97 19 38 1 1155403408 50569776 3688784
07:20:35 47395 8192000000 10093571920 10544852992 1098249536 11.821 97 38 76 4 1192311568 86246640 7815392
07:22:44 67057 8192000000 10124460656 10565029888 1109580464 11.928 98 32 62 2 1197566080 81138704 6846912
07:24:52 50977 8192000000 10155374416 10607976448 1140365040 12.219 100 21 42 2 1221245488 75902064 4978384
07:26:46 44474 8192000000 10202166224 10666254336 1139482128 12.211 100 40 80 0 1310642704 162602992 8557584
07:28:31 72272 8192000000 10050347952 10500247552 1072845472 11.579 95 13 26 0 1154570480 78779728 2945280
07:30:44 82019 8192000000 10102689776 10536681472 1130334560 12.125 100 56 112 0 1207458672 65882720 11241392
07:33:09 31021 8192000000 10087202224 10517704704 1138664448 12.203 100 52 101 0 1186351568 36316704 11370416
07:34:40 64672 8192000000 10101422384 10564313088 1145572768 12.268 100 13 25 1 1208669648 60328064 2768816
07:36:45 32686 8192000000 9554093696 9952423936 668379936 7.543 59 8 12 1 689357504 19499664 1477904

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 28-05-2022 07:39:06 to 28-05-2022 07:39:22.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 147 v_add_invoice_to_stock 335544517
except 4 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 28-05-2022 07:39:23 to 28-05-2022 07:39:25.

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          222034
MON$OLDEST_ACTIVE               222035
MON$OLDEST_SNAPSHOT             222035
MON$NEXT_TRANSACTION            222035
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                       2085984
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             772
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 ---
:102: 
:103: mscs-test-161.budzdorov.ru    Sat May 28 07:37:20 2022
:104:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:105: 
--- end of diff output ---

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

28.05.22 07:39:26. 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-28 07:39:28 No severe PSQL-related problems occured
28.05.22 07:39:29. End of report.