Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 11.07.2022 01:40
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 15994544 kB
MemAvailable 31147720 kB
Buffers 0 kB
Cached 15746012 kB
SwapCached 2520 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.11
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-07-11 01:38:19
DTS_END                         2022-07-11 01:38:19.8290
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/56896
TRN_ID                          247949
ATT_ID                          684
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: 9527.64 18911 1143317
customer order: creation 176.18 358 21141
customer order: refuse 15.20 325 1824
order to supplier: creation 48.27 1264 5792
order to supplier: removal 3.54 98426 425
invoice (draft): creation 48.01 3251 5761
invoice (draft): removal 55.11 779 6613
invoice accept: apply 43.04 17010 5165
invoice accept: cancel 49.35 763 5922
customer reserve: creation 3960.30 169 475236
customer reserve: removal 4822.24 56 578669
realization accept: apply 85.83 187 10300
realization accept: cancel 86.58 106 10389
payment from customer: creation 62.29 107 7475
payment from customer: removal 5.80 72 696
payment to supplier: creation 50.17 125 6020
payment to supplier: removal 3.53 70 424
service: total inventory turnovers 4.63 958 555
service: total monetary turnovers 6.69 42 803
service: aggregate perf. data 0.86 19384 103
service: refresh index statistics 0.03 234763 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 11-07-2022 01:40:28 to 11-07-2022 01:40:30.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2396
TEST_TIME 2 3351
TEST_TIME 3 3752
TEST_TIME 4 4132
TEST_TIME 5 4593
TEST_TIME 6 5516
TEST_TIME 7 5443
TEST_TIME 8 5336
TEST_TIME 9 5565
TEST_TIME 10 6022
TEST_TIME 11 6067
TEST_TIME 12 5962
TEST_TIME 13 6043
TEST_TIME 14 6344
TEST_TIME 15 6232
TEST_TIME 16 6302
TEST_TIME 17 6927
TEST_TIME 18 7164
TEST_TIME 19 7140
TEST_TIME 20 7665
TEST_TIME 21 7686
TEST_TIME 22 7987
TEST_TIME 23 8183
TEST_TIME 24 8823
TEST_TIME 25 9438
TEST_TIME 26 9477
TEST_TIME 27 9786
TEST_TIME 28 10412
TEST_TIME 29 10483
TEST_TIME 30 10452
TEST_TIME 31 10279
TEST_TIME 32 10068
TEST_TIME 33 10180
TEST_TIME 34 10305
TEST_TIME 35 10396
TEST_TIME 36 10333
TEST_TIME 37 10187
TEST_TIME 38 10019
TEST_TIME 39 9962
TEST_TIME 40 9995
TEST_TIME 41 9988
TEST_TIME 42 9875
TEST_TIME 43 9799
TEST_TIME 44 9723
TEST_TIME 45 9695
TEST_TIME 46 9851
TEST_TIME 47 10003
TEST_TIME 48 9964
TEST_TIME 49 9879
TEST_TIME 50 9817
TEST_TIME 51 9716
TEST_TIME 52 9709
TEST_TIME 53 9758
TEST_TIME 54 9714
TEST_TIME 55 9739
TEST_TIME 56 9888
TEST_TIME 57 9883
TEST_TIME 58 9885
TEST_TIME 59 9966
TEST_TIME 60 9977
TEST_TIME 61 9971
TEST_TIME 62 10023
TEST_TIME 63 10007
TEST_TIME 64 10072
TEST_TIME 65 10046
TEST_TIME 66 10209
TEST_TIME 67 10139
TEST_TIME 68 10001
TEST_TIME 69 9924
TEST_TIME 70 10029
TEST_TIME 71 9952
TEST_TIME 72 9932
TEST_TIME 73 9886
TEST_TIME 74 9854
TEST_TIME 75 9902
TEST_TIME 76 9863
TEST_TIME 77 9826
TEST_TIME 78 9712
TEST_TIME 79 9762
TEST_TIME 80 9698
TEST_TIME 81 9695
TEST_TIME 82 9702
TEST_TIME 83 9679
TEST_TIME 84 9680
TEST_TIME 85 9708
TEST_TIME 86 9760
TEST_TIME 87 9772
TEST_TIME 88 9795
TEST_TIME 89 9789
TEST_TIME 90 9780
TEST_TIME 91 9787
TEST_TIME 92 9865
TEST_TIME 93 9890
TEST_TIME 94 9854
TEST_TIME 95 9753
TEST_TIME 96 9729
TEST_TIME 97 9715
TEST_TIME 98 9685
TEST_TIME 99 9649
TEST_TIME 100 9597
TEST_TIME 101 9587
TEST_TIME 102 9556
TEST_TIME 103 9566
TEST_TIME 104 9569
TEST_TIME 105 9562
TEST_TIME 106 9558
TEST_TIME 107 9584
TEST_TIME 108 9590
TEST_TIME 109 9626
TEST_TIME 110 9620
TEST_TIME 111 9609
TEST_TIME 112 9652
TEST_TIME 113 9647
TEST_TIME 114 9656
TEST_TIME 115 9670
TEST_TIME 116 9632
TEST_TIME 117 9610
TEST_TIME 118 9593
TEST_TIME 119 9591
TEST_TIME 120 9530

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 11-07-2022 01:40:31 to 11-07-2022 01:40:34.

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 1584272 1584272 0 0.00 0 35198 6 0 0
doc_list_biud 1584272 1584272 0 0.00 0 47994 26 0 0
sp_add_invoice_to_stock 5283 5165 118 2.23 25 169903 17011 0 118
sp_cancel_adding_invoice 5922 5922 0 0.00 4 71407 763 0 0
sp_cancel_client_order 1824 1824 0 0.00 3 14487 326 0 0
sp_cancel_customer_reserve 578669 578669 0 0.00 0 47995 56 0 0
sp_cancel_pay_from_customer 696 696 0 0.00 3 4048 73 0 0
sp_cancel_pay_to_supplier 424 424 0 0.00 2 3362 71 0 0
sp_cancel_supplier_invoice 6631 6613 18 0.27 1 21225 779 0 18
sp_cancel_supplier_order 425 425 0 0.00 5 1018146 98426 0 0
sp_cancel_write_off 10389 10389 0 0.00 1 22719 107 0 0
sp_client_order 21141 21141 0 0.00 3 32933 358 0 0
sp_customer_reserve 475236 475236 0 0.00 0 64024 169 0 0
sp_fill_shopping_cart 32687 32687 0 0.00 1 13391 34 0 0
sp_fill_shopping_cart_clo_res 475229 475229 0 0.00 0 25657 13 0 0
sp_get_clo_for_invoice 5158 5158 0 0.00 0 31818 324 0 0
sp_kill_cost_storno 17431 17431 0 0.00 0 8834 50 0 0
sp_kill_qstorno_ret_qs2qd 1174765 1174765 0 0.00 0 47992 31 0 0
sp_kill_qty_storno 619299 619299 0 0.00 0 47994 67 0 0
sp_lock_selected_doc 21435 21435 0 0.00 0 11483 5 0 0
sp_make_cost_storno 28952 28952 0 0.00 0 14286 50 0 0
sp_multiply_rows_for_pdistr 28952 28952 0 0.00 0 11635 27 0 0
sp_multiply_rows_for_qdistr 21141 21141 0 0.00 0 15232 152 0 0
sp_pay_from_customer 7475 7475 0 0.00 4 15085 108 0 0
sp_pay_to_supplier 6020 6020 0 0.00 4 12155 126 0 0
sp_payment_common 13495 13495 0 0.00 2 15080 26 0 0
sp_qd_handle_on_cancel_clo 1824 1824 0 0.00 0 14470 305 0 0
sp_qd_handle_on_invoice_upd_sts 11079 11079 0 0.00 0 14438 328 0 0
sp_qd_handle_on_reserve_upd_sts 10300 10300 0 0.00 0 24453 77 0 0
sp_reserve_write_off 10300 10300 0 0.00 6 24531 188 0 0
sp_supplier_invoice 5761 5761 0 0.00 6 77717 3252 0 0
sp_supplier_order 5792 5792 0 0.00 4 72644 1264 0 0
srv_aggregate_perf_data 103 103 0 0.00 2698 479301 19384 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1048 139478 43143 0 0
srv_find_qd_qs_mism 1094483 1094483 0 0.00 0 35197 5 0 0
srv_make_invnt_saldo 555 555 0 0.00 36 26739 959 0 0
srv_make_money_saldo 803 803 0 0.00 1 15322 43 0 0
srv_recalc_idx_stat 4 4 0 0.00 133802 311806 234763 0 0
t$perf-abend:sp_add_invoice_to_stock 118 118 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 18 18 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5165 5165 0 0.00 0 17603 163 0 0
t$perf-norm:sp_cancel_adding_invoice 432 432 0 0.00 0 6231 114 0 0
t$perf-norm:sp_cancel_client_order 1824 1824 0 0.00 0 6340 27 0 0
t$perf-norm:sp_cancel_customer_reserve 707 707 0 0.00 0 1654 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 696 696 0 0.00 0 2090 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 424 424 0 0.00 0 816 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 428 428 0 0.00 0 1934 14 0 0
t$perf-norm:sp_cancel_supplier_order 425 425 0 0.00 0 33283 1595 0 0
t$perf-norm:sp_cancel_write_off 1042 1042 0 0.00 0 4247 11 0 0
t$perf-norm:sp_client_order 21141 21141 0 0.00 0 6850 20 0 0
t$perf-norm:sp_customer_reserve 1064 1064 0 0.00 0 1276 4 0 0
t$perf-norm:sp_pay_from_customer 7475 7475 0 0.00 0 15174 17 0 0
t$perf-norm:sp_pay_to_supplier 6020 6020 0 0.00 0 9144 13 0 0
t$perf-norm:sp_reserve_write_off 10300 10300 0 0.00 0 9644 12 0 0
t$perf-norm:sp_supplier_invoice 5761 5761 0 0.00 0 6311 27 0 0
t$perf-norm:sp_supplier_order 5792 5792 0 0.00 0 11997 18 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 928 24 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 588 10 0 0
t$perf-norm:srv_make_invnt_saldo 555 555 0 0.00 0 558 17 0 0
t$perf-norm:srv_make_money_saldo 803 803 0 0.00 0 421 11 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 3 0 0
v_add_invoice_to_stock 10550 10314 236 2.23 0 11573 18 0 236
v_all_customers 38922 38922 0 0.00 0 134 1 0 0
v_all_suppliers 31796 31796 0 0.00 0 27 1 0 0
v_all_wares 42282 42282 0 0.00 0 25 1 0 0
v_cancel_adding_invoice 864 864 0 0.00 0 5577 18 0 0
v_cancel_client_order 3648 3648 0 0.00 0 959 3 0 0
v_cancel_customer_prepayment 1392 1392 0 0.00 0 1157 5 0 0
v_cancel_customer_reserve 1414 1414 0 0.00 0 2631 8 0 0
v_cancel_payment_to_supplier 848 848 0 0.00 0 3348 7 0 0
v_cancel_supplier_invoice 892 856 36 4.03 0 3338 20 0 36
v_cancel_supplier_order 850 850 0 0.00 0 1798 10 0 0
v_cancel_write_off 2084 2084 0 0.00 0 6224 19 0 0
v_max_id_clo_ord 5791 5791 0 0.00 0 4307 2 0 0
v_max_id_clo_res 1064 1064 0 0.00 0 2256 11 0 0
v_max_id_ord_sup 5755 5755 0 0.00 0 1279 2 0 0
v_max_non_paid_invoice 1668 1668 0 0.00 0 275 2 0 0
v_max_non_paid_realizn 3902 3902 0 0.00 0 1004 3 0 0
v_min_id_clo_ord 5791 5791 0 0.00 0 3271 5 0 0
v_min_id_clo_res 1064 1064 0 0.00 0 4317 13 0 0
v_min_id_ord_sup 5755 5755 0 0.00 0 3401 6 0 0
v_min_non_paid_invoice 6020 6020 0 0.00 0 9256 19 0 0
v_min_non_paid_realizn 7475 7475 0 0.00 0 15076 19 0 0
v_our_firm 10506 10506 0 0.00 0 31 1 0 0
v_reserve_write_off 20600 20600 0 0.00 0 3600 7 0 0
x_lock_depdocs_on_canc_invoice 12107 12107 0 0.00 0 18365 102 0 0
x_lock_depdocs_on_canc_sup_ord 425 425 0 0.00 0 3259 297 0 0
x_make_qstorno_1000_1200 5792 5792 0 0.00 1 72608 1193 0 0
x_make_qstorno_1200_2000 5761 5761 0 0.00 2 77697 3130 0 0
x_make_qstorno_2100_3300 475236 475236 0 0.00 0 64022 153 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 11-07-2022 01:40:35 to 11-07-2022 01:40:39.

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:23 7 8192000000 8810031136 8825053184 14628992 0.178 4 4 5 0 14833536 82064 122480
23:09:24 6356 8192000000 9897048112 10206695424 986767328 10.750 100 88 176 0 1015925760 5861648 23296784
23:10:36 27820 8192000000 9946176752 10268471296 1023261760 11.103 100 85 171 0 1053399072 8199632 21937680
23:12:07 19528 8192000000 9977516096 10311380992 1062001280 11.476 100 86 172 0 1093237024 9904960 21330784
23:13:31 5607 8192000000 10018230976 10364350464 1106333120 11.898 100 81 162 0 1137212128 11713472 19165536
23:14:39 6150 8192000000 10071808384 10426449920 1155195600 12.358 100 85 170 0 1188807280 13555120 20056560
23:15:48 4900 8192000000 10108705280 10478182400 1202215376 12.797 100 70 137 1 1229911328 11512224 16183728
23:16:54 2171 8192000000 10150078720 10520039424 1235890240 13.108 100 88 174 0 1268427040 13602304 18934496
23:17:58 1823 8192000000 10180506688 10560045056 1268835424 13.411 100 84 165 1 1300471040 12694208 18941408
23:19:02 1315 8192000000 10207853504 10593603584 1295542880 13.655 100 92 185 0 1329707392 13637808 20526704
23:20:05 12936 8192000000 10207475120 10607939584 1305295600 13.743 100 47 91 0 1323438704 7705376 10437728
23:21:19 6030 8192000000 10204757120 10615853056 1312104736 13.805 100 33 65 1 1327469264 8207712 7156816
23:22:26 13311 8192000000 10204277872 10619359232 1316896608 13.849 100 20 37 1 1327190880 6355136 3939136
23:23:41 60546 8192000000 10227765872 10639728640 1326644144 13.937 100 54 106 1 1345447392 6025440 12777808
23:25:42 4195 8192000000 10226958976 10647019520 1333102832 13.995 100 20 40 1 1343688128 5943648 4641648
23:26:49 7423 8192000000 10236746032 10659061760 1340653648 14.063 100 20 41 0 1351074032 5523568 4896816
23:27:58 14486 8192000000 10194693456 10616344576 1283869216 13.548 97 17 34 0 1293003376 5212416 3921744
23:29:14 18909 8192000000 10175923824 10566873088 1228632640 13.041 94 41 82 0 1244008592 5539616 9836336
23:30:33 6944 8192000000 10142067376 10544967680 1223296496 12.992 97 20 40 0 1237950912 10089248 4565168
23:31:43 5988 8192000000 10142020064 10551181312 1254813488 13.282 100 23 46 0 1274026784 13960912 5252384
23:32:54 11137 8192000000 10150078064 10555469824 1253644240 13.272 99 19 38 1 1263616224 6121216 3850768
23:34:06 20628 8192000000 10178595584 10572427264 1271514160 13.435 100 54 106 0 1289891632 5771200 12606272
23:35:28 5409 8192000000 10173256304 10586144768 1284122624 13.551 100 19 38 0 1294205584 5586816 4496144
23:36:36 9586 8192000000 10183006144 10597601280 1294345584 13.644 100 16 32 0 1304031728 5547632 4138512
23:37:46 39395 8192000000 10194478064 10610405376 1302743808 13.720 100 20 38 1 1312423984 5371840 4308336
23:39:45 15304 8192000000 10234977584 10639831040 1327785200 13.947 100 45 89 0 1345558208 7331120 10441888
23:41:10 21404 8192000000 10255447248 10669486080 1347411360 14.124 100 56 109 1 1369928112 9617792 12898960
23:42:35 16456 8192000000 10264325296 10685489152 1358000928 14.219 100 31 62 0 1378305776 12878624 7426224
23:43:56 2837 8192000000 10285510112 10702888960 1368713984 14.316 100 52 103 0 1395816960 14740848 12362128
23:45:04 18496 8192000000 10295537968 10723913728 1383978304 14.452 100 36 70 0 1408397760 15841408 8578048
23:46:27 13163 8192000000 10313498064 10732658688 1393316976 14.535 100 54 104 1 1422138944 16391008 12430960
23:47:48 18603 8192000000 10310905024 10740121600 1379108320 14.409 99 54 107 0 1418216432 25242192 13865920
23:49:10 9949 8192000000 10311633616 10733858816 1381190624 14.427 99 62 120 0 1418474656 22959120 14324912
23:50:28 2397 8192000000 10306625824 10721669120 1375652560 14.378 100 57 113 0 1415692832 25320320 14719952
23:51:35 4733 8192000000 10323348288 10734678016 1384349728 14.455 100 64 128 0 1430822704 30808144 15664832
23:52:46 1051 8192000000 10319248336 10720722944 1371521552 14.341 100 83 162 1 1425029968 32214688 21293728
23:53:53 6077 8192000000 10313464896 10717831168 1364724240 14.280 99 71 140 1 1419035232 36550848 17760144
23:55:05 4980 8192000000 10341431120 10746568704 1369724912 14.325 99 73 146 1 1433297040 44873648 18698480
23:56:20 3939 8192000000 10353967072 10763145216 1392508672 14.528 100 86 169 2 1456144768 42492976 21143120
23:57:31 1046 8192000000 10359763984 10771013632 1396933152 14.568 100 67 133 1 1461756544 47930480 16892912
23:58:46 8065 8192000000 10340092736 10753728512 1380805648 14.424 100 55 108 0 1444003696 49065936 14132112
00:00:03 6862 8192000000 10349242192 10759753728 1370969824 14.336 99 64 127 1 1440706272 52439568 17296880
00:01:14 10401 8192000000 10323368832 10750611456 1368055488 14.310 100 29 58 0 1431690768 54956768 8678512
00:02:47 33170 8192000000 10319896656 10759139328 1356836624 14.209 99 26 52 0 1413656304 49217184 7602496
00:04:26 37665 8192000000 10286419360 10723594240 1335700384 14.019 98 20 40 0 1376672912 35902336 5070192
00:06:08 56286 8192000000 10259393312 10706632704 1321425072 13.890 98 10 20 0 1343491056 19601552 2464432
00:08:05 77556 8192000000 10276648272 10707472384 1299791424 13.693 95 25 50 0 1312134448 7918576 4424448
00:10:23 43378 8192000000 10301985712 10726666240 1366701776 14.297 100 26 50 0 1387487760 15233936 5552048
00:12:40 80261 8192000000 10293043776 10749288448 1367951056 14.309 99 12 24 0 1387515696 17144992 2419648
00:15:01 20851 8192000000 10294759472 10728144896 1359339056 14.231 98 34 66 1 1374788064 8112640 7336368
00:16:24 66126 8192000000 10310804496 10743791616 1385590160 14.467 100 41 70 1 1399124608 6536496 6997952
00:18:32 37555 8192000000 10331986064 10771640320 1396599264 14.565 100 35 70 0 1417088256 12748896 7740096
00:20:12 38204 8192000000 10310913408 10774511616 1402035712 14.613 100 15 30 0 1419509312 14114544 3359056
00:21:52 28285 8192000000 10318602224 10771517440 1363736144 14.271 97 39 78 1 1391932960 19283440 8913376
00:23:45 21910 8192000000 10335703184 10774155264 1387992992 14.488 99 53 106 0 1419891280 19544656 12353632
00:25:09 26375 8192000000 10312181824 10760126464 1378363408 14.402 99 41 82 0 1406274848 18292640 9618800
00:26:41 43977 8192000000 10299380224 10770485248 1392050320 14.524 100 11 22 0 1406082416 11270800 2761296
00:28:28 75217 8192000000 10306602000 10765799424 1397547952 14.573 100 11 22 2 1408967216 8200784 3218480
00:30:50 86574 8192000000 10301829520 10762719232 1381668256 14.431 99 13 24 0 1400034688 15407040 2959392
00:33:17 41498 8192000000 10317214576 10779967488 1382363728 14.438 98 21 42 0 1402448528 15172352 4912448
00:35:10 34204 8192000000 10300354240 10754277376 1357465008 14.215 97 34 70 0 1374191376 9059552 7666816
00:36:45 139478 8192000000 10281083616 10735583232 1253875776 13.274 90 5 10 0 1269672304 15032256 764272
00:40:11 74383 8192000000 10255193808 10700550144 1277241232 13.488 93 13 25 0 1287540640 7987072 2312336
00:42:26 59361 8192000000 10288715248 10744459264 1336042864 14.022 98 11 21 0 1350919152 12608768 2267520
00:44:38 106414 8192000000 10260837280 10704097280 1312565824 13.809 96 16 31 0 1329300496 13610432 3124240
00:47:31 98632 8192000000 10269985408 10725740544 1354057200 14.184 99 25 48 4 1381793104 22144880 5591024
00:50:15 72473 8192000000 10260122400 10713096192 1310991152 13.795 97 23 47 0 1332580576 16260336 5329088
00:52:41 53185 8192000000 10245343888 10693869568 1310095776 13.787 98 17 34 0 1339141616 24501920 4543920
00:54:35 45393 8192000000 10237296208 10692157440 1321926896 13.894 99 6 12 0 1339335984 16152272 1256816
00:56:20 43374 8192000000 10249472912 10697736192 1334089312 14.004 100 27 54 2 1354694464 14752288 5852864
00:58:04 49534 8192000000 10255539104 10720120832 1340421760 14.061 99 12 24 0 1353306272 9972960 2911552
01:00:24 55643 8192000000 10272078208 10739548160 1367498688 14.305 100 26 50 1 1387406432 14070496 5837248
01:02:20 36394 8192000000 10297469392 10745073664 1373958752 14.363 99 31 62 0 1397986320 16973872 7053696
01:03:58 52232 8192000000 10295814480 10753798144 1371879008 14.344 99 18 35 1 1388790784 12937600 3974176
01:05:54 75565 8192000000 10297801184 10762100736 1359606848 14.234 98 13 26 0 1371283904 9044272 2632784
01:08:15 10665 8192000000 10324086016 10769190912 1399269552 14.588 100 45 90 0 1418439072 9910128 9259392
01:09:28 35989 8192000000 10314709088 10771636224 1387112832 14.480 99 34 68 1 1407654256 13337488 7203936
01:11:18 41370 8192000000 10314289104 10776584192 1380647264 14.422 98 16 32 0 1399500336 15309648 3543424
01:13:02 87100 8192000000 10301550576 10758266880 1385908608 14.469 99 19 38 0 1401465296 11144640 4412048
01:16:56 70080 8192000000 10295290048 10762850304 1398083760 14.578 100 13 26 0 1407321776 6548912 2689104
01:19:06 50819 8192000000 10288700656 10758320128 1360024896 14.238 97 17 34 0 1374984016 10965664 3993456
01:20:57 79650 8192000000 10297546016 10769551360 1373614048 14.359 98 8 16 0 1386297792 10783408 1900336
01:23:21 56908 8192000000 10287624640 10746683392 1334374368 14.007 96 19 38 0 1347613296 8982000 4256928
01:25:20 33671 8192000000 10292125024 10749325312 1379286544 14.410 100 28 56 0 1403533168 17720192 6526432
01:26:54 61067 8192000000 10276115360 10718031872 1309801872 13.784 95 14 28 0 1327844912 14955904 3087136
01:29:02 54989 8192000000 10297585504 10743611392 1359734416 14.235 99 37 74 0 1383076176 15247344 8094416
01:31:08 55292 8192000000 10284936736 10727845888 1335939776 14.021 97 25 50 1 1351056464 9549232 5567456
01:33:04 45706 8192000000 10305178112 10740277248 1330467904 13.971 97 30 60 1 1351899888 14789088 6642896
01:34:51 94336 8192000000 10252626032 10705584128 1289031184 13.595 95 26 48 1 1308866224 14114928 5720112
01:37:31 53458 8192000000 10012096304 10436124672 1096500640 11.804 82 12 22 1 1106532240 6937264 3094336

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 11-07-2022 01:40:41 to 11-07-2022 01:40:56.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 118 v_add_invoice_to_stock 335544517
except 18 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 11-07-2022 01:40:57 to 11-07-2022 01:40:59.

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          247715
MON$OLDEST_ACTIVE               248175
MON$OLDEST_SNAPSHOT             248175
MON$NEXT_TRANSACTION            248175
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                       2048288
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33608 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33608 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33608 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33608 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 ---
:865: 
:866: mscs-test-161.budzdorov.ru    Mon Jul 11 01:38:50 2022
:867:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:868: 
:869: 
:870: mscs-test-161.budzdorov.ru    Mon Jul 11 01:38:50 2022
:871:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:872: 
:873: 
:874: mscs-test-161.budzdorov.ru    Mon Jul 11 01:38:50 2022
:875:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:876: 
:877: 
:878: mscs-test-161.budzdorov.ru    Mon Jul 11 01:38:50 2022
:879:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:880: 
:881: 
:882: mscs-test-161.budzdorov.ru    Mon Jul 11 01:38:50 2022
:883:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:884: 
--- end of diff output ---

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

11.07.22 01:41:00. 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-07-11 01:41:03 No severe PSQL-related problems occured
11.07.22 01:41:04. End of report.