Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 06.05.2022 16:43
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 16538216 kB
MemAvailable 31466204 kB
Buffers 0 kB
Cached 15139664 kB
SwapCached 2548 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-05-06 16:42:23
DTS_END                         2022-05-06 16:42:23.5410
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/47054
TRN_ID                          235966
ATT_ID                          636
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: 9498.89 22919 1139867
customer order: creation 172.67 377 20720
customer order: refuse 14.08 371 1690
order to supplier: creation 44.39 1398 5327
order to supplier: removal 3.62 103348 434
invoice (draft): creation 44.90 3004 5388
invoice (draft): removal 56.21 763 6745
invoice accept: apply 41.00 16623 4920
invoice accept: cancel 50.47 836 6056
customer reserve: creation 3817.08 162 458049
customer reserve: removal 4953.58 60 594430
realization accept: apply 83.22 185 9986
realization accept: cancel 90.43 109 10852
payment from customer: creation 58.92 110 7070
payment from customer: removal 5.41 55 649
payment to supplier: creation 48.07 112 5768
payment to supplier: removal 3.28 90 393
service: total inventory turnovers 4.25 1203 510
service: total monetary turnovers 6.41 35 769
service: aggregate perf. data 0.89 16823 107
service: refresh index statistics 0.03 312720 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 06-05-2022 16:43:23 to 06-05-2022 16:43:25.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3803
TEST_TIME 2 4227
TEST_TIME 3 4503
TEST_TIME 4 4002
TEST_TIME 5 4128
TEST_TIME 6 3932
TEST_TIME 7 3855
TEST_TIME 8 4253
TEST_TIME 9 4420
TEST_TIME 10 5001
TEST_TIME 11 5051
TEST_TIME 12 5198
TEST_TIME 13 5870
TEST_TIME 14 6534
TEST_TIME 15 6922
TEST_TIME 16 7089
TEST_TIME 17 7525
TEST_TIME 18 8069
TEST_TIME 19 8446
TEST_TIME 20 8789
TEST_TIME 21 8814
TEST_TIME 22 8849
TEST_TIME 23 8913
TEST_TIME 24 9402
TEST_TIME 25 9408
TEST_TIME 26 9520
TEST_TIME 27 10169
TEST_TIME 28 10465
TEST_TIME 29 10804
TEST_TIME 30 10671
TEST_TIME 31 10617
TEST_TIME 32 10904
TEST_TIME 33 10710
TEST_TIME 34 10707
TEST_TIME 35 10450
TEST_TIME 36 10345
TEST_TIME 37 10180
TEST_TIME 38 10078
TEST_TIME 39 10143
TEST_TIME 40 10066
TEST_TIME 41 9953
TEST_TIME 42 9856
TEST_TIME 43 9894
TEST_TIME 44 9948
TEST_TIME 45 9906
TEST_TIME 46 9814
TEST_TIME 47 9696
TEST_TIME 48 9670
TEST_TIME 49 9764
TEST_TIME 50 9794
TEST_TIME 51 9709
TEST_TIME 52 9737
TEST_TIME 53 9891
TEST_TIME 54 9863
TEST_TIME 55 9886
TEST_TIME 56 9890
TEST_TIME 57 9916
TEST_TIME 58 9932
TEST_TIME 59 10026
TEST_TIME 60 10003
TEST_TIME 61 9965
TEST_TIME 62 9849
TEST_TIME 63 9785
TEST_TIME 64 9735
TEST_TIME 65 9719
TEST_TIME 66 9666
TEST_TIME 67 9630
TEST_TIME 68 9554
TEST_TIME 69 9679
TEST_TIME 70 9705
TEST_TIME 71 9755
TEST_TIME 72 9743
TEST_TIME 73 9689
TEST_TIME 74 9670
TEST_TIME 75 9736
TEST_TIME 76 9941
TEST_TIME 77 9898
TEST_TIME 78 10035
TEST_TIME 79 10036
TEST_TIME 80 9949
TEST_TIME 81 9836
TEST_TIME 82 9828
TEST_TIME 83 9753
TEST_TIME 84 9774
TEST_TIME 85 9795
TEST_TIME 86 9751
TEST_TIME 87 9692
TEST_TIME 88 9652
TEST_TIME 89 9658
TEST_TIME 90 9626
TEST_TIME 91 9623
TEST_TIME 92 9622
TEST_TIME 93 9615
TEST_TIME 94 9606
TEST_TIME 95 9739
TEST_TIME 96 9704
TEST_TIME 97 9766
TEST_TIME 98 9753
TEST_TIME 99 9775
TEST_TIME 100 9788
TEST_TIME 101 9769
TEST_TIME 102 9775
TEST_TIME 103 9803
TEST_TIME 104 9755
TEST_TIME 105 9684
TEST_TIME 106 9654
TEST_TIME 107 9619
TEST_TIME 108 9627
TEST_TIME 109 9622
TEST_TIME 110 9581
TEST_TIME 111 9569
TEST_TIME 112 9551
TEST_TIME 113 9513
TEST_TIME 114 9479
TEST_TIME 115 9497
TEST_TIME 116 9517
TEST_TIME 117 9497
TEST_TIME 118 9530
TEST_TIME 119 9557
TEST_TIME 120 9508

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 06-05-2022 16:43:27 to 06-05-2022 16:43:30.

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 1565871 1565871 0 0.00 0 38214 6 0 0
doc_list_biud 1565871 1565871 0 0.00 0 52800 29 0 0
sp_add_invoice_to_stock 5025 4920 105 2.08 38 176789 16624 0 105
sp_cancel_adding_invoice 6056 6056 0 0.00 4 85121 836 0 0
sp_cancel_client_order 1690 1690 0 0.00 4 11764 371 0 0
sp_cancel_customer_reserve 594430 594430 0 0.00 1 37879 61 0 0
sp_cancel_pay_from_customer 649 649 0 0.00 2 1699 55 0 0
sp_cancel_pay_to_supplier 393 393 0 0.00 2 3719 91 0 0
sp_cancel_supplier_invoice 6759 6745 14 0.20 1 52804 764 0 14
sp_cancel_supplier_order 434 434 0 0.00 6 956023 103349 0 0
sp_cancel_write_off 10852 10852 0 0.00 1 15501 110 0 0
sp_client_order 20720 20720 0 0.00 3 46807 377 0 0
sp_customer_reserve 458049 458049 0 0.00 0 117592 163 0 0
sp_fill_shopping_cart 31425 31425 0 0.00 1 15380 38 0 0
sp_fill_shopping_cart_clo_res 458035 458035 0 0.00 0 36898 15 0 0
sp_get_clo_for_invoice 4905 4905 0 0.00 0 38489 323 0 0
sp_kill_cost_storno 17950 17950 0 0.00 0 10308 54 0 0
sp_kill_qstorno_ret_qs2qd 1206891 1206891 0 0.00 0 52799 33 0 0
sp_kill_qty_storno 635098 635098 0 0.00 0 52800 70 0 0
sp_lock_selected_doc 20629 20629 0 0.00 0 9310 5 0 0
sp_make_cost_storno 27729 27729 0 0.00 0 38202 56 0 0
sp_multiply_rows_for_pdistr 27729 27729 0 0.00 0 7025 26 0 0
sp_multiply_rows_for_qdistr 20720 20720 0 0.00 0 39123 195 0 0
sp_pay_from_customer 7070 7070 0 0.00 4 11712 111 0 0
sp_pay_to_supplier 5768 5768 0 0.00 4 8516 113 0 0
sp_payment_common 12838 12838 0 0.00 1 8625 26 0 0
sp_qd_handle_on_cancel_clo 1690 1690 0 0.00 0 11758 345 0 0
sp_qd_handle_on_invoice_upd_sts 10961 10961 0 0.00 0 28239 321 0 0
sp_qd_handle_on_reserve_upd_sts 9986 9986 0 0.00 0 20205 59 0 0
sp_reserve_write_off 9986 9986 0 0.00 6 38233 185 0 0
sp_supplier_invoice 5388 5388 0 0.00 7 67606 3004 0 0
sp_supplier_order 5327 5327 0 0.00 6 57976 1399 0 0
srv_aggregate_perf_data 107 107 0 0.00 2427 208065 16824 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1074 135646 46227 0 0
srv_find_qd_qs_mism 1093555 1093555 0 0.00 0 11886 4 0 0
srv_make_invnt_saldo 510 510 0 0.00 33 49669 1204 0 0
srv_make_money_saldo 769 769 0 0.00 1 8743 36 0 0
srv_recalc_idx_stat 4 4 0 0.00 94900 530109 312721 0 0
t$perf-abend:sp_add_invoice_to_stock 105 105 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4921 4921 0 0.00 0 12741 169 0 0
t$perf-norm:sp_cancel_adding_invoice 404 404 0 0.00 0 6542 115 0 0
t$perf-norm:sp_cancel_client_order 1690 1690 0 0.00 0 10100 20 0 0
t$perf-norm:sp_cancel_customer_reserve 726 726 0 0.00 0 5670 18 0 0
t$perf-norm:sp_cancel_pay_from_customer 649 649 0 0.00 0 531 2 0 0
t$perf-norm:sp_cancel_pay_to_supplier 393 393 0 0.00 0 9631 27 0 0
t$perf-norm:sp_cancel_supplier_invoice 422 422 0 0.00 0 1815 12 0 0
t$perf-norm:sp_cancel_supplier_order 434 434 0 0.00 0 23285 1819 0 0
t$perf-norm:sp_cancel_write_off 1021 1021 0 0.00 0 4187 8 0 0
t$perf-norm:sp_client_order 20720 20720 0 0.00 0 13588 18 0 0
t$perf-norm:sp_customer_reserve 973 973 0 0.00 0 10531 14 0 0
t$perf-norm:sp_pay_from_customer 7070 7070 0 0.00 0 15905 14 0 0
t$perf-norm:sp_pay_to_supplier 5768 5768 0 0.00 0 10512 11 0 0
t$perf-norm:sp_reserve_write_off 9986 9986 0 0.00 0 10585 14 0 0
t$perf-norm:sp_supplier_invoice 5388 5388 0 0.00 0 6270 18 0 0
t$perf-norm:sp_supplier_order 5327 5327 0 0.00 0 7656 15 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 431 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 110 3 0 0
t$perf-norm:srv_make_invnt_saldo 510 510 0 0.00 0 873 13 0 0
t$perf-norm:srv_make_money_saldo 769 769 0 0.00 0 4474 24 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 3 0 0
v_add_invoice_to_stock 10020 9810 210 2.09 0 6207 18 0 210
v_all_customers 38016 38016 0 0.00 0 34 0 0 0
v_all_suppliers 30232 30232 0 0.00 0 23 1 0 0
v_all_wares 41438 41438 0 0.00 0 119 1 0 0
v_cancel_adding_invoice 808 808 0 0.00 0 1979 10 0 0
v_cancel_client_order 3380 3380 0 0.00 0 4864 7 0 0
v_cancel_customer_prepayment 1298 1298 0 0.00 0 882 4 0 0
v_cancel_customer_reserve 1452 1452 0 0.00 0 3414 7 0 0
v_cancel_payment_to_supplier 786 786 0 0.00 0 2903 11 0 0
v_cancel_supplier_invoice 872 844 28 3.21 0 255 10 0 28
v_cancel_supplier_order 868 868 0 0.00 0 1796 6 0 0
v_cancel_write_off 2042 2042 0 0.00 0 2508 14 0 0
v_max_id_clo_ord 5324 5324 0 0.00 0 4616 3 0 0
v_max_id_clo_res 973 973 0 0.00 0 3472 16 0 0
v_max_id_ord_sup 5382 5382 0 0.00 0 6787 6 0 0
v_max_non_paid_invoice 1358 1358 0 0.00 0 320 2 0 0
v_max_non_paid_realizn 3667 3667 0 0.00 0 4972 5 0 0
v_min_id_clo_ord 5324 5324 0 0.00 0 4130 8 0 0
v_min_id_clo_res 973 973 0 0.00 0 2926 7 0 0
v_min_id_ord_sup 5382 5382 0 0.00 0 5022 6 0 0
v_min_non_paid_invoice 5768 5768 0 0.00 0 5778 17 0 0
v_min_non_paid_realizn 7070 7070 0 0.00 0 8617 21 0 0
v_our_firm 10228 10228 0 0.00 0 12 1 0 0
v_reserve_write_off 19970 19970 0 0.00 0 5539 6 0 0
x_lock_depdocs_on_canc_invoice 12379 12379 0 0.00 0 17421 106 0 0
x_lock_depdocs_on_canc_sup_ord 434 434 0 0.00 0 12098 337 0 0
x_make_qstorno_1000_1200 5327 5327 0 0.00 2 57916 1316 0 0
x_make_qstorno_1200_2000 5388 5388 0 0.00 4 66796 2867 0 0
x_make_qstorno_2100_3300 458049 458049 0 0.00 0 117591 145 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 06-05-2022 16:43:31 to 06-05-2022 16:43:35.

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
14:12:27 1 8192000000 8797934432 8808988672 4622400 0.056 2 1 2 0 4721888 56704 42784
14:13:28 25065 8192000000 9907781744 10220437504 984284896 10.726 100 84 169 0 1012817712 6078784 22454032
14:15:00 10788 8192000000 9953567824 10276012032 1022904016 11.100 100 92 182 2 1055007184 8680832 23422336
14:16:16 18384 8192000000 9983820896 10317012992 1057058800 11.428 100 83 166 1 1087271536 9562992 20649744
14:17:40 11820 8192000000 10026113760 10373472256 1103414912 11.870 100 77 154 1 1132703152 11298608 17989632
14:18:54 11585 8192000000 10075573136 10433347584 1150898768 12.318 100 85 171 0 1182779696 12165424 19715504
14:20:09 5212 8192000000 10134281696 10504646656 1208514528 12.855 100 77 152 1 1236884560 11449136 16920896
14:21:16 3119 8192000000 10171988768 10547965952 1243526368 13.179 100 83 167 0 1276463248 14657584 18279296
14:22:21 1992 8192000000 10205753616 10586624000 1276899536 13.485 100 92 183 1 1310574976 13504928 20170512
14:23:25 1700 8192000000 10227395184 10612326400 1294071760 13.641 100 91 183 0 1327414656 13037088 20305808
14:24:29 1244 8192000000 10223447136 10616209408 1302914048 13.722 100 76 153 0 1330881632 11626144 16341440
14:25:31 18328 8192000000 10192691936 10615119872 1303377504 13.726 100 20 41 1 1313625936 5732944 4515488
14:26:49 7638 8192000000 10199998656 10624401408 1311250128 13.797 100 22 44 1 1322664560 5602032 5812400
14:27:58 10127 8192000000 10208279136 10630541312 1313524288 13.818 100 13 24 1 1322376448 5995664 2856496
14:29:11 7451 8192000000 10215303808 10637828096 1332160400 13.987 100 23 46 0 1347718576 10223632 5334544
14:30:19 62443 8192000000 10233001248 10657619968 1342475552 14.080 100 22 44 0 1353694016 5509936 5708528
14:32:22 3568 8192000000 10232619328 10647556096 1332769856 13.992 99 26 51 2 1343735024 5220592 5744576
14:33:26 6128 8192000000 10216943696 10634969088 1321850960 13.893 99 27 54 1 1334645568 6486096 6308512
14:34:35 15402 8192000000 10155642512 10548858880 1209852448 12.868 95 28 54 3 1225997072 9545392 6599232
14:35:51 13521 8192000000 10130909248 10537709568 1216404176 12.928 97 24 48 0 1228417296 6724704 5288416
14:37:05 4344 8192000000 10138057968 10542964736 1249675776 13.235 99 30 60 0 1263026928 6576752 6774400
14:38:13 6523 8192000000 10158247792 10567655424 1269689040 13.419 100 25 50 0 1281094208 5604224 5800944
14:39:22 6747 8192000000 10181620768 10592751616 1291280320 13.616 100 29 61 1 1303502912 4912096 7310496
14:40:29 28403 8192000000 10218119776 10609401856 1297040464 13.668 100 85 173 3 1323985440 5596224 21348752
14:42:02 6615 8192000000 10203617200 10615955456 1313262560 13.816 100 26 52 0 1323899056 4975600 5660896
14:43:19 21421 8192000000 10235745376 10647687168 1333471920 13.999 100 43 86 1 1350813392 7265504 10075968
14:44:45 7274 8192000000 10253373968 10666352640 1347380608 14.124 100 50 100 0 1370817280 10905504 12531168
14:45:57 17736 8192000000 10272678752 10691588096 1364148592 14.275 100 45 88 0 1386035728 11459520 10427616
14:47:30 62621 8192000000 10305679952 10730913792 1388276592 14.490 100 30 60 0 1416291808 20106048 7909168
14:49:36 18640 8192000000 10324175008 10744909824 1394997328 14.550 100 49 98 0 1431147808 24288960 11861520
14:51:03 15155 8192000000 10324551136 10754289664 1404530992 14.635 100 40 80 0 1438792496 23975024 10286480
14:52:28 3051 8192000000 10344737568 10768445440 1416616704 14.743 100 58 115 2 1456784608 25337968 14829936
14:53:38 6743 8192000000 10350945680 10773536768 1419827216 14.771 100 61 120 0 1460933488 26337344 14768928
14:54:50 9376 8192000000 10379058640 10801426432 1429736352 14.859 100 61 122 0 1482952832 36894832 16321648
14:56:03 5100 8192000000 10374700624 10805088256 1432953920 14.887 100 67 131 2 1485558144 36088736 16515488
14:57:14 1780 8192000000 10369133200 10789031936 1415290096 14.731 99 73 140 2 1468003440 35205600 17507744
14:58:22 3045 8192000000 10374411488 10795151360 1418865296 14.763 100 73 146 2 1474786512 38017104 17904112
14:59:33 1072 8192000000 10352872544 10761293824 1387524096 14.484 100 90 180 1 1452012432 42346144 22142192
15:00:38 7149 8192000000 10338875280 10763399168 1391773856 14.522 100 56 112 1 1442139424 36196496 14169072
15:01:50 18983 8192000000 10315643104 10749366272 1378974400 14.407 100 30 60 1 1424505616 37092720 8438496
15:03:15 7973 8192000000 10318729152 10748080128 1376595360 14.386 99 40 80 1 1413589840 25980928 11013552
15:04:26 15461 8192000000 10349415536 10782617600 1413581360 14.716 100 36 72 0 1456109696 32418144 10110192
15:05:49 11792 8192000000 10340764336 10763694080 1381801056 14.433 99 38 76 1 1431848384 39211104 10836224
15:07:05 9918 8192000000 10333549936 10770927616 1388110960 14.489 99 33 66 0 1431891888 34582624 9198304
15:08:20 21030 8192000000 10314541664 10753888256 1374320880 14.366 100 29 58 0 1418210800 35869472 8020448
15:10:03 39860 8192000000 10268580592 10713911296 1330725616 13.974 99 11 22 1 1364090816 30205936 3159264
15:11:44 23791 8192000000 10272478000 10696056832 1347203936 14.122 100 44 86 3 1369695536 12304288 10187312
15:13:14 67456 8192000000 10256561760 10708963328 1316263232 13.843 97 13 26 0 1339323968 20074096 2986640
15:15:29 73654 8192000000 10269306912 10708680704 1330142256 13.968 98 22 44 0 1345428688 10761840 4524592
15:17:43 135646 8192000000 10269220608 10719346688 1339614480 14.054 98 23 46 2 1359398720 14627312 5156928
15:21:08 47673 8192000000 10270357104 10725863424 1357898576 14.218 99 38 72 6 1372951488 7712448 7340464
15:23:03 23670 8192000000 10277818880 10725494784 1372748704 14.352 100 33 63 1 1390107568 9809312 7549552
15:24:28 134897 8192000000 10273163280 10734895104 1361674128 14.252 99 13 26 0 1376645632 11937872 3033632
15:27:48 49987 8192000000 10272698800 10737381376 1364294976 14.276 99 8 16 0 1375167920 8860352 2012592
15:29:48 56149 8192000000 10295883216 10758348800 1395198992 14.552 100 17 34 0 1410463488 11351296 3913200
15:32:03 44186 8192000000 10292856736 10754797568 1391692112 14.521 100 10 20 0 1407211488 13098128 2421248
15:33:48 80921 8192000000 10315201936 10779783168 1402600560 14.618 100 33 66 0 1427585344 17449776 7535008
15:36:10 43103 8192000000 10309235088 10769166336 1404299024 14.633 100 30 58 1 1422299376 10444256 7556096
15:38:02 45896 8192000000 10302259616 10759180288 1371619040 14.342 98 28 55 3 1391215664 13052960 6543664
15:39:49 118915 8192000000 10303356416 10759688192 1315510432 13.836 94 6 12 0 1329579136 12848096 1220608
15:42:57 85660 8192000000 10284917312 10736058368 1334738176 14.010 95 19 37 1 1345550304 6985616 3826512
15:46:01 57814 8192000000 10273408880 10726645760 1322586832 13.900 95 22 44 0 1336158880 8445776 5126272
15:48:10 53284 8192000000 10291025952 10754654208 1377393200 14.393 99 15 30 0 1397111856 16346192 3372464
15:50:04 47061 8192000000 10317810272 10767835136 1363833984 14.272 98 19 38 1 1393159408 24658880 4666544
15:51:51 49791 8192000000 10285137376 10744033280 1326646256 13.937 95 14 28 0 1344569472 14475024 3448192
15:53:43 54931 8192000000 10274654608 10721562624 1304049824 13.732 95 23 46 0 1317432160 8145312 5237024
15:55:43 47640 8192000000 10301214752 10743123968 1351258480 14.159 98 21 42 0 1378116160 22039104 4818576
15:58:03 72418 8192000000 10264085888 10705481728 1302419456 13.717 96 9 18 1 1334375760 29870480 2085824
16:00:17 73807 8192000000 10277799952 10738515968 1346266896 14.114 99 10 19 1 1365008672 16751984 1989792
16:02:31 52331 8192000000 10261055488 10702966784 1332081264 13.986 98 39 78 1 1348472192 8015456 8375472
16:04:25 48724 8192000000 10269554752 10723889152 1331817488 13.984 98 19 38 0 1347784784 10961552 5005744
16:06:15 61337 8192000000 10271537456 10734256128 1350196640 14.149 99 19 38 1 1371930864 16928704 4805520
16:08:17 47389 8192000000 10270292368 10717073408 1330697952 13.973 97 15 30 0 1346019184 11081296 4239936
16:10:05 79360 8192000000 10264946608 10734522368 1360264176 14.240 99 12 25 0 1374529552 11314240 2951136
16:12:29 66186 8192000000 10271276720 10723393536 1340753520 14.064 97 12 24 2 1350867152 7506352 2607280
16:14:45 63093 8192000000 10288165760 10740240384 1357589552 14.216 98 20 40 1 1377295040 15310544 4394944
16:16:49 72358 8192000000 10302922576 10780901376 1397393136 14.572 100 19 38 2 1422064080 20454160 4216784
16:19:04 46439 8192000000 10286354064 10743701504 1368276944 14.312 99 13 25 0 1382469456 10793600 3398912
16:20:51 62723 8192000000 10300936624 10747404288 1375138320 14.373 100 31 62 0 1392901328 11037024 6725984
16:23:01 43271 8192000000 10306009424 10756845568 1363531296 14.269 98 31 62 0 1381546944 11442624 6573024
16:25:12 58841 8192000000 10301478352 10756530176 1385772960 14.468 100 23 46 1 1402294256 11549360 4971936
16:27:12 86805 8192000000 10296888688 10754379776 1387924816 14.487 100 26 52 0 1402546880 8993408 5628656
16:29:40 44099 8192000000 10294355392 10766262272 1393275392 14.535 100 16 32 1 1412596624 15706160 3615072
16:31:30 52504 8192000000 10266934672 10736099328 1355270752 14.195 98 6 12 0 1366871680 10102784 1498144
16:33:28 58680 8192000000 10284429184 10750054400 1370730384 14.334 99 9 18 0 1380567280 8065456 1771440
16:35:35 105040 8192000000 10288098944 10761355264 1373316464 14.357 99 15 29 0 1387484848 11249328 2919056
16:38:20 25140 8192000000 10293496336 10746695680 1381427344 14.429 100 18 35 1 1393723792 7458752 4837696
16:39:50 29426 8192000000 10270915216 10725539840 1341603328 14.072 98 25 50 0 1357310816 10031376 5676112
16:41:29 61172 8192000000 9289860544 9573363712 434322960 5.034 33 7 14 0 441887824 5755888 1808976

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 06-05-2022 16:43:37 to 06-05-2022 16:43:52.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 105 v_add_invoice_to_stock 335544517
except 14 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 06-05-2022 16:43:53 to 06-05-2022 16:43:55.

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          235965
MON$OLDEST_ACTIVE               236702
MON$OLDEST_SNAPSHOT             236702
MON$NEXT_TRANSACTION            236702
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                       2032224
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.33586 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33586 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33586 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33586 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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Fri May  6 16:42:33 2022
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Fri May  6 16:42:35 2022
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:13: 
:14: 
:15: mscs-test-161.budzdorov.ru    Fri May  6 16:42:35 2022
:16:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:17: 
:18: 
:19: mscs-test-161.budzdorov.ru    Fri May  6 16:42:35 2022
:20:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:21: 
:22: 
:23: mscs-test-161.budzdorov.ru    Fri May  6 16:42:36 2022
:24:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:25: 
--- end of diff output ---

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

06.05.22 16:43:56. 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-05-06 16:43:58 No severe PSQL-related problems occured
06.05.22 16:43:59. End of report.