Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 05.12.2021 01:39
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 16890736 kB
MemAvailable 31527316 kB
Buffers 0 kB
Cached 14815952 kB
SwapCached 0 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 3.0.8
DB_NAME                         /home/bases/oltp30-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/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/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=3333
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/oltp30-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-oltp30
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-12-05 01:37:56
DTS_END                         2021-12-05 01:37:56.5340
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/39956
TRN_ID                          238282
ATT_ID                          696
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: 8940.76 19060 1072891
customer order: creation 174.84 343 20981
customer order: refuse 15.10 392 1812
order to supplier: creation 46.45 1227 5574
order to supplier: removal 3.19 106124 383
invoice (draft): creation 45.84 3778 5501
invoice (draft): removal 47.55 782 5706
invoice accept: apply 42.98 19969 5157
invoice accept: cancel 44.00 895 5280
customer reserve: creation 3943.59 199 473231
customer reserve: removal 4277.84 63 513341
realization accept: apply 84.65 191 10158
realization accept: cancel 83.96 107 10075
payment from customer: creation 60.23 114 7227
payment from customer: removal 5.63 76 676
payment to supplier: creation 48.92 144 5870
payment to supplier: removal 3.48 78 418
service: total inventory turnovers 4.71 1310 565
service: total monetary turnovers 6.82 73 818
service: aggregate perf. data 0.95 18267 114
service: refresh index statistics 0.03 227064 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 05-12-2021 01:39:31 to 05-12-2021 01:39:34.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4143
TEST_TIME 2 4720
TEST_TIME 3 5871
TEST_TIME 4 5709
TEST_TIME 5 5468
TEST_TIME 6 5395
TEST_TIME 7 5728
TEST_TIME 8 6183
TEST_TIME 9 6082
TEST_TIME 10 6306
TEST_TIME 11 6303
TEST_TIME 12 6446
TEST_TIME 13 6563
TEST_TIME 14 6587
TEST_TIME 15 6489
TEST_TIME 16 6477
TEST_TIME 17 7108
TEST_TIME 18 7423
TEST_TIME 19 7521
TEST_TIME 20 7865
TEST_TIME 21 7778
TEST_TIME 22 7981
TEST_TIME 23 8120
TEST_TIME 24 8770
TEST_TIME 25 8874
TEST_TIME 26 9147
TEST_TIME 27 9342
TEST_TIME 28 9682
TEST_TIME 29 10141
TEST_TIME 30 10180
TEST_TIME 31 10277
TEST_TIME 32 10165
TEST_TIME 33 10065
TEST_TIME 34 9911
TEST_TIME 35 9758
TEST_TIME 36 9543
TEST_TIME 37 9516
TEST_TIME 38 9529
TEST_TIME 39 9513
TEST_TIME 40 9565
TEST_TIME 41 9534
TEST_TIME 42 9410
TEST_TIME 43 9259
TEST_TIME 44 9182
TEST_TIME 45 9156
TEST_TIME 46 9081
TEST_TIME 47 9179
TEST_TIME 48 9250
TEST_TIME 49 9237
TEST_TIME 50 9115
TEST_TIME 51 9003
TEST_TIME 52 9093
TEST_TIME 53 8982
TEST_TIME 54 8963
TEST_TIME 55 8978
TEST_TIME 56 9001
TEST_TIME 57 8909
TEST_TIME 58 8909
TEST_TIME 59 8880
TEST_TIME 60 8994
TEST_TIME 61 9037
TEST_TIME 62 9026
TEST_TIME 63 9063
TEST_TIME 64 9109
TEST_TIME 65 9207
TEST_TIME 66 9147
TEST_TIME 67 9299
TEST_TIME 68 9331
TEST_TIME 69 9294
TEST_TIME 70 9252
TEST_TIME 71 9242
TEST_TIME 72 9271
TEST_TIME 73 9182
TEST_TIME 74 9152
TEST_TIME 75 9080
TEST_TIME 76 9091
TEST_TIME 77 9058
TEST_TIME 78 8990
TEST_TIME 79 8975
TEST_TIME 80 8952
TEST_TIME 81 8914
TEST_TIME 82 8905
TEST_TIME 83 8901
TEST_TIME 84 8871
TEST_TIME 85 8860
TEST_TIME 86 8904
TEST_TIME 87 8935
TEST_TIME 88 8922
TEST_TIME 89 8939
TEST_TIME 90 8931
TEST_TIME 91 8948
TEST_TIME 92 8993
TEST_TIME 93 8975
TEST_TIME 94 8962
TEST_TIME 95 9005
TEST_TIME 96 9021
TEST_TIME 97 9026
TEST_TIME 98 8948
TEST_TIME 99 8915
TEST_TIME 100 8861
TEST_TIME 101 8874
TEST_TIME 102 8843
TEST_TIME 103 8783
TEST_TIME 104 8750
TEST_TIME 105 8761
TEST_TIME 106 8843
TEST_TIME 107 8872
TEST_TIME 108 8891
TEST_TIME 109 8928
TEST_TIME 110 8901
TEST_TIME 111 8869
TEST_TIME 112 8959
TEST_TIME 113 8930
TEST_TIME 114 8945
TEST_TIME 115 8986
TEST_TIME 116 9033
TEST_TIME 117 9025
TEST_TIME 118 9043
TEST_TIME 119 8983
TEST_TIME 120 8946

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 05-12-2021 01:39:35 to 05-12-2021 01:39:39.

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 1510336 1510336 0 0.00 0 27117 7 0 0
doc_list_biud 1510336 1510336 0 0.00 0 29265 27 0 0
sp_add_invoice_to_stock 5245 5157 88 1.67 37 203505 19970 0 88
sp_cancel_adding_invoice 5280 5280 0 0.00 4 75867 895 0 0
sp_cancel_client_order 1812 1812 0 0.00 4 9996 393 0 0
sp_cancel_customer_reserve 513341 513341 0 0.00 1 20086 64 0 0
sp_cancel_pay_from_customer 676 676 0 0.00 2 5006 76 0 0
sp_cancel_pay_to_supplier 418 418 0 0.00 3 5970 79 0 0
sp_cancel_supplier_invoice 5712 5706 6 0.10 2 17136 782 0 6
sp_cancel_supplier_order 383 383 0 0.00 5 1103950 106124 0 0
sp_cancel_write_off 10075 10075 0 0.00 1 10763 108 0 0
sp_client_order 20981 20981 0 0.00 3 18534 344 0 0
sp_customer_reserve 473231 473231 0 0.00 0 40909 199 0 0
sp_fill_shopping_cart 32050 32050 0 0.00 1 10340 43 0 0
sp_fill_shopping_cart_clo_res 473223 473223 0 0.00 0 14217 14 0 0
sp_get_clo_for_invoice 5148 5148 0 0.00 0 35975 357 0 0
sp_kill_cost_storno 16449 16449 0 0.00 0 26508 61 0 0
sp_kill_qstorno_ret_qs2qd 1042846 1042846 0 0.00 0 19947 34 0 0
sp_kill_qty_storno 551903 551903 0 0.00 0 29262 74 0 0
sp_lock_selected_doc 21006 21006 0 0.00 0 8781 7 0 0
sp_make_cost_storno 28403 28403 0 0.00 0 27113 61 0 0
sp_multiply_rows_for_pdistr 28403 28403 0 0.00 0 7347 33 0 0
sp_multiply_rows_for_qdistr 20981 20981 0 0.00 0 9970 146 0 0
sp_pay_from_customer 7227 7227 0 0.00 4 10431 115 0 0
sp_pay_to_supplier 5870 5870 0 0.00 5 32633 144 0 0
sp_payment_common 13097 13097 0 0.00 1 32619 31 0 0
sp_qd_handle_on_cancel_clo 1812 1812 0 0.00 0 9987 350 0 0
sp_qd_handle_on_invoice_upd_sts 10428 10428 0 0.00 0 29259 331 0 0
sp_qd_handle_on_reserve_upd_sts 10158 10158 0 0.00 0 19990 60 0 0
sp_reserve_write_off 10158 10158 0 0.00 6 20034 191 0 0
sp_supplier_invoice 5501 5501 0 0.00 7 85882 3779 0 0
sp_supplier_order 5574 5574 0 0.00 5 23017 1227 0 0
srv_aggregate_perf_data 114 114 0 0.00 2356 320365 18268 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1477 137748 45531 0 0
srv_find_qd_qs_mism 1024028 1024028 0 0.00 0 14215 6 0 0
srv_make_invnt_saldo 565 565 0 0.00 42 173970 1310 0 0
srv_make_money_saldo 818 818 0 0.00 1 37128 74 0 0
srv_recalc_idx_stat 4 4 0 0.00 130500 344557 227065 0 0
t$perf-abend:sp_add_invoice_to_stock 88 88 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 5157 5157 0 0.00 0 6506 137 0 0
t$perf-norm:sp_cancel_adding_invoice 467 467 0 0.00 0 2753 78 0 0
t$perf-norm:sp_cancel_client_order 1812 1812 0 0.00 0 1199 11 0 0
t$perf-norm:sp_cancel_customer_reserve 644 644 0 0.00 0 1147 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 676 676 0 0.00 0 276 1 0 0
t$perf-norm:sp_cancel_pay_to_supplier 418 418 0 0.00 0 1549 9 0 0
t$perf-norm:sp_cancel_supplier_invoice 376 376 0 0.00 0 817 10 0 0
t$perf-norm:sp_cancel_supplier_order 383 383 0 0.00 0 23440 1466 0 0
t$perf-norm:sp_cancel_write_off 925 925 0 0.00 0 4254 10 0 0
t$perf-norm:sp_client_order 20981 20981 0 0.00 0 8047 18 0 0
t$perf-norm:sp_customer_reserve 1050 1050 0 0.00 0 2160 9 0 0
t$perf-norm:sp_pay_from_customer 7227 7227 0 0.00 0 4306 8 0 0
t$perf-norm:sp_pay_to_supplier 5870 5870 0 0.00 0 3901 7 0 0
t$perf-norm:sp_reserve_write_off 10158 10158 0 0.00 0 5544 9 0 0
t$perf-norm:sp_supplier_invoice 5501 5501 0 0.00 0 2978 14 0 0
t$perf-norm:sp_supplier_order 5574 5574 0 0.00 0 7603 16 0 0
t$perf-norm:srv_aggregate_perf_data 114 114 0 0.00 0 425 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 17 1 0 0
t$perf-norm:srv_make_invnt_saldo 565 565 0 0.00 0 1460 23 0 0
t$perf-norm:srv_make_money_saldo 818 818 0 0.00 0 1866 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 18 8 0 0
v_add_invoice_to_stock 10470 10294 176 1.68 0 6262 18 0 176
v_all_customers 38512 38512 0 0.00 0 15 1 0 0
v_all_suppliers 30404 30404 0 0.00 0 22 1 0 0
v_all_wares 41962 41962 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 934 934 0 0.00 0 1769 10 0 0
v_cancel_client_order 3624 3624 0 0.00 0 4726 9 0 0
v_cancel_customer_prepayment 1352 1352 0 0.00 0 4995 12 0 0
v_cancel_customer_reserve 1288 1288 0 0.00 0 5620 13 0 0
v_cancel_payment_to_supplier 836 836 0 0.00 0 5673 14 0 0
v_cancel_supplier_invoice 764 752 12 1.57 0 3281 23 0 12
v_cancel_supplier_order 766 766 0 0.00 0 1885 9 0 0
v_cancel_write_off 1850 1850 0 0.00 0 6677 21 0 0
v_max_id_clo_ord 5574 5574 0 0.00 0 631 1 0 0
v_max_id_clo_res 1050 1050 0 0.00 0 1738 10 0 0
v_max_id_ord_sup 5495 5495 0 0.00 0 4355 4 0 0
v_max_non_paid_invoice 1737 1737 0 0.00 0 4220 5 0 0
v_max_non_paid_realizn 3808 3808 0 0.00 0 5590 7 0 0
v_min_id_clo_ord 5574 5574 0 0.00 0 1675 5 0 0
v_min_id_clo_res 1050 1050 0 0.00 0 4535 9 0 0
v_min_id_ord_sup 5495 5495 0 0.00 0 5661 7 0 0
v_min_non_paid_invoice 5870 5870 0 0.00 0 32614 28 0 0
v_min_non_paid_realizn 7227 7227 0 0.00 0 6998 19 0 0
v_our_firm 10288 10288 0 0.00 0 16 1 0 0
v_reserve_write_off 20316 20316 0 0.00 0 5259 7 0 0
x_lock_depdocs_on_canc_invoice 10610 10610 0 0.00 0 22828 129 0 0
x_lock_depdocs_on_canc_sup_ord 383 383 0 0.00 0 9199 383 0 0
x_make_qstorno_1000_1200 5574 5574 0 0.00 2 23011 1143 0 0
x_make_qstorno_1200_2000 5501 5501 0 0.00 3 85017 3613 0 0
x_make_qstorno_2100_3300 473231 473231 0 0.00 0 33026 183 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 05-12-2021 01:39:40 to 05-12-2021 01:39:44.

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
23:08:00 4 8192000000 8800890720 8812855296 7015920 0.085 2 2 3 0 7157008 66144 74944
23:09:00 19909 8192000000 9894056832 10200821760 976885904 10.654 100 89 178 0 1006650480 5852368 23912208
23:10:25 10329 8192000000 9937411120 10256388096 1022466016 11.096 100 91 182 0 1053224976 7747552 23011408
23:11:42 17561 8192000000 9969848464 10299404288 1052256560 11.382 100 84 168 0 1082475072 9211952 21006560
23:13:05 8713 8192000000 10021500224 10364637184 1105160272 11.887 100 87 174 0 1136590720 9765536 21664912
23:14:21 10352 8192000000 10076632816 10434990080 1164333344 12.444 100 76 152 1 1193192880 11327712 17531824
23:15:34 8338 8192000000 10119348512 10486173696 1208246960 12.853 100 80 160 0 1239476032 13684912 17544160
23:16:44 9440 8192000000 10155538704 10532036608 1246590432 13.207 100 78 156 0 1275620624 12233184 16797008
23:17:58 2425 8192000000 10187038656 10567716864 1271881760 13.439 100 90 179 2 1306952704 14281504 20789440
23:19:03 1895 8192000000 10208127328 10593038336 1293223840 13.634 100 90 180 0 1326788496 13691216 19873440
23:20:08 3624 8192000000 10208281632 10605121536 1305211648 13.743 100 59 116 0 1329381008 10608512 13560848
23:21:12 6271 8192000000 10198663776 10612719616 1317882320 13.858 100 20 38 1 1328679136 6351840 4444976
23:22:20 10089 8192000000 10201961920 10621272064 1323149376 13.905 100 26 52 1 1335378560 6558064 5671120
23:23:31 42023 8192000000 10217701856 10636726272 1328695360 13.955 100 31 62 2 1341516784 5796320 7025104
23:25:14 4177 8192000000 10228871632 10649698304 1337865744 14.038 100 41 82 1 1354629104 7787552 8975808
23:26:20 10191 8192000000 10226938432 10648924160 1336674976 14.027 100 29 58 0 1349976320 5819072 7482272
23:27:35 41438 8192000000 10206442144 10628603904 1303256368 13.725 98 42 82 1 1319068768 5722032 10090368
23:29:23 5186 8192000000 10150041584 10538856448 1225819936 13.015 95 39 79 1 1241254112 6321408 9112768
23:30:28 5298 8192000000 10129742176 10520457216 1214485184 12.911 97 31 62 0 1231635376 10266688 6883504
23:31:34 8976 8192000000 10151446160 10550542336 1244986128 13.192 99 26 52 2 1257234160 5672848 6575184
23:32:47 15876 8192000000 10146335584 10548645888 1193225200 12.713 94 25 50 0 1204182224 5584976 5372048
23:34:06 6353 8192000000 10159020400 10567188480 1272881008 13.448 100 28 55 2 1284966816 6056880 6028928
23:35:16 12399 8192000000 10184544784 10593542144 1288951328 13.595 100 24 47 3 1300119008 5284432 5883248
23:36:29 77612 8192000000 10202185536 10606936064 1299987600 13.695 100 32 64 1 1313941968 5605264 8349104
23:38:56 37058 8192000000 10241997008 10655113216 1339107872 14.049 100 54 108 1 1361846176 10642112 12096192
23:40:41 11188 8192000000 10273174720 10684047360 1356619088 14.207 100 62 124 1 1384588960 13691600 14278272
23:41:54 28498 8192000000 10276812944 10694029312 1367699936 14.306 100 49 97 2 1392158416 13255552 11202928
23:43:35 10335 8192000000 10288472128 10707054592 1374787744 14.370 100 50 100 0 1400804848 13993136 12023968
23:44:52 9649 8192000000 10293549520 10720616448 1381939088 14.434 100 37 74 0 1407034272 16038384 9056800
23:46:07 12499 8192000000 10307663840 10731249664 1398594032 14.582 100 59 118 2 1427799696 15917712 13287952
23:47:25 4967 8192000000 10324700112 10745057280 1397732128 14.575 100 57 114 4 1433495632 22344144 13419360
23:48:41 5654 8192000000 10349972976 10767319040 1410502368 14.688 100 75 149 1 1455767232 27198768 18066096
23:49:50 5272 8192000000 10320065824 10738286592 1386614400 14.476 99 64 129 1 1431332368 28092320 16625648
23:51:01 8482 8192000000 10334712048 10758205440 1405343824 14.643 100 53 106 1 1446563008 27150032 14069152
23:52:13 9397 8192000000 10317300768 10735345664 1370349888 14.330 98 60 120 0 1415998256 30642080 15006288
23:53:29 5723 8192000000 10326560480 10746634240 1387620416 14.485 100 55 108 1 1436640208 33911552 15108240
23:54:43 2460 8192000000 10335384464 10748723200 1370848848 14.335 99 70 140 0 1430164704 41423312 17892544
23:55:51 5969 8192000000 10308216288 10716110848 1363236624 14.266 100 62 125 0 1406188112 27222832 15728656
23:57:02 1942 8192000000 10342236976 10744504320 1382316048 14.437 100 87 174 0 1437810768 34123920 21370800
23:58:10 1477 8192000000 10333627776 10750570496 1389669840 14.503 100 62 124 1 1439549488 34029728 15849920
23:59:18 3306 8192000000 10352813472 10755792896 1381215792 14.427 100 96 194 3 1448085072 41708384 25160896
00:00:25 4158 8192000000 10338376816 10748968960 1373963296 14.363 100 69 135 5 1432852416 40840416 18048704
00:01:36 15522 8192000000 10321100512 10759233536 1376002144 14.381 100 31 62 0 1430061472 45221248 8838080
00:02:59 4328 8192000000 10345433008 10778554368 1389835296 14.504 100 43 83 1 1443494480 42805600 10853584
00:04:05 7640 8192000000 10312160016 10740834304 1360929744 14.246 98 38 75 1 1408951040 37468000 10553296
00:05:14 17747 8192000000 10287276368 10727632896 1340364368 14.061 98 23 46 1 1391266320 44518672 6383280
00:06:45 29501 8192000000 10267460496 10706436096 1325450384 13.926 98 18 36 1 1358603568 28962464 4190720
00:08:51 74223 8192000000 10263347920 10708619264 1352844672 14.173 99 17 35 0 1366183680 9491008 3848000
00:11:11 92339 8192000000 10276672576 10731593728 1373640512 14.360 100 8 18 0 1388288416 13154848 1493056
00:13:43 79327 8192000000 10293972608 10750431232 1382135968 14.436 100 21 40 1 1398658400 12183312 4339120
00:16:04 79989 8192000000 10303501664 10767355904 1382604352 14.440 100 13 26 0 1403190000 18082144 2503504
00:18:24 93666 8192000000 10287061344 10737012736 1346710704 14.118 97 18 36 1 1359531440 8458416 4362320
00:21:12 34775 8192000000 10294718448 10745024512 1386738128 14.477 100 23 46 0 1405962992 13423184 5801680
00:22:48 40467 8192000000 10325114864 10766123008 1379677280 14.414 99 28 55 4 1402325440 16467472 6180688
00:24:42 37207 8192000000 10295608160 10774237184 1390332128 14.509 100 9 18 0 1416083312 23244960 2506224
00:26:26 67860 8192000000 10283047312 10735579136 1379355776 14.411 99 17 34 0 1392658656 9783600 3519280
00:28:34 91802 8192000000 10291641904 10754740224 1392320608 14.527 100 34 68 2 1409365552 8443712 8601232
00:31:13 32193 8192000000 10302788528 10766659584 1402190368 14.614 100 26 51 2 1420828656 12284272 6354016
00:32:51 74857 8192000000 10290161712 10758774784 1393491872 14.537 99 14 29 0 1409412288 12215040 3705376
00:35:15 61381 8192000000 10314188544 10764533760 1379291168 14.410 98 15 30 1 1401194192 18475696 3427328
00:37:31 53876 8192000000 10295590160 10757275648 1367135888 14.301 98 19 37 0 1383790960 13077792 3577280
00:39:33 43766 8192000000 10246579184 10692530176 1292506080 13.627 95 19 38 0 1306411040 9522624 4382336
00:41:24 87369 8192000000 10275717328 10733629440 1361000464 14.246 99 8 16 0 1374153728 11404688 1748576
00:43:54 57709 8192000000 10283450304 10747457536 1355018912 14.193 98 15 30 0 1373939424 15455120 3465392
00:46:01 32843 8192000000 10273785936 10707488768 1309913520 13.785 96 20 40 0 1323334816 9171248 4250048
00:47:37 12878 8192000000 10282479536 10709069824 1330625968 13.973 98 62 121 2 1358107840 13875568 13606304
00:48:54 31115 8192000000 10295715632 10734673920 1369081168 14.319 100 39 78 0 1391806464 14539200 8186096
00:50:29 129583 8192000000 10248094720 10697334784 1299361696 13.689 95 28 55 2 1316816240 11093376 6361168
00:53:40 61586 8192000000 10271992240 10735652864 1369276480 14.321 100 13 26 1 1383859808 11517824 3065504
00:55:42 57854 8192000000 10283965856 10738647040 1370549584 14.332 99 13 26 1 1383683728 9977008 3157136
00:57:48 39116 8192000000 10275297648 10730610688 1367284080 14.303 99 15 30 0 1381110256 10177072 3649104
00:59:28 43519 8192000000 10278840912 10751377408 1383599424 14.449 100 7 14 0 1395411280 10211312 1600544
01:01:16 96369 8192000000 10297664752 10736009216 1354226768 14.185 98 17 34 0 1371290096 12705568 4357760
01:04:07 75535 8192000000 10269571488 10723454976 1294926416 13.649 94 23 46 0 1309621568 9435440 5259712
01:06:24 44145 8192000000 10278231200 10721636352 1339884192 14.056 97 24 48 0 1353847008 8809760 5153056
01:08:08 89571 8192000000 10297433040 10758963200 1345690768 14.109 97 33 66 1 1368827104 15758416 7377920
01:11:10 44404 8192000000 10293727280 10735738880 1366346704 14.294 99 28 56 2 1382528432 10331360 5850368
01:12:55 99660 8192000000 10294256880 10737668096 1334651184 14.009 96 22 42 1 1348727088 9602976 4472928
01:15:35 78673 8192000000 10299051936 10749833216 1389971456 14.506 100 29 58 0 1404973568 8151920 6850192
01:17:56 22219 8192000000 10305992400 10755411968 1398402288 14.581 100 39 78 0 1416947280 9775488 8769504
01:19:30 94180 8192000000 10293991056 10756894720 1344974336 14.102 96 11 21 3 1360992960 13484128 2534496
01:22:08 78469 8192000000 10292334752 10774368256 1391890736 14.523 100 16 32 1 1415507440 19372544 4244160
01:24:29 137748 8192000000 10303273440 10770186240 1374271024 14.365 99 14 28 0 1392299856 15362752 2666080
01:27:55 110414 8192000000 10307910480 10776121344 1386565136 14.475 99 14 28 0 1412199536 22055680 3578720
01:30:47 23949 8192000000 10307587376 10750369792 1380939952 14.425 100 37 74 2 1408067920 18933648 8194320
01:32:23 58185 8192000000 10300634912 10769592320 1345981888 14.111 97 28 56 1 1383813248 31427520 6403840
01:34:26 14731 8192000000 10273397904 10707542016 1319804448 13.875 96 33 66 2 1333721344 7072912 6843984
01:35:43 91598 8192000000 10265321072 10719744000 1354185248 14.185 99 24 48 0 1367679296 8319648 5174400

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 05-12-2021 01:39:46 to 05-12-2021 01:40:00.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 88 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 05-12-2021 01:40:01 to 05-12-2021 01:40:03.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          237792
MON$OLDEST_ACTIVE               238452
MON$OLDEST_SNAPSHOT             238452
MON$NEXT_TRANSACTION            238452
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-03 16:41:55.3470
MON$PAGES                       2012928
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33541 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33541 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33541 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33541 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.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 ---
:57: 
:58: mscs-test-161.budzdorov.ru    Sun Dec  5 01:38:15 2021
:59:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:60: 
--- end of diff output ---

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

05.12.21 01:40:04. There are 3 logs in /var/tmp/logs-oltp30 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-12-05 01:40:07 No severe PSQL-related problems occured
05.12.21 01:40:08. End of report.