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

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-10-26 10:37:09
DTS_END                         2021-10-26 10:37:09.5940
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_ORDER(38:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/60308
TRN_ID                          232395
ATT_ID                          669
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: 9075.86 23276 1089103
customer order: creation 159.14 390 19097
customer order: refuse 14.08 356 1689
order to supplier: creation 43.51 1470 5221
order to supplier: removal 3.26 125612 391
invoice (draft): creation 43.95 3935 5274
invoice (draft): removal 52.93 895 6351
invoice accept: apply 39.89 18998 4787
invoice accept: cancel 47.82 998 5738
customer reserve: creation 3697.53 184 443704
customer reserve: removal 4684.60 69 562152
realization accept: apply 78.43 207 9411
realization accept: cancel 86.48 131 10377
payment from customer: creation 57.24 125 6869
payment from customer: removal 5.68 94 682
payment to supplier: creation 47.43 143 5692
payment to supplier: removal 3.44 78 413
service: total inventory turnovers 3.36 4681 403
service: total monetary turnovers 6.18 569 741
service: aggregate perf. data 0.89 18750 107
service: refresh index statistics 0.03 287828 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 26-10-2021 10:39:03 to 26-10-2021 10:39:05.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5152
TEST_TIME 2 4415
TEST_TIME 3 3702
TEST_TIME 4 3860
TEST_TIME 5 4338
TEST_TIME 6 4278
TEST_TIME 7 4663
TEST_TIME 8 5109
TEST_TIME 9 5688
TEST_TIME 10 6031
TEST_TIME 11 6552
TEST_TIME 12 7100
TEST_TIME 13 7517
TEST_TIME 14 7491
TEST_TIME 15 7697
TEST_TIME 16 7860
TEST_TIME 17 8224
TEST_TIME 18 8500
TEST_TIME 19 8454
TEST_TIME 20 8477
TEST_TIME 21 8887
TEST_TIME 22 8898
TEST_TIME 23 8990
TEST_TIME 24 8786
TEST_TIME 25 9303
TEST_TIME 26 9557
TEST_TIME 27 9427
TEST_TIME 28 9433
TEST_TIME 29 9947
TEST_TIME 30 10382
TEST_TIME 31 10584
TEST_TIME 32 11177
TEST_TIME 33 10963
TEST_TIME 34 10734
TEST_TIME 35 10709
TEST_TIME 36 10811
TEST_TIME 37 10738
TEST_TIME 38 10581
TEST_TIME 39 10575
TEST_TIME 40 10623
TEST_TIME 41 10561
TEST_TIME 42 10403
TEST_TIME 43 10199
TEST_TIME 44 10090
TEST_TIME 45 10012
TEST_TIME 46 9924
TEST_TIME 47 9922
TEST_TIME 48 9791
TEST_TIME 49 9704
TEST_TIME 50 9711
TEST_TIME 51 9694
TEST_TIME 52 9864
TEST_TIME 53 9857
TEST_TIME 54 9794
TEST_TIME 55 9735
TEST_TIME 56 9741
TEST_TIME 57 9773
TEST_TIME 58 9753
TEST_TIME 59 9793
TEST_TIME 60 9874
TEST_TIME 61 9814
TEST_TIME 62 9853
TEST_TIME 63 9893
TEST_TIME 64 9877
TEST_TIME 65 9858
TEST_TIME 66 9865
TEST_TIME 67 9920
TEST_TIME 68 9906
TEST_TIME 69 9772
TEST_TIME 70 9728
TEST_TIME 71 9724
TEST_TIME 72 9690
TEST_TIME 73 9613
TEST_TIME 74 9528
TEST_TIME 75 9491
TEST_TIME 76 9460
TEST_TIME 77 9422
TEST_TIME 78 9431
TEST_TIME 79 9398
TEST_TIME 80 9375
TEST_TIME 81 9469
TEST_TIME 82 9444
TEST_TIME 83 9458
TEST_TIME 84 9480
TEST_TIME 85 9521
TEST_TIME 86 9492
TEST_TIME 87 9661
TEST_TIME 88 9760
TEST_TIME 89 9655
TEST_TIME 90 9557
TEST_TIME 91 9594
TEST_TIME 92 9556
TEST_TIME 93 9502
TEST_TIME 94 9460
TEST_TIME 95 9396
TEST_TIME 96 9341
TEST_TIME 97 9320
TEST_TIME 98 9269
TEST_TIME 99 9226
TEST_TIME 100 9279
TEST_TIME 101 9251
TEST_TIME 102 9221
TEST_TIME 103 9243
TEST_TIME 104 9219
TEST_TIME 105 9267
TEST_TIME 106 9267
TEST_TIME 107 9270
TEST_TIME 108 9305
TEST_TIME 109 9294
TEST_TIME 110 9305
TEST_TIME 111 9343
TEST_TIME 112 9339
TEST_TIME 113 9294
TEST_TIME 114 9240
TEST_TIME 115 9214
TEST_TIME 116 9207
TEST_TIME 117 9157
TEST_TIME 118 9137
TEST_TIME 119 9150
TEST_TIME 120 9077

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 26-10-2021 10:39:06 to 26-10-2021 10:39:10.

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1498815 1498815 0 0.00 0 27457 7 0 0
doc_list_biud 1498812 1498812 0 0.00 0 27335 33 0 0
sp_add_invoice_to_stock 4880 4787 93 1.90 51 168635 18998 0 93
sp_cancel_adding_invoice 5738 5738 0 0.00 6 96199 998 0 0
sp_cancel_client_order 1689 1689 0 0.00 4 11789 356 0 0
sp_cancel_customer_reserve 562152 562152 0 0.00 1 27191 70 0 0
sp_cancel_pay_from_customer 682 682 0 0.00 3 5852 95 0 0
sp_cancel_pay_to_supplier 413 413 0 0.00 3 3945 78 0 0
sp_cancel_supplier_invoice 6357 6351 6 0.09 2 26391 895 0 6
sp_cancel_supplier_order 391 391 0 0.00 17 1110767 125612 0 0
sp_cancel_write_off 10377 10377 0 0.00 1 24206 132 0 0
sp_client_order 19097 19097 0 0.00 4 19976 391 0 0
sp_customer_reserve 443704 443704 0 0.00 0 100306 185 0 0
sp_fill_shopping_cart 29579 29579 0 0.00 1 19357 51 0 0
sp_fill_shopping_cart_clo_res 443702 443702 0 0.00 0 17031 16 0 0
sp_get_clo_for_invoice 4785 4785 0 0.00 0 25117 360 0 0
sp_kill_cost_storno 17210 17210 0 0.00 0 8920 66 0 0
sp_kill_qstorno_ret_qs2qd 1141423 1141423 0 0.00 0 27178 37 0 0
sp_kill_qty_storno 600894 600894 0 0.00 0 27335 80 0 0
sp_lock_selected_doc 19841 19841 0 0.00 0 8742 5 0 0
sp_make_cost_storno 26757 26757 0 0.00 0 17287 68 0 0
sp_multiply_rows_for_pdistr 26756 26756 0 0.00 0 12254 36 0 0
sp_multiply_rows_for_qdistr 19097 19097 0 0.00 0 11909 188 0 0
sp_pay_from_customer 6869 6869 0 0.00 4 12204 125 0 0
sp_pay_to_supplier 5692 5692 0 0.00 5 17320 143 0 0
sp_payment_common 12557 12557 0 0.00 1 12194 29 0 0
sp_qd_handle_on_cancel_clo 1689 1689 0 0.00 0 11782 321 0 0
sp_qd_handle_on_invoice_upd_sts 10523 10523 0 0.00 0 27334 418 0 0
sp_qd_handle_on_reserve_upd_sts 9411 9411 0 0.00 0 16336 56 0 0
sp_reserve_write_off 9411 9411 0 0.00 6 16352 207 0 0
sp_supplier_invoice 5274 5274 0 0.00 6 81030 3935 0 0
sp_supplier_order 5221 5221 0 0.00 6 37622 1471 0 0
srv_aggregate_perf_data 107 107 0 0.00 3324 173025 18750 0 0
srv_fill_mon_cache_memory 60 60 0 0.00 1100 128003 50598 0 0
srv_find_qd_qs_mism 1043033 1043033 0 0.00 0 27432 6 0 0
srv_make_invnt_saldo 403 403 0 0.00 72 79733 4682 0 0
srv_make_money_saldo 741 741 0 0.00 1 12410 570 0 0
srv_recalc_idx_stat 4 4 0 0.00 182401 464818 287829 0 0
t$perf-abend:sp_add_invoice_to_stock 93 93 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4788 4788 0 0.00 0 15175 216 0 0
t$perf-norm:sp_cancel_adding_invoice 411 411 0 0.00 0 1893 92 0 0
t$perf-norm:sp_cancel_client_order 1689 1689 0 0.00 0 7918 21 0 0
t$perf-norm:sp_cancel_customer_reserve 633 633 0 0.00 0 7049 30 0 0
t$perf-norm:sp_cancel_pay_from_customer 682 682 0 0.00 0 5248 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 413 413 0 0.00 0 386 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 408 408 0 0.00 0 5978 38 0 0
t$perf-norm:sp_cancel_supplier_order 391 391 0 0.00 0 17939 1789 0 0
t$perf-norm:sp_cancel_write_off 1018 1018 0 0.00 0 1073 3 0 0
t$perf-norm:sp_client_order 19098 19098 0 0.00 0 12634 22 0 0
t$perf-norm:sp_customer_reserve 947 947 0 0.00 0 1439 8 0 0
t$perf-norm:sp_pay_from_customer 6870 6870 0 0.00 0 10015 15 0 0
t$perf-norm:sp_pay_to_supplier 5692 5692 0 0.00 0 19905 19 0 0
t$perf-norm:sp_reserve_write_off 9411 9411 0 0.00 0 8262 16 0 0
t$perf-norm:sp_supplier_invoice 5274 5274 0 0.00 0 11736 29 0 0
t$perf-norm:sp_supplier_order 5221 5221 0 0.00 0 3298 18 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 3560 42 0 0
t$perf-norm:srv_fill_mon_cache_memory 60 60 0 0.00 0 29 2 0 0
t$perf-norm:srv_make_invnt_saldo 403 403 0 0.00 0 5350 27 0 0
t$perf-norm:srv_make_money_saldo 741 741 0 0.00 0 12088 29 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 9 5 0 0
v_add_invoice_to_stock 9756 9570 186 1.90 0 6414 20 0 186
v_all_customers 35146 35146 0 0.00 0 136 1 0 0
v_all_suppliers 29160 29160 0 0.00 0 22 1 0 0
v_all_wares 38194 38194 0 0.00 0 21 1 0 0
v_cancel_adding_invoice 822 822 0 0.00 0 351 8 0 0
v_cancel_client_order 3378 3378 0 0.00 0 3105 6 0 0
v_cancel_customer_prepayment 1364 1364 0 0.00 0 1414 7 0 0
v_cancel_customer_reserve 1266 1266 0 0.00 0 771 4 0 0
v_cancel_payment_to_supplier 826 826 0 0.00 0 523 5 0 0
v_cancel_supplier_invoice 828 816 12 1.44 0 1298 16 0 12
v_cancel_supplier_order 782 782 0 0.00 0 3105 11 0 0
v_cancel_write_off 2036 2036 0 0.00 0 2021 19 0 0
v_max_id_clo_ord 5218 5218 0 0.00 0 825 2 0 0
v_max_id_clo_res 947 947 0 0.00 0 7840 24 0 0
v_max_id_ord_sup 5262 5262 0 0.00 0 5603 6 0 0
v_max_non_paid_invoice 1588 1588 0 0.00 0 6104 6 0 0
v_max_non_paid_realizn 3602 3602 0 0.00 0 3294 4 0 0
v_min_id_clo_ord 5218 5218 0 0.00 0 5619 13 0 0
v_min_id_clo_res 947 947 0 0.00 0 8320 23 0 0
v_min_id_ord_sup 5262 5262 0 0.00 0 5571 7 0 0
v_min_non_paid_invoice 5690 5690 0 0.00 0 9212 20 0 0
v_min_non_paid_realizn 6867 6867 0 0.00 0 9665 18 0 0
v_our_firm 9578 9578 0 0.00 0 17 1 0 0
v_reserve_write_off 18822 18822 0 0.00 0 5267 7 0 0
x_lock_depdocs_on_canc_invoice 11681 11681 0 0.00 0 9544 123 0 0
x_lock_depdocs_on_canc_sup_ord 391 391 0 0.00 0 12472 375 0 0
x_make_qstorno_1000_1200 5221 5221 0 0.00 2 33469 1374 0 0
x_make_qstorno_1200_2000 5274 5274 0 0.00 2 81011 3752 0 0
x_make_qstorno_2100_3300 443704 443704 0 0.00 0 100304 166 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 26-10-2021 10:39:11 to 26-10-2021 10:39:15.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
08:07:13 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:15 25081 8192000000 9719543696 10019291136 802782048 8.924 100 81 162 0 829365872 7378704 19205120
08:09:45 8987 8192000000 9758781936 10063859712 835419504 9.254 100 87 174 0 868268048 12463648 20384896
08:11:04 18672 8192000000 9804825632 10118774784 859698544 9.497 100 85 170 0 903737248 24073408 19965296
08:12:34 14310 8192000000 9859569312 10193907712 916240112 10.059 100 81 162 1 972040448 38122256 17678080
08:13:49 9706 8192000000 9919427344 10266206208 959083376 10.480 100 81 163 1 1034681200 58739680 16858144
08:15:02 5089 8192000000 9979494032 10332119040 996871040 10.848 100 79 158 0 1078823504 66281376 15671088
08:16:09 3865 8192000000 10000766896 10363187200 1024046192 11.111 100 87 171 0 1111403808 70213568 17144048
08:17:15 1858 8192000000 10010072880 10376802304 1052310768 11.383 100 87 174 1 1121566624 51487424 17768432
08:18:18 1261 8192000000 10040759008 10414714880 1076773104 11.617 100 84 167 0 1154751696 61062960 16915632
08:19:20 20805 8192000000 9978468752 10384191488 1075817280 11.608 100 31 62 0 1109315936 25913040 7585616
08:20:41 7818 8192000000 9999688720 10407624704 1079684992 11.644 100 28 57 0 1115382944 29794144 5903808
08:21:49 6480 8192000000 10004709920 10405244928 1087656400 11.720 100 34 68 0 1124353856 28801552 7895904
08:22:57 3627 8192000000 10004608128 10403463168 1096226256 11.802 100 48 95 1 1120137008 12255184 11655568
08:24:04 46163 8192000000 10027854496 10443837440 1100840288 11.846 100 42 84 2 1137903184 27880864 9182032
08:25:51 8756 8192000000 10036554144 10438803456 1107641904 11.910 100 38 74 0 1137009008 21563584 7803520
08:27:00 8856 8192000000 10016209744 10428973056 1087950560 11.723 99 35 70 3 1118924096 23920800 7052736
08:28:09 8200 8192000000 9998136464 10398560256 1068425200 11.537 99 30 60 2 1099837568 25177232 6235136
08:29:22 22745 8192000000 9976078192 10374832128 994445024 10.825 93 21 40 0 1024482368 26209120 3828224
08:30:48 8545 8192000000 9962153920 10342227968 1010110320 10.976 99 42 84 0 1028736000 9205072 9420608
08:31:58 10325 8192000000 9949497296 10347008000 1035525536 11.222 100 41 82 0 1063126336 17838400 9762400
08:33:11 68718 8192000000 9968548672 10362634240 1034344960 11.210 99 30 60 0 1060416288 19234336 6836992
08:35:21 6713 8192000000 9985375120 10383429632 1058474464 11.442 100 48 97 2 1103442336 33998848 10969024
08:36:28 4334 8192000000 9991502496 10392268800 1067392720 11.527 100 39 77 0 1102499376 26449120 8657536
08:37:35 34286 8192000000 9998351136 10394476544 1078877504 11.637 100 42 82 1 1100262592 11644720 9740368
08:39:15 106326 8192000000 10050990544 10461208576 1102703344 11.863 100 29 57 0 1159315744 50741552 5870848
08:42:07 7350 8192000000 10087146960 10491138048 1115447264 11.984 100 43 86 4 1197737440 72987984 9302192
08:43:18 29142 8192000000 10136798576 10548510720 1127155200 12.095 100 39 79 1 1245724544 110434192 8135152
08:45:39 16259 8192000000 10152066816 10562269184 1138064400 12.197 100 45 87 0 1255137632 106847600 10225632
08:47:15 2178 8192000000 10202349216 10600468480 1163529216 12.436 100 78 155 1 1307642544 127776784 16336544
08:48:30 2033 8192000000 10223163472 10630696960 1168107456 12.479 100 76 149 0 1330133424 145671392 16354576
08:49:34 1950 8192000000 10234271408 10634108928 1169677216 12.494 100 82 165 0 1332845712 145909616 17258880
08:50:41 18868 8192000000 10180716944 10601639936 1166031024 12.460 99 47 94 1 1283577776 107354032 10192720
08:52:07 2352 8192000000 10219697824 10611093504 1120593632 12.033 98 84 169 0 1312840960 173945312 18302016
08:53:14 2455 8192000000 10245642800 10635849728 1117536528 12.004 100 78 156 1 1341592896 206855600 17200768
08:54:21 2653 8192000000 10238793616 10647212032 1120781328 12.034 100 70 138 1 1336154752 198706656 16666768
08:55:28 11885 8192000000 10238862592 10651164672 1131261104 12.133 100 37 70 0 1360271520 221006640 8003776
08:56:45 1788 8192000000 10314245616 10703757312 1134014176 12.159 99 87 173 0 1406461232 253676992 18770064
08:57:52 1096 8192000000 10387285648 10793160704 1166618416 12.465 100 79 158 1 1491989680 306397824 18973440
08:58:56 5642 8192000000 10383200096 10800885760 1168562912 12.483 100 51 102 0 1483782896 302840160 12379824
09:00:05 7444 8192000000 10422095344 10833084416 1144735088 12.260 99 43 84 0 1511363760 355653840 10974832
09:01:19 7381 8192000000 10454274592 10867556352 1146633408 12.278 100 50 99 1 1546151344 386598928 12919008
09:02:30 10335 8192000000 10472013104 10941001728 1158355024 12.388 100 44 86 0 1610723840 440643360 11725456
09:03:44 27830 8192000000 10478485984 10926342144 1163894976 12.440 100 42 84 0 1561460720 386039120 11526624
09:05:15 45087 8192000000 10489046160 10968190976 1147853744 12.289 99 34 66 1 1569960352 413354368 8752240
09:07:01 50599 8192000000 10295453056 10788995072 1130360912 12.125 100 24 48 0 1463152624 326591904 6199808
09:09:45 49179 8192000000 10069713568 10487414784 1101888064 11.856 97 24 49 0 1143093056 35693152 5511840
09:11:35 26844 8192000000 10136660528 10576691200 1140817888 12.223 100 56 110 1 1220392464 68524704 11049872
09:13:59 120033 8192000000 10093490464 10509238272 1132899376 12.149 99 20 40 0 1169949936 32585936 4464624
09:17:02 98537 8192000000 10073183168 10530459648 1092142304 11.763 96 7 14 1 1143497584 50292144 1063136
09:19:41 71644 8192000000 10074089392 10500669440 1132871296 12.148 100 38 75 0 1178508176 37586656 8050224
09:21:57 112573 8192000000 10152042176 10602430464 1127306672 12.096 99 32 62 2 1225465632 90844656 7314304
09:25:17 34392 8192000000 10201307728 10618511360 1161127888 12.414 100 45 90 0 1283018192 111397776 10492528
09:27:17 68391 8192000000 10197519088 10645614592 1144457408 12.257 99 23 47 2 1283780016 133813280 5509328
09:30:13 68136 8192000000 10075943392 10531504128 1109280944 11.926 96 10 20 0 1163301872 51622944 2397984
09:32:24 69110 8192000000 10138788400 10629554176 1140506800 12.220 99 6 12 0 1217822608 75956608 1359200
09:34:34 41946 8192000000 10155043376 10594828288 1159560560 12.399 100 32 64 0 1214080304 47920304 6599440
09:36:22 103349 8192000000 10102514480 10601889792 1127542160 12.098 98 9 18 1 1186336176 57043744 1750272
09:39:08 74756 8192000000 10118469776 10602549248 1119390848 12.021 98 22 44 0 1191436704 67198272 4847584
09:41:36 53708 8192000000 10056896864 10503630848 1075776512 11.607 95 15 29 0 1109345056 30613616 2954928
09:43:33 86019 8192000000 10085843728 10578128896 1088512800 11.729 97 14 28 1 1195127504 103751216 2863488
09:46:05 97768 8192000000 10077235392 10521661440 1121886336 12.045 100 29 58 0 1170373376 42183056 6303984
09:49:12 77807 8192000000 10050359968 10514755584 1103128096 11.867 97 16 32 0 1135824304 29307776 3388432
09:51:30 60725 8192000000 10051658336 10522988544 1110845936 11.940 98 9 17 1 1146071728 33518144 1707648
09:53:31 52831 8192000000 10117480720 10580615168 1130450032 12.126 99 27 54 0 1204574640 68170528 5954080
09:55:32 90515 8192000000 10145464432 10610360320 1105959664 11.894 97 16 32 0 1190102288 80478320 3664304
09:58:07 71929 8192000000 10071590800 10508541952 1069732512 11.550 94 25 50 0 1132904576 57643872 5528192
10:00:20 55299 8192000000 10119711280 10571456512 1115688192 11.986 98 19 38 0 1216288992 96652416 3948384
10:02:19 46844 8192000000 10115135056 10607796224 1110981872 11.942 98 24 48 1 1201917312 86108992 4826448
10:04:09 35383 8192000000 10143423632 10646376448 1124382448 12.068 99 9 18 0 1244323872 117998736 1942688
10:05:45 96994 8192000000 10119275312 10664206336 1127255456 12.095 100 9 18 0 1204050608 74996000 1799152
10:08:48 48519 8192000000 10106343536 10618499072 1131544016 12.136 100 9 18 0 1208627888 75167088 1916784
10:10:39 50772 8192000000 10042691568 10515337216 1120276464 12.030 99 9 19 1 1146953296 24550704 2126128
10:12:33 52030 8192000000 10057081312 10499055616 1111681344 11.948 98 12 24 0 1139183488 24676272 2825872
10:15:54 100826 8192000000 10069674000 10592112640 1120603888 12.033 98 18 36 1 1215388912 90743664 4041360
10:18:36 40770 8192000000 10075927552 10512850944 1122100352 12.047 98 15 30 1 1149486000 23355008 4030640
10:20:18 34105 8192000000 10093534064 10571120640 1123659712 12.062 99 30 60 1 1204293168 74338576 6294880
10:21:54 29429 8192000000 10117927536 10563633152 1134371264 12.163 100 42 80 4 1220664256 77732752 8560240
10:23:33 67351 8192000000 10137039168 10579918848 1125434944 12.078 99 30 60 0 1219440720 87588912 6416864
10:25:41 55649 8192000000 10083362896 10557796352 1102932608 11.865 97 26 52 0 1153030096 45106496 4990992
10:27:36 114960 8192000000 10068206336 10520551424 1114154928 11.972 98 30 60 0 1170299408 50214432 5930048
10:30:32 128003 8192000000 10029589552 10477490176 1063757376 11.492 94 27 54 0 1107389408 38242240 5389792
10:33:40 97635 8192000000 10075399520 10526904320 1085111184 11.696 97 14 28 1 1145872960 57545040 3216736
10:36:18 56056 8192000000 9575914720 9991745536 698006544 7.851 63 13 25 0 757175760 56233408 2935808

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 26-10-2021 10:39:17 to 26-10-2021 10:39:31.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 93 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 26-10-2021 10:39:32 to 26-10-2021 10:39:34.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          232585
MON$OLDEST_ACTIVE               232586
MON$OLDEST_SNAPSHOT             232586
MON$NEXT_TRANSACTION            232586
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                       2084000
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             798
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2642 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2642 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2642 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2642 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 ---
:369: 
:370: mscs-test-161.budzdorov.ru    Tue Oct 26 10:37:19 2021
:371:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:372: 
:373: 
:374: mscs-test-161.budzdorov.ru    Tue Oct 26 10:37:24 2021
:375:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:376: 
:377: 
:378: mscs-test-161.budzdorov.ru    Tue Oct 26 10:37:24 2021
:379:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:380: 
:381: 
:382: mscs-test-161.budzdorov.ru    Tue Oct 26 10:37:29 2021
:383:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:384: 
:385: 
:386: mscs-test-161.budzdorov.ru    Tue Oct 26 10:37:29 2021
:387:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:388: 
:389: 
:390: mscs-test-161.budzdorov.ru    Tue Oct 26 10:37:29 2021
:391:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:392: 
:393: 
:394: mscs-test-161.budzdorov.ru    Tue Oct 26 10:37:36 2021
:395:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:396: 
--- end of diff output ---

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

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