Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 09.04.2022 22: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 16598744 kB
MemAvailable 31511968 kB
Buffers 0 kB
Cached 15085740 kB
SwapCached 2364 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.10
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 2022-04-09 22:38:16
DTS_END                         2022-04-09 22:38:16.8020
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/36920
TRN_ID                          245468
ATT_ID                          702
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: 9494.03 19265 1139283
customer order: creation 183.74 310 22049
customer order: refuse 15.53 355 1864
order to supplier: creation 49.45 1254 5934
order to supplier: removal 3.42 104365 410
invoice (draft): creation 49.01 3500 5881
invoice (draft): removal 50.25 933 6030
invoice accept: apply 44.35 18607 5322
invoice accept: cancel 46.26 908 5551
customer reserve: creation 4206.05 181 504726
customer reserve: removal 4529.01 60 543481
realization accept: apply 90.82 179 10898
realization accept: cancel 87.68 97 10521
payment from customer: creation 63.83 114 7659
payment from customer: removal 5.98 72 718
payment to supplier: creation 52.16 145 6259
payment to supplier: removal 3.80 81 456
service: total inventory turnovers 4.91 1007 589
service: total monetary turnovers 6.93 50 832
service: aggregate perf. data 0.83 18848 99
service: refresh index statistics 0.03 234243 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 09-04-2022 22:39:02 to 09-04-2022 22:39:05.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2974
TEST_TIME 2 4443
TEST_TIME 3 5406
TEST_TIME 4 4856
TEST_TIME 5 4938
TEST_TIME 6 5318
TEST_TIME 7 5427
TEST_TIME 8 5349
TEST_TIME 9 5512
TEST_TIME 10 5745
TEST_TIME 11 5916
TEST_TIME 12 5854
TEST_TIME 13 6134
TEST_TIME 14 6448
TEST_TIME 15 6678
TEST_TIME 16 6994
TEST_TIME 17 7304
TEST_TIME 18 7436
TEST_TIME 19 7698
TEST_TIME 20 7869
TEST_TIME 21 8037
TEST_TIME 22 8740
TEST_TIME 23 9687
TEST_TIME 24 9650
TEST_TIME 25 9928
TEST_TIME 26 9753
TEST_TIME 27 9708
TEST_TIME 28 9672
TEST_TIME 29 10658
TEST_TIME 30 10820
TEST_TIME 31 10868
TEST_TIME 32 10892
TEST_TIME 33 10869
TEST_TIME 34 10856
TEST_TIME 35 10711
TEST_TIME 36 10629
TEST_TIME 37 10511
TEST_TIME 38 10410
TEST_TIME 39 10247
TEST_TIME 40 10156
TEST_TIME 41 10118
TEST_TIME 42 9988
TEST_TIME 43 10133
TEST_TIME 44 10139
TEST_TIME 45 10081
TEST_TIME 46 10058
TEST_TIME 47 10194
TEST_TIME 48 10362
TEST_TIME 49 10271
TEST_TIME 50 10198
TEST_TIME 51 10098
TEST_TIME 52 9990
TEST_TIME 53 9995
TEST_TIME 54 9996
TEST_TIME 55 9931
TEST_TIME 56 9838
TEST_TIME 57 9861
TEST_TIME 58 9773
TEST_TIME 59 9664
TEST_TIME 60 9637
TEST_TIME 61 9549
TEST_TIME 62 9542
TEST_TIME 63 9639
TEST_TIME 64 9711
TEST_TIME 65 9677
TEST_TIME 66 9657
TEST_TIME 67 9802
TEST_TIME 68 9754
TEST_TIME 69 9749
TEST_TIME 70 9779
TEST_TIME 71 9950
TEST_TIME 72 9935
TEST_TIME 73 9936
TEST_TIME 74 9933
TEST_TIME 75 9949
TEST_TIME 76 9827
TEST_TIME 77 9811
TEST_TIME 78 9750
TEST_TIME 79 9741
TEST_TIME 80 9705
TEST_TIME 81 9668
TEST_TIME 82 9628
TEST_TIME 83 9584
TEST_TIME 84 9533
TEST_TIME 85 9499
TEST_TIME 86 9496
TEST_TIME 87 9517
TEST_TIME 88 9498
TEST_TIME 89 9549
TEST_TIME 90 9534
TEST_TIME 91 9554
TEST_TIME 92 9658
TEST_TIME 93 9626
TEST_TIME 94 9646
TEST_TIME 95 9675
TEST_TIME 96 9674
TEST_TIME 97 9635
TEST_TIME 98 9747
TEST_TIME 99 9757
TEST_TIME 100 9731
TEST_TIME 101 9683
TEST_TIME 102 9621
TEST_TIME 103 9613
TEST_TIME 104 9611
TEST_TIME 105 9554
TEST_TIME 106 9535
TEST_TIME 107 9512
TEST_TIME 108 9470
TEST_TIME 109 9467
TEST_TIME 110 9516
TEST_TIME 111 9532
TEST_TIME 112 9505
TEST_TIME 113 9515
TEST_TIME 114 9519
TEST_TIME 115 9520
TEST_TIME 116 9502
TEST_TIME 117 9551
TEST_TIME 118 9533
TEST_TIME 119 9557
TEST_TIME 120 9494

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 09-04-2022 22:39:06 to 09-04-2022 22: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 1604111 1604111 0 0.00 0 25316 7 0 0
doc_list_biud 1604111 1604111 0 0.00 0 39346 27 0 0
sp_add_invoice_to_stock 5449 5322 127 2.33 39 207961 18608 0 127
sp_cancel_adding_invoice 5551 5551 0 0.00 5 89143 908 0 0
sp_cancel_client_order 1864 1864 0 0.00 4 8250 355 0 0
sp_cancel_customer_reserve 543481 543481 0 0.00 1 34742 61 0 0
sp_cancel_pay_from_customer 718 718 0 0.00 3 4753 73 0 0
sp_cancel_pay_to_supplier 456 456 0 0.00 3 4050 82 0 0
sp_cancel_supplier_invoice 6036 6030 6 0.09 2 29861 934 0 6
sp_cancel_supplier_order 410 410 0 0.00 7 1051090 104366 0 0
sp_cancel_write_off 10521 10521 0 0.00 1 7408 98 0 0
sp_client_order 22049 22049 0 0.00 3 25237 310 0 0
sp_customer_reserve 504726 504726 0 0.00 0 127139 181 0 0
sp_fill_shopping_cart 33852 33852 0 0.00 1 15847 35 0 0
sp_fill_shopping_cart_clo_res 504722 504722 0 0.00 0 16766 12 0 0
sp_get_clo_for_invoice 5318 5318 0 0.00 0 23829 244 0 0
sp_kill_cost_storno 17246 17246 0 0.00 0 8629 57 0 0
sp_kill_qstorno_ret_qs2qd 1103923 1103923 0 0.00 0 31275 33 0 0
sp_kill_qty_storno 584073 584073 0 0.00 0 39336 72 0 0
sp_lock_selected_doc 22391 22391 0 0.00 0 7110 5 0 0
sp_make_cost_storno 30134 30134 0 0.00 0 25312 62 0 0
sp_multiply_rows_for_pdistr 30134 30134 0 0.00 0 6047 27 0 0
sp_multiply_rows_for_qdistr 22049 22049 0 0.00 0 8609 124 0 0
sp_pay_from_customer 7659 7659 0 0.00 4 17317 114 0 0
sp_pay_to_supplier 6259 6259 0 0.00 4 25335 145 0 0
sp_payment_common 13918 13918 0 0.00 1 10298 24 0 0
sp_qd_handle_on_cancel_clo 1864 1864 0 0.00 0 8245 319 0 0
sp_qd_handle_on_invoice_upd_sts 10869 10869 0 0.00 0 39329 345 0 0
sp_qd_handle_on_reserve_upd_sts 10898 10898 0 0.00 0 16561 55 0 0
sp_reserve_write_off 10898 10898 0 0.00 7 16576 180 0 0
sp_supplier_invoice 5881 5881 0 0.00 6 109545 3501 0 0
sp_supplier_order 5934 5934 0 0.00 4 41775 1255 0 0
srv_aggregate_perf_data 99 99 0 0.00 3009 205609 18849 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1440 146570 42127 0 0
srv_find_qd_qs_mism 1086552 1086552 0 0.00 0 24661 6 0 0
srv_make_invnt_saldo 589 589 0 0.00 28 54598 1007 0 0
srv_make_money_saldo 832 832 0 0.00 1 19113 51 0 0
srv_recalc_idx_stat 4 4 0 0.00 131502 288344 234244 0 0
t$perf-abend:sp_add_invoice_to_stock 127 127 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 5322 5322 0 0.00 0 12722 164 0 0
t$perf-norm:sp_cancel_adding_invoice 476 476 0 0.00 0 3218 88 0 0
t$perf-norm:sp_cancel_client_order 1864 1864 0 0.00 0 4604 16 0 0
t$perf-norm:sp_cancel_customer_reserve 758 758 0 0.00 0 3000 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 718 718 0 0.00 0 3123 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 456 456 0 0.00 0 1059 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 417 417 0 0.00 0 1526 14 0 0
t$perf-norm:sp_cancel_supplier_order 410 410 0 0.00 0 24751 1562 0 0
t$perf-norm:sp_cancel_write_off 1076 1076 0 0.00 0 4860 10 0 0
t$perf-norm:sp_client_order 22049 22049 0 0.00 0 11580 18 0 0
t$perf-norm:sp_customer_reserve 1047 1047 0 0.00 0 3304 7 0 0
t$perf-norm:sp_pay_from_customer 7659 7659 0 0.00 0 5221 11 0 0
t$perf-norm:sp_pay_to_supplier 6259 6259 0 0.00 0 5028 10 0 0
t$perf-norm:sp_reserve_write_off 10898 10898 0 0.00 0 10443 11 0 0
t$perf-norm:sp_supplier_invoice 5881 5881 0 0.00 0 3818 17 0 0
t$perf-norm:sp_supplier_order 5934 5934 0 0.00 0 11478 18 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 589 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 16 1 0 0
t$perf-norm:srv_make_invnt_saldo 589 589 0 0.00 0 3256 29 0 0
t$perf-norm:srv_make_money_saldo 832 832 0 0.00 0 6886 26 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 3 0 0
v_add_invoice_to_stock 10890 10636 254 2.33 0 5900 19 0 254
v_all_customers 41002 41002 0 0.00 0 16 1 0 0
v_all_suppliers 32876 32876 0 0.00 0 42 1 0 0
v_all_wares 44098 44098 0 0.00 0 73 1 0 0
v_cancel_adding_invoice 952 952 0 0.00 0 3577 15 0 0
v_cancel_client_order 3728 3728 0 0.00 0 5541 9 0 0
v_cancel_customer_prepayment 1436 1436 0 0.00 0 1913 6 0 0
v_cancel_customer_reserve 1516 1516 0 0.00 0 3352 7 0 0
v_cancel_payment_to_supplier 912 912 0 0.00 0 2487 12 0 0
v_cancel_supplier_invoice 846 834 12 1.41 0 3126 17 0 12
v_cancel_supplier_order 820 820 0 0.00 0 2004 11 0 0
v_cancel_write_off 2152 2152 0 0.00 0 4238 19 0 0
v_max_id_clo_ord 5932 5932 0 0.00 0 395 1 0 0
v_max_id_clo_res 1047 1047 0 0.00 0 1159 9 0 0
v_max_id_ord_sup 5871 5871 0 0.00 0 1425 2 0 0
v_max_non_paid_invoice 1624 1624 0 0.00 0 3989 6 0 0
v_max_non_paid_realizn 3842 3842 0 0.00 0 4060 7 0 0
v_min_id_clo_ord 5932 5932 0 0.00 0 2249 4 0 0
v_min_id_clo_res 1047 1047 0 0.00 0 5933 12 0 0
v_min_id_ord_sup 5871 5871 0 0.00 0 3462 4 0 0
v_min_non_paid_invoice 6259 6259 0 0.00 0 10292 19 0 0
v_min_non_paid_realizn 7659 7659 0 0.00 0 5048 15 0 0
v_our_firm 10730 10730 0 0.00 0 15 1 0 0
v_reserve_write_off 21796 21796 0 0.00 0 5870 8 0 0
x_lock_depdocs_on_canc_invoice 11164 11164 0 0.00 0 19202 135 0 0
x_lock_depdocs_on_canc_sup_ord 410 410 0 0.00 0 15008 379 0 0
x_make_qstorno_1000_1200 5934 5934 0 0.00 1 41756 1179 0 0
x_make_qstorno_1200_2000 5881 5881 0 0.00 2 109537 3371 0 0
x_make_qstorno_2100_3300 504726 504726 0 0.00 0 127138 167 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 09-04-2022 22:39:11 to 09-04-2022 22: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
20:08:20 11 8192000000 8806350912 8820133888 10517600 0.128 3 2 3 0 10674768 77840 79328
20:09:21 22166 8192000000 9904103840 10215473152 989889040 10.780 100 90 180 0 1019759696 6407616 23463040
20:10:44 22776 8192000000 9946401520 10270982144 1028164048 11.151 100 89 179 0 1059035504 8125136 22746320
20:12:13 20777 8192000000 9993503168 10327097344 1070600816 11.558 100 85 171 0 1101282272 9458016 21223440
20:13:40 10904 8192000000 10045359328 10396045312 1131762176 12.138 100 77 153 1 1159968528 10742112 17464240
20:14:54 10981 8192000000 10103104752 10463825920 1183504720 12.623 100 80 160 1 1214419152 12764608 18149824
20:16:07 5910 8192000000 10130038384 10496344064 1215233248 12.918 100 79 156 1 1245487408 12703232 17550928
20:17:15 3695 8192000000 10163223408 10536943616 1246974208 13.210 100 85 170 2 1280173040 15049952 18148880
20:18:19 2269 8192000000 10194527232 10573357056 1274164640 13.460 100 91 183 0 1307523520 12735184 20623696
20:19:23 1309 8192000000 10210469472 10592878592 1293101536 13.632 100 91 180 1 1327525616 13886512 20537568
20:20:29 24950 8192000000 10179308176 10593480704 1295520656 13.654 100 38 76 1 1310559040 6537552 8500832
20:21:57 12797 8192000000 10200256928 10616328192 1310859328 13.794 100 17 33 2 1321000688 6092832 4048528
20:23:10 8368 8192000000 10206401648 10623512576 1320274352 13.879 100 23 46 0 1331916320 6412592 5229376
20:24:25 67297 8192000000 10214536688 10632937472 1326292368 13.934 100 37 72 1 1339852976 4688560 8872048
20:26:33 4775 8192000000 10219376704 10642137088 1333296000 13.997 100 19 37 0 1343614416 6126288 4192128
20:27:41 9457 8192000000 10214839136 10638860288 1330814704 13.975 100 19 38 0 1340515536 5108864 4591968
20:28:54 36951 8192000000 10193643008 10609123328 1266474048 13.389 96 25 48 1 1277335392 5218768 5642576
20:30:33 8120 8192000000 10150385104 10560794624 1231834160 13.071 97 22 44 0 1242761088 5560144 5366784
20:31:42 3561 8192000000 10131059280 10529058816 1215613024 12.921 97 33 66 1 1229796400 5939872 8243504
20:32:46 44467 8192000000 10156559376 10559561728 1243057856 13.174 98 24 47 2 1254403984 5493040 5853088
20:34:47 8580 8192000000 10165257040 10572947456 1273788016 13.456 100 28 55 1 1286164336 6218048 6158272
20:35:58 5784 8192000000 10186682688 10596167680 1287985616 13.586 100 25 50 0 1300055184 6462848 5606720
20:37:07 10620 8192000000 10187878784 10600574976 1293956880 13.640 100 14 26 1 1302133936 5381296 2795760
20:38:22 32883 8192000000 10222270000 10630320128 1316414816 13.844 100 41 82 1 1331094848 5885280 8794752
20:40:11 1971 8192000000 10258635632 10657759232 1338891712 14.047 100 81 161 2 1369492912 12862688 17738512
20:41:18 7008 8192000000 10249685232 10666430464 1346490576 14.116 100 44 87 1 1369098224 12323040 10284608
20:42:29 33943 8192000000 10262722752 10685632512 1353557184 14.179 100 35 70 0 1377570080 15757872 8255024
20:44:05 16117 8192000000 10283861232 10705846272 1368710208 14.315 100 53 106 1 1401142208 19742864 12689136
20:45:28 3862 8192000000 10310165680 10725658624 1382713408 14.441 100 71 142 0 1421366560 21752448 16900704
20:46:41 5803 8192000000 10308058896 10723758080 1371196992 14.338 99 63 123 2 1408938368 23110608 14630768
20:47:51 4026 8192000000 10308753056 10732191744 1390545072 14.511 100 55 108 1 1427168816 23091056 13532688
20:48:58 5174 8192000000 10323363728 10740269056 1383433952 14.447 99 63 126 1 1425710352 27226304 15050096
20:50:24 5905 8192000000 10349131760 10765979648 1412063520 14.702 100 66 130 1 1455757168 28025344 15668304
20:51:33 2273 8192000000 10339382608 10746560512 1390653440 14.512 100 83 165 1 1447689616 36010320 21025856
20:52:41 4579 8192000000 10345443792 10754019328 1389204992 14.499 99 80 159 1 1446209776 37211360 19793424
20:53:52 2172 8192000000 10348484224 10754838528 1391340272 14.518 100 94 189 0 1454171120 39316544 23514304
20:55:00 1428 8192000000 10340126064 10740711424 1376514352 14.385 100 87 172 2 1438580080 40588736 21476992
20:56:05 4865 8192000000 10326633728 10741583872 1368991808 14.318 99 54 108 0 1425972544 42962848 14017888
20:57:15 13865 8192000000 10319573136 10740785152 1364032016 14.274 99 40 80 0 1419230848 43700800 11498032
20:58:32 5896 8192000000 10335224096 10757828608 1371453808 14.340 99 58 116 1 1437616736 49857488 16305440
20:59:40 10707 8192000000 10341420512 10759458816 1357167072 14.212 98 55 108 1 1431362848 58756160 15439616
21:00:57 4686 8192000000 10328474800 10750595072 1366356864 14.294 99 60 117 2 1426078864 43330496 16391504
21:02:04 19393 8192000000 10312403504 10746302464 1362364656 14.259 98 34 68 1 1398179760 26576176 9238928
21:03:27 22411 8192000000 10322818656 10763386880 1393298144 14.535 100 29 58 2 1434046432 32410672 8337616
21:04:50 16606 8192000000 10322134448 10758991872 1390189584 14.508 100 27 54 1 1430879024 33647936 7041504
21:06:10 15392 8192000000 10307275136 10741960704 1363937808 14.273 100 33 66 0 1411513856 38335552 9240496
21:07:31 28642 8192000000 10285824672 10737790976 1343850400 14.092 99 15 29 1 1382379600 34119728 4409472
21:09:12 70332 8192000000 10292863696 10721374208 1361541600 14.251 99 27 54 0 1388072192 19749536 6781056
21:11:24 99577 8192000000 10290890048 10740498432 1359727280 14.235 98 3 6 0 1374590608 14302224 561104
21:14:06 84241 8192000000 10325188592 10752913408 1390227712 14.508 100 22 44 0 1405128848 10175536 4725600
21:16:36 120913 8192000000 10300141280 10741092352 1364048048 14.274 98 36 72 2 1386683088 14057104 8577936
21:19:45 144412 8192000000 10300145792 10752684032 1365518432 14.287 98 30 62 3 1408256896 35492432 7246032
21:23:14 68681 8192000000 10318793040 10777411584 1385555504 14.466 99 28 54 2 1413571040 20930336 7085200
21:25:26 37945 8192000000 10306643728 10762977280 1383216112 14.445 99 33 66 1 1405583568 14569456 7798000
21:27:21 71876 8192000000 10286508480 10741329920 1342935264 14.084 97 24 49 0 1360850240 12944736 4970240
21:29:35 88737 8192000000 10276095872 10733834240 1356274800 14.204 98 8 16 0 1367119280 8888736 1955744
21:32:56 16460 8192000000 10290787056 10738016256 1378456064 14.403 100 56 111 1 1397923360 6000512 13466784
21:34:20 6195 8192000000 10287725968 10748792832 1387676336 14.485 100 21 42 0 1403852960 11321136 4855488
21:35:29 43496 8192000000 10271754400 10743611392 1363556384 14.269 98 21 41 1 1376543344 8298640 4688320
21:38:01 53032 8192000000 10263739488 10733490176 1353864000 14.182 98 20 40 1 1369356368 11312048 4180320
21:40:31 63736 8192000000 10259811952 10718638080 1309082928 13.778 95 16 31 1 1325945888 13301856 3561104
21:42:37 37385 8192000000 10263248864 10719035392 1335809360 14.020 97 13 27 1 1346350912 7573712 2967840
21:44:25 84715 8192000000 10249302960 10704510976 1267744064 13.401 92 21 42 0 1288291728 15881856 4665808
21:46:50 40341 8192000000 10262564096 10703237120 1304132144 13.733 95 39 78 1 1330116832 17127328 8857360
21:48:30 101431 8192000000 10251412768 10710609920 1216388096 12.928 89 8 16 0 1234664480 16773504 1502880
21:51:20 58384 8192000000 10253525824 10704158720 1290139808 13.605 95 18 36 0 1305278784 10648784 4490192
21:53:19 69127 8192000000 10266814160 10716663808 1352763696 14.172 100 13 26 0 1369302000 12997456 3540848
21:56:14 43437 8192000000 10284155120 10729467904 1370573904 14.332 100 48 94 0 1393160528 12433600 10153024
21:57:58 18618 8192000000 10262587104 10723635200 1371552656 14.341 100 19 38 0 1383877920 7585648 4739616
21:59:16 56340 8192000000 10263490912 10729132032 1359159520 14.230 99 7 14 1 1367638976 6893584 1585872
22:01:33 48601 8192000000 10275934144 10727710720 1362171200 14.257 99 21 43 2 1373380256 5995200 5213856
22:03:27 53206 8192000000 10282002128 10747486208 1368439504 14.313 99 7 14 0 1380240720 10208480 1592736
22:05:20 35242 8192000000 10278535200 10739994624 1371122896 14.337 99 14 28 0 1383058064 8904000 3031168
22:07:09 45448 8192000000 10270711392 10732331008 1331381216 13.980 96 35 68 2 1353139728 13438144 8320368
22:09:00 85907 8192000000 10291315168 10753122304 1382545888 14.439 100 15 30 1 1401476544 15647392 3283264
22:11:43 24499 8192000000 10290719248 10736205824 1377496816 14.394 100 28 56 0 1391780384 8392640 5890928
22:13:15 40303 8192000000 10299133680 10753794048 1390182288 14.507 100 25 50 1 1405732624 10082784 5467552
22:14:59 18991 8192000000 10308689296 10757578752 1392370656 14.527 100 30 60 1 1409482480 9861952 7249872
22:16:18 146570 8192000000 10286413184 10740068352 1307888224 13.767 94 10 20 1 1324831312 15139664 1803424
22:19:48 85665 8192000000 10292654512 10745503744 1358247840 14.222 98 42 84 1 1378544336 10096960 10199536
22:22:14 48040 8192000000 10273092608 10749743104 1378804272 14.406 100 12 24 0 1394584752 12801792 2978688
22:24:17 100108 8192000000 10281281728 10752499712 1375524688 14.377 99 8 16 1 1385483120 8063936 1894496
22:26:58 90903 8192000000 10294985440 10767683584 1391314848 14.518 100 12 24 0 1405549840 11253104 2981888
22:29:30 65657 8192000000 10273422784 10726719488 1347786576 14.128 97 20 40 3 1360326160 7634736 4904848
22:31:39 38346 8192000000 10266081872 10717429760 1333535584 13.999 97 25 50 1 1347750928 8713136 5502208
22:33:19 13945 8192000000 10241948320 10685693952 1310059488 13.787 97 29 58 2 1325830720 8826992 6944240
22:34:34 43973 8192000000 10238350176 10696712192 1324156160 13.914 99 25 50 0 1342170464 12474144 5540160
22:36:34 23989 8192000000 10262261488 10703966208 1325009168 13.922 98 35 70 0 1342589664 9983648 7596848
22:38:01 21847 8192000000 9667612128 10024042496 786829552 8.763 58 26 50 1 800520128 8041888 5648688

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 09-04-2022 22:39:16 to 09-04-2022 22:39:31.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 127 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 09-04-2022 22:39:32 to 09-04-2022 22:39:34.

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          247808
MON$OLDEST_ACTIVE               248091
MON$OLDEST_SNAPSHOT             248091
MON$NEXT_TRANSACTION            248091
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                       2033312
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33577 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33577 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33577 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33577 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 ---
:132: 
:133: mscs-test-161.budzdorov.ru    Sat Apr  9 22:38:32 2022
:134:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:135: 
:136: 
:137: mscs-test-161.budzdorov.ru    Sat Apr  9 22:38:34 2022
:138:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:139: 
:140: 
:141: mscs-test-161.budzdorov.ru    Sat Apr  9 22:38:34 2022
:142:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:143: 
--- end of diff output ---

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

09.04.22 22:39:35. 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
2022-04-09 22:39:38 No severe PSQL-related problems occured
09.04.22 22:39:39. End of report.