Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 23.06.2022 22: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 16093576 kB
MemAvailable 31259292 kB
Buffers 0 kB
Cached 15642784 kB
SwapCached 2492 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-06-23 22:38:23
DTS_END                         2022-06-23 22:38:23.6940
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/53398
TRN_ID                          242130
ATT_ID                          688
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: 9307.94 19755 1116953
customer order: creation 173.86 344 20863
customer order: refuse 15.19 343 1823
order to supplier: creation 46.86 1194 5623
order to supplier: removal 3.70 102125 444
invoice (draft): creation 46.20 3214 5544
invoice (draft): removal 53.69 717 6443
invoice accept: apply 41.71 17879 5005
invoice accept: cancel 48.23 820 5787
customer reserve: creation 3865.81 176 463897
customer reserve: removal 4710.38 65 565246
realization accept: apply 83.83 193 10060
realization accept: cancel 87.43 103 10492
payment from customer: creation 59.83 121 7179
payment from customer: removal 5.92 86 710
payment to supplier: creation 49.31 155 5917
payment to supplier: removal 3.73 49 447
service: total inventory turnovers 4.83 1167 580
service: total monetary turnovers 6.57 25 788
service: aggregate perf. data 0.84 21066 101
service: refresh index statistics 0.03 245248 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 23-06-2022 22:39:59 to 23-06-2022 22:40:01.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2683
TEST_TIME 2 4330
TEST_TIME 3 3418
TEST_TIME 4 3530
TEST_TIME 5 3559
TEST_TIME 6 4554
TEST_TIME 7 4585
TEST_TIME 8 4509
TEST_TIME 9 4789
TEST_TIME 10 5190
TEST_TIME 11 5644
TEST_TIME 12 6157
TEST_TIME 13 6262
TEST_TIME 14 6425
TEST_TIME 15 6791
TEST_TIME 16 7194
TEST_TIME 17 7321
TEST_TIME 18 7137
TEST_TIME 19 7286
TEST_TIME 20 7665
TEST_TIME 21 7772
TEST_TIME 22 7657
TEST_TIME 23 8174
TEST_TIME 24 8510
TEST_TIME 25 8621
TEST_TIME 26 8915
TEST_TIME 27 9867
TEST_TIME 28 10517
TEST_TIME 29 10824
TEST_TIME 30 10582
TEST_TIME 31 10600
TEST_TIME 32 10651
TEST_TIME 33 10587
TEST_TIME 34 10518
TEST_TIME 35 10418
TEST_TIME 36 10426
TEST_TIME 37 10402
TEST_TIME 38 10152
TEST_TIME 39 10192
TEST_TIME 40 10112
TEST_TIME 41 10070
TEST_TIME 42 9931
TEST_TIME 43 9922
TEST_TIME 44 9873
TEST_TIME 45 9746
TEST_TIME 46 9740
TEST_TIME 47 9785
TEST_TIME 48 9707
TEST_TIME 49 9783
TEST_TIME 50 9736
TEST_TIME 51 9786
TEST_TIME 52 9722
TEST_TIME 53 9692
TEST_TIME 54 9788
TEST_TIME 55 9806
TEST_TIME 56 10034
TEST_TIME 57 9932
TEST_TIME 58 9870
TEST_TIME 59 10019
TEST_TIME 60 10150
TEST_TIME 61 10150
TEST_TIME 62 10175
TEST_TIME 63 10055
TEST_TIME 64 9967
TEST_TIME 65 9893
TEST_TIME 66 9797
TEST_TIME 67 9852
TEST_TIME 68 9798
TEST_TIME 69 9715
TEST_TIME 70 9652
TEST_TIME 71 9603
TEST_TIME 72 9610
TEST_TIME 73 9591
TEST_TIME 74 9487
TEST_TIME 75 9550
TEST_TIME 76 9501
TEST_TIME 77 9517
TEST_TIME 78 9462
TEST_TIME 79 9528
TEST_TIME 80 9519
TEST_TIME 81 9521
TEST_TIME 82 9467
TEST_TIME 83 9492
TEST_TIME 84 9568
TEST_TIME 85 9603
TEST_TIME 86 9617
TEST_TIME 87 9591
TEST_TIME 88 9696
TEST_TIME 89 9666
TEST_TIME 90 9709
TEST_TIME 91 9634
TEST_TIME 92 9588
TEST_TIME 93 9538
TEST_TIME 94 9520
TEST_TIME 95 9505
TEST_TIME 96 9457
TEST_TIME 97 9497
TEST_TIME 98 9500
TEST_TIME 99 9529
TEST_TIME 100 9499
TEST_TIME 101 9493
TEST_TIME 102 9488
TEST_TIME 103 9546
TEST_TIME 104 9528
TEST_TIME 105 9582
TEST_TIME 106 9562
TEST_TIME 107 9584
TEST_TIME 108 9574
TEST_TIME 109 9604
TEST_TIME 110 9590
TEST_TIME 111 9520
TEST_TIME 112 9486
TEST_TIME 113 9459
TEST_TIME 114 9442
TEST_TIME 115 9408
TEST_TIME 116 9429
TEST_TIME 117 9394
TEST_TIME 118 9367
TEST_TIME 119 9363
TEST_TIME 120 9310

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 23-06-2022 22:40:03 to 23-06-2022 22:40:06.

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 1547388 1547388 0 0.00 0 26459 6 0 0
doc_list_biud 1547388 1547388 0 0.00 0 50919 29 0 0
sp_add_invoice_to_stock 5078 5005 73 1.43 37 204687 17879 0 73
sp_cancel_adding_invoice 5787 5787 0 0.00 4 69540 820 0 0
sp_cancel_client_order 1823 1823 0 0.00 4 11573 344 0 0
sp_cancel_customer_reserve 565246 565246 0 0.00 1 50921 66 0 0
sp_cancel_pay_from_customer 710 710 0 0.00 2 6385 86 0 0
sp_cancel_pay_to_supplier 447 447 0 0.00 3 1220 50 0 0
sp_cancel_supplier_invoice 6448 6443 5 0.07 2 15479 718 0 5
sp_cancel_supplier_order 444 444 0 0.00 6 1107329 102126 0 0
sp_cancel_write_off 10492 10492 0 0.00 1 21497 104 0 0
sp_client_order 20863 20863 0 0.00 3 22246 345 0 0
sp_customer_reserve 463897 463897 0 0.00 0 64510 176 0 0
sp_fill_shopping_cart 32022 32022 0 0.00 1 16400 36 0 0
sp_fill_shopping_cart_clo_res 463893 463893 0 0.00 0 21107 14 0 0
sp_get_clo_for_invoice 5001 5001 0 0.00 0 30362 317 0 0
sp_kill_cost_storno 17436 17436 0 0.00 0 9703 55 0 0
sp_kill_qstorno_ret_qs2qd 1147871 1147871 0 0.00 0 50915 35 0 0
sp_kill_qty_storno 605296 605296 0 0.00 0 50919 74 0 0
sp_lock_selected_doc 21039 21039 0 0.00 0 9521 7 0 0
sp_make_cost_storno 28157 28157 0 0.00 0 24709 68 0 0
sp_multiply_rows_for_pdistr 28157 28157 0 0.00 0 14642 32 0 0
sp_multiply_rows_for_qdistr 20863 20863 0 0.00 0 15901 164 0 0
sp_pay_from_customer 7179 7179 0 0.00 4 20335 122 0 0
sp_pay_to_supplier 5917 5917 0 0.00 4 20019 156 0 0
sp_payment_common 13096 13096 0 0.00 1 10708 32 0 0
sp_qd_handle_on_cancel_clo 1823 1823 0 0.00 0 11564 312 0 0
sp_qd_handle_on_invoice_upd_sts 10788 10788 0 0.00 0 20538 318 0 0
sp_qd_handle_on_reserve_upd_sts 10060 10060 0 0.00 0 23336 55 0 0
sp_reserve_write_off 10060 10060 0 0.00 6 23564 193 0 0
sp_supplier_invoice 5544 5544 0 0.00 6 81709 3214 0 0
sp_supplier_order 5623 5623 0 0.00 5 34861 1194 0 0
srv_aggregate_perf_data 101 101 0 0.00 2033 307156 21066 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1901 115398 45937 0 0
srv_find_qd_qs_mism 1069220 1069220 0 0.00 0 17810 5 0 0
srv_make_invnt_saldo 580 580 0 0.00 28 93698 1167 0 0
srv_make_money_saldo 788 788 0 0.00 1 2558 25 0 0
srv_recalc_idx_stat 4 4 0 0.00 205084 289662 245248 0 0
t$perf-abend:sp_add_invoice_to_stock 73 73 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5005 5005 0 0.00 0 17623 177 0 0
t$perf-norm:sp_cancel_adding_invoice 418 418 0 0.00 0 6783 105 0 0
t$perf-norm:sp_cancel_client_order 1823 1823 0 0.00 0 1359 10 0 0
t$perf-norm:sp_cancel_customer_reserve 671 671 0 0.00 0 1733 16 0 0
t$perf-norm:sp_cancel_pay_from_customer 710 710 0 0.00 0 282 2 0 0
t$perf-norm:sp_cancel_pay_to_supplier 447 447 0 0.00 0 753 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 419 419 0 0.00 0 881 11 0 0
t$perf-norm:sp_cancel_supplier_order 444 444 0 0.00 0 27164 1568 0 0
t$perf-norm:sp_cancel_write_off 1046 1046 0 0.00 0 1064 5 0 0
t$perf-norm:sp_client_order 20863 20863 0 0.00 0 15554 20 0 0
t$perf-norm:sp_customer_reserve 1022 1022 0 0.00 0 1359 4 0 0
t$perf-norm:sp_pay_from_customer 7179 7179 0 0.00 0 15305 10 0 0
t$perf-norm:sp_pay_to_supplier 5917 5917 0 0.00 0 3381 9 0 0
t$perf-norm:sp_reserve_write_off 10060 10060 0 0.00 0 7846 12 0 0
t$perf-norm:sp_supplier_invoice 5544 5544 0 0.00 0 4692 16 0 0
t$perf-norm:sp_supplier_order 5623 5623 0 0.00 0 2704 17 0 0
t$perf-norm:srv_aggregate_perf_data 101 101 0 0.00 0 405 17 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 171 8 0 0
t$perf-norm:srv_make_invnt_saldo 580 580 0 0.00 0 2119 27 0 0
t$perf-norm:srv_make_money_saldo 788 788 0 0.00 0 1420 11 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 7 3 0 0
v_add_invoice_to_stock 10148 10002 146 1.43 0 7494 18 0 146
v_all_customers 38204 38204 0 0.00 0 52 1 0 0
v_all_suppliers 30910 30910 0 0.00 0 52 1 0 0
v_all_wares 41726 41726 0 0.00 0 71 1 0 0
v_cancel_adding_invoice 836 836 0 0.00 0 2849 9 0 0
v_cancel_client_order 3646 3646 0 0.00 0 3690 5 0 0
v_cancel_customer_prepayment 1420 1420 0 0.00 0 6381 11 0 0
v_cancel_customer_reserve 1342 1342 0 0.00 0 2716 7 0 0
v_cancel_payment_to_supplier 894 894 0 0.00 0 755 4 0 0
v_cancel_supplier_invoice 848 838 10 1.17 0 921 14 0 10
v_cancel_supplier_order 888 888 0 0.00 0 2604 6 0 0
v_cancel_write_off 2092 2092 0 0.00 0 2347 17 0 0
v_max_id_clo_ord 5620 5620 0 0.00 0 185 1 0 0
v_max_id_clo_res 1022 1022 0 0.00 0 3638 12 0 0
v_max_id_ord_sup 5539 5539 0 0.00 0 5655 5 0 0
v_max_non_paid_invoice 1621 1621 0 0.00 0 624 3 0 0
v_max_non_paid_realizn 3685 3685 0 0.00 0 5945 5 0 0
v_min_id_clo_ord 5620 5620 0 0.00 0 10629 8 0 0
v_min_id_clo_res 1022 1022 0 0.00 0 5781 13 0 0
v_min_id_ord_sup 5539 5539 0 0.00 0 10334 7 0 0
v_min_non_paid_invoice 5917 5917 0 0.00 0 6806 25 0 0
v_min_non_paid_realizn 7179 7179 0 0.00 0 10517 23 0 0
v_our_firm 10510 10510 0 0.00 0 14 1 0 0
v_reserve_write_off 20120 20120 0 0.00 0 8020 6 0 0
x_lock_depdocs_on_canc_invoice 11811 11811 0 0.00 0 18842 119 0 0
x_lock_depdocs_on_canc_sup_ord 444 444 0 0.00 0 8158 320 0 0
x_make_qstorno_1000_1200 5623 5623 0 0.00 1 34850 1112 0 0
x_make_qstorno_1200_2000 5544 5544 0 0.00 2 81696 3089 0 0
x_make_qstorno_2100_3300 463897 463897 0 0.00 0 64508 160 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 23-06-2022 22:40:07 to 23-06-2022 22:40:11.

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:27 4 8192000000 8802330272 8814891008 8131888 0.099 3 1 2 0 8240064 60032 48144
20:09:28 32589 8192000000 9911631392 10222256128 990296560 10.784 100 88 176 1 1018817680 5976512 22544608
20:11:11 28416 8192000000 9957911232 10281615360 1035828224 11.225 100 91 182 0 1068901024 9901712 23171088
20:12:45 15454 8192000000 10000977056 10337406976 1076740608 11.616 100 80 158 1 1111025072 15411840 18872624
20:14:06 13616 8192000000 10056048368 10404495360 1134508128 12.164 100 87 174 1 1168381712 12962240 20911344
20:15:26 6844 8192000000 10090726224 10456371200 1183090544 12.619 100 80 160 0 1214398176 13269648 18037984
20:16:34 3864 8192000000 10147514960 10516578304 1228577680 13.041 100 81 163 1 1260698528 13743440 18377408
20:17:40 2453 8192000000 10170573504 10550730752 1261498656 13.344 100 80 160 0 1290618704 11677648 17442400
20:18:45 1865 8192000000 10199246720 10583150592 1286825472 13.575 100 85 169 1 1316517776 10496224 19196080
20:19:49 1762 8192000000 10220433712 10603241472 1300221888 13.697 100 85 170 0 1332706576 12923024 19561664
20:20:55 1190 8192000000 10227002656 10612330496 1310000192 13.786 100 95 190 0 1343122016 11461312 21660512
20:21:57 5786 8192000000 10200136592 10607104000 1311654128 13.801 100 30 60 2 1324930304 6762192 6513984
20:23:03 10119 8192000000 10205052688 10622795776 1325129744 13.923 100 16 31 1 1334640176 5324192 4186240
20:24:17 68160 8192000000 10239003296 10652762112 1336604432 14.027 100 32 63 1 1350152992 5323712 8224848
20:26:36 4823 8192000000 10231171632 10655047680 1342783440 14.082 100 33 66 0 1357530080 7201248 7545392
20:27:42 5880 8192000000 10238026352 10648772608 1324911664 13.921 99 27 54 2 1336875712 5877248 6086800
20:28:48 9868 8192000000 10202223376 10615508992 1302635360 13.719 99 14 28 0 1311468256 5369728 3463168
20:29:58 22400 8192000000 10139937328 10541371392 1119347856 12.021 86 34 70 0 1132257024 4134944 8774224
20:31:21 6716 8192000000 10147748688 10549264384 1228028464 13.036 97 29 58 1 1240430416 5521360 6880592
20:32:29 7383 8192000000 10162662944 10562433024 1251777312 13.255 99 30 60 2 1264358736 5579760 7001664
20:33:37 16863 8192000000 10162081824 10572189696 1256461632 13.298 98 26 52 0 1268285760 4561216 7262912
20:34:57 22509 8192000000 10176447840 10588508160 1285531024 13.563 100 22 44 1 1296004336 5621184 4852128
20:36:21 8738 8192000000 10203886704 10612047872 1303142848 13.724 100 31 62 1 1316265440 5840096 7282496
20:37:36 19278 8192000000 10206690304 10623320064 1313663552 13.819 100 19 37 2 1324034176 5617872 4752752
20:39:00 18960 8192000000 10240981872 10651570176 1336817952 14.029 100 38 74 2 1352019616 6068864 9132800
20:40:24 39603 8192000000 10260572784 10680754176 1359301168 14.231 100 29 59 1 1373200368 7674976 6224224
20:42:07 58112 8192000000 10271435696 10695684096 1365725232 14.289 100 33 66 0 1386275008 12686032 7863744
20:44:11 15711 8192000000 10295292768 10721370112 1378573744 14.404 100 47 94 3 1407173872 17378176 11221952
20:45:42 8268 8192000000 10309697328 10735468544 1393807200 14.540 100 42 84 1 1422022496 18478672 9736624
20:47:01 12916 8192000000 10328144272 10748710912 1400238752 14.597 100 55 109 0 1437156688 23810528 13107408
20:48:23 4913 8192000000 10348326928 10761633792 1397639872 14.574 99 69 136 1 1446159808 31681280 16838656
20:49:30 6478 8192000000 10342941968 10762272768 1393481024 14.537 99 65 130 1 1444111744 34648096 15982624
20:50:41 4553 8192000000 10330342432 10733600768 1376989008 14.390 98 90 177 1 1435418304 37971648 20457648
20:51:51 7424 8192000000 10350693424 10771533824 1401403680 14.607 100 64 128 0 1457540208 40554576 15581952
20:53:08 5831 8192000000 10347175840 10762203136 1390640448 14.512 100 69 136 2 1451081040 43740208 16700384
20:54:20 12520 8192000000 10343917376 10766827520 1397416944 14.572 100 42 84 2 1450812352 42439120 10956288
20:55:45 5825 8192000000 10333550944 10753814528 1386336864 14.473 100 39 76 1 1442234640 45474704 10423072
20:56:57 1900 8192000000 10375314000 10773696512 1390260464 14.508 100 88 176 0 1472728864 59947344 22521056
20:58:05 3538 8192000000 10346508624 10750296064 1353676576 14.181 98 75 150 1 1436532256 63567536 19288144
20:59:15 12232 8192000000 10352365824 10769743872 1389627264 14.503 100 39 78 0 1457724880 57383024 10714592
21:00:31 4537 8192000000 10362784400 10779639808 1389078336 14.498 100 49 98 0 1468950944 65505520 14367088
21:01:38 8704 8192000000 10369352560 10784432128 1389121488 14.498 100 52 104 3 1476998128 72510752 15365888
21:03:03 23000 8192000000 10355478288 10778619904 1365381312 14.286 98 36 72 0 1441017072 65839904 9795856
21:04:29 9614 8192000000 10339855520 10779676672 1378812128 14.406 100 35 68 0 1445338560 57267696 9258736
21:05:58 25192 8192000000 10305298992 10755375104 1358764688 14.226 100 19 36 5 1411517248 46769920 5982640
21:07:36 71275 8192000000 10308527216 10746249216 1385021568 14.461 100 21 42 0 1398596176 8498672 5075936
21:09:56 76004 8192000000 10290854336 10746093568 1367670016 14.306 99 11 22 1 1381339232 11706704 1962512
21:12:17 58516 8192000000 10307890000 10750873600 1380802096 14.424 99 15 30 0 1393760272 9652480 3305696
21:14:57 112975 8192000000 10300650976 10742960128 1382754528 14.441 99 12 24 1 1397737744 11711616 3271600
21:17:53 35761 8192000000 10315116352 10757050368 1383584512 14.449 99 47 93 1 1401443024 6045488 11813024
21:19:32 90294 8192000000 10324820880 10772279296 1388127248 14.489 99 32 63 1 1409659184 14058384 7473552
21:22:05 36965 8192000000 10318837072 10775011328 1405295792 14.642 100 19 37 1 1424314192 13939696 5078704
21:23:44 24867 8192000000 10336682848 10789421056 1415572608 14.733 100 39 78 2 1445999280 20841504 9585168
21:25:12 40377 8192000000 10325986288 10795073536 1411039776 14.693 100 10 20 0 1438135776 24269072 2826928
21:27:45 35668 8192000000 10278178752 10736517120 1363400352 14.268 97 17 34 1 1379298016 11579264 4318400
21:29:36 47022 8192000000 10299760656 10765520896 1379462784 14.412 99 9 18 0 1392832400 11243376 2126240
21:31:55 60769 8192000000 10309056480 10779258880 1397976720 14.577 100 21 42 1 1426018448 22766160 5275568
21:34:16 107091 8192000000 10302503600 10751205376 1333683408 14.000 96 10 20 0 1351519648 15958432 1877808
21:37:03 62184 8192000000 10285132160 10738503680 1349196736 14.140 98 13 26 0 1373647824 21170416 3280672
21:39:06 101760 8192000000 10275967024 10730369024 1272223136 13.442 92 13 26 0 1288955248 13804080 2928032
21:41:48 86764 8192000000 10259790816 10692685824 1296053504 13.659 96 33 66 0 1313284368 9409584 7821280
21:44:16 45285 8192000000 10253311840 10699182080 1319019344 13.868 98 40 80 0 1342773072 14478224 9275504
21:46:05 46510 8192000000 10214887136 10657878016 1268042464 13.404 95 12 24 0 1275924240 5353808 2527968
21:48:05 61937 8192000000 10254865328 10703732736 1349890416 14.146 100 22 44 0 1365441872 10338272 5213184
21:50:08 113248 8192000000 10256906352 10705420288 1303450304 13.727 96 11 20 1 1316860304 10179904 3230096
21:53:57 11795 8192000000 10270782512 10726395904 1363779360 14.271 100 31 62 1 1385303680 14538528 6985792
21:55:10 28984 8192000000 10276729552 10729934848 1368563152 14.314 100 13 26 1 1385143776 13200864 3379760
21:56:39 72393 8192000000 10277663168 10743844864 1357998704 14.219 99 12 24 1 1376756864 15882784 2875376
21:58:54 66359 8192000000 10283552544 10732421120 1337210640 14.032 97 15 30 0 1350920384 9672336 4037408
22:01:02 40363 8192000000 10285932352 10743001088 1360446144 14.241 99 24 48 0 1381409840 15789248 5174448
22:02:43 38002 8192000000 10296742608 10749091840 1380276128 14.419 100 17 32 1 1395589280 11519488 3793664
22:04:22 101360 8192000000 10308121680 10757689344 1347414944 14.124 97 17 34 1 1362185136 11106480 3663712
22:07:06 60944 8192000000 10300465664 10744778752 1365761072 14.289 99 19 39 1 1382525680 12250448 4514160
22:09:23 115398 8192000000 10287846656 10738270208 1348572160 14.135 97 28 55 0 1360744064 6358880 5813024
22:12:29 91751 8192000000 10294294160 10766012416 1400129072 14.596 100 19 38 1 1420810576 16070528 4610976
22:15:01 71656 8192000000 10278929120 10746966016 1345614064 14.108 97 17 34 0 1368196208 18749504 3832640
22:17:36 58886 8192000000 10291502480 10751369216 1372962784 14.354 99 14 28 0 1390877168 14732320 3182064
22:19:36 34977 8192000000 10314917920 10750951424 1369225152 14.320 98 30 60 1 1385861808 9872672 6763984
22:21:23 75048 8192000000 10293096448 10748739584 1350983536 14.156 97 9 18 0 1367660368 14506976 2169856
22:23:51 58271 8192000000 10287925152 10746232832 1347334064 14.123 97 7 14 1 1359570080 10886544 1349472
22:25:49 40362 8192000000 10276377776 10723983360 1324010816 13.913 96 22 44 2 1340555744 11899808 4645120
22:27:31 112028 8192000000 10269934896 10730483712 1310251936 13.788 95 16 32 0 1326834496 13403792 3178768
22:30:38 46025 8192000000 10266972000 10709577728 1342670224 14.081 98 32 64 0 1359835600 10343088 6822288
22:32:25 51971 8192000000 10253200032 10708348928 1324170624 13.914 97 19 38 2 1340683696 11864928 4648144
22:34:18 58224 8192000000 10238147360 10695933952 1302516144 13.718 96 11 22 1 1315380352 9708864 3155344
22:36:27 35112 8192000000 10245840960 10699079680 1321121184 13.887 97 27 54 0 1338587392 11572032 5894176
22:38:15 18894 8192000000 9149826864 9414774784 47442896 0.575 4 1 2 0 50057584 2572336 42352

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 23-06-2022 22:40:13 to 23-06-2022 22:40:28.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 73 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 23-06-2022 22:40:29 to 23-06-2022 22:40:30.

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          244296
MON$OLDEST_ACTIVE               244297
MON$OLDEST_SNAPSHOT             244297
MON$NEXT_TRANSACTION            244297
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                       2050528
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.33603 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33603 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33603 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33603 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 ---
:336: 
:337: mscs-test-161.budzdorov.ru    Thu Jun 23 22:38:42 2022
:338:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:339: 
--- end of diff output ---

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

23.06.22 22:40:31. 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-06-23 22:40:34 No severe PSQL-related problems occured
23.06.22 22:40:35. End of report.