Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 14.07.2022 01:39
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16000712 kB
MemAvailable 31125504 kB
Buffers 0 kB
Cached 15734240 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-14 01:38:21
DTS_END                         2022-07-14 01:38:21.4130
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/46962
TRN_ID                          246051
ATT_ID                          714
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: 9510.17 19472 1141220
customer order: creation 179.30 317 21516
customer order: refuse 15.07 340 1808
order to supplier: creation 46.74 1139 5609
order to supplier: removal 3.47 103274 416
invoice (draft): creation 46.78 3147 5613
invoice (draft): removal 54.20 718 6504
invoice accept: apply 42.93 16869 5152
invoice accept: cancel 49.15 783 5898
customer reserve: creation 3976.23 166 477148
customer reserve: removal 4784.70 59 574164
realization accept: apply 88.38 182 10606
realization accept: cancel 89.78 107 10773
payment from customer: creation 61.30 108 7356
payment from customer: removal 5.58 138 670
payment to supplier: creation 50.89 137 6107
payment to supplier: removal 3.51 75 421
service: total inventory turnovers 4.78 1134 573
service: total monetary turnovers 6.61 30 793
service: aggregate perf. data 0.74 29904 89
service: refresh index statistics 0.03 230806 4

Performance in TOTAL, chart


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

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3897
TEST_TIME 2 4292
TEST_TIME 3 4206
TEST_TIME 4 5130
TEST_TIME 5 5512
TEST_TIME 6 5184
TEST_TIME 7 5720
TEST_TIME 8 5920
TEST_TIME 9 6382
TEST_TIME 10 6412
TEST_TIME 11 6370
TEST_TIME 12 6826
TEST_TIME 13 7013
TEST_TIME 14 7062
TEST_TIME 15 7221
TEST_TIME 16 7454
TEST_TIME 17 7394
TEST_TIME 18 7411
TEST_TIME 19 7438
TEST_TIME 20 7453
TEST_TIME 21 7662
TEST_TIME 22 8378
TEST_TIME 23 8737
TEST_TIME 24 8757
TEST_TIME 25 8819
TEST_TIME 26 9203
TEST_TIME 27 9749
TEST_TIME 28 10493
TEST_TIME 29 11053
TEST_TIME 30 10827
TEST_TIME 31 10999
TEST_TIME 32 10924
TEST_TIME 33 10773
TEST_TIME 34 10504
TEST_TIME 35 10496
TEST_TIME 36 10516
TEST_TIME 37 10384
TEST_TIME 38 10264
TEST_TIME 39 10272
TEST_TIME 40 10201
TEST_TIME 41 10146
TEST_TIME 42 10153
TEST_TIME 43 10186
TEST_TIME 44 10072
TEST_TIME 45 10013
TEST_TIME 46 9909
TEST_TIME 47 9971
TEST_TIME 48 10089
TEST_TIME 49 9979
TEST_TIME 50 9957
TEST_TIME 51 9781
TEST_TIME 52 9868
TEST_TIME 53 9852
TEST_TIME 54 9811
TEST_TIME 55 9858
TEST_TIME 56 9884
TEST_TIME 57 9818
TEST_TIME 58 9970
TEST_TIME 59 9994
TEST_TIME 60 9958
TEST_TIME 61 9939
TEST_TIME 62 10078
TEST_TIME 63 10151
TEST_TIME 64 10057
TEST_TIME 65 10196
TEST_TIME 66 10168
TEST_TIME 67 10086
TEST_TIME 68 9985
TEST_TIME 69 9988
TEST_TIME 70 9891
TEST_TIME 71 9835
TEST_TIME 72 9837
TEST_TIME 73 9820
TEST_TIME 74 9788
TEST_TIME 75 9771
TEST_TIME 76 9724
TEST_TIME 77 9734
TEST_TIME 78 9803
TEST_TIME 79 9778
TEST_TIME 80 9788
TEST_TIME 81 9790
TEST_TIME 82 9885
TEST_TIME 83 9839
TEST_TIME 84 9832
TEST_TIME 85 9942
TEST_TIME 86 9983
TEST_TIME 87 9954
TEST_TIME 88 10060
TEST_TIME 89 9978
TEST_TIME 90 9889
TEST_TIME 91 9889
TEST_TIME 92 9881
TEST_TIME 93 9862
TEST_TIME 94 9793
TEST_TIME 95 9756
TEST_TIME 96 9795
TEST_TIME 97 9758
TEST_TIME 98 9725
TEST_TIME 99 9748
TEST_TIME 100 9781
TEST_TIME 101 9811
TEST_TIME 102 9778
TEST_TIME 103 9778
TEST_TIME 104 9795
TEST_TIME 105 9825
TEST_TIME 106 9841
TEST_TIME 107 9812
TEST_TIME 108 9815
TEST_TIME 109 9883
TEST_TIME 110 9807
TEST_TIME 111 9776
TEST_TIME 112 9760
TEST_TIME 113 9718
TEST_TIME 114 9697
TEST_TIME 115 9655
TEST_TIME 116 9704
TEST_TIME 117 9674
TEST_TIME 118 9635
TEST_TIME 119 9578
TEST_TIME 120 9515

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 14-07-2022 01:40:02 to 14-07-2022 01:40:05.

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 1582658 1582658 0 0.00 0 18063 6 0 0
doc_list_biud 1582658 1582658 0 0.00 0 41523 27 0 0
sp_add_invoice_to_stock 5311 5152 159 2.99 27 188961 16870 0 159
sp_cancel_adding_invoice 5898 5898 0 0.00 3 82189 783 0 0
sp_cancel_client_order 1808 1808 0 0.00 4 19727 341 0 0
sp_cancel_customer_reserve 574164 574164 0 0.00 0 41524 59 0 0
sp_cancel_pay_from_customer 670 670 0 0.00 3 11646 138 0 0
sp_cancel_pay_to_supplier 421 421 0 0.00 3 8060 76 0 0
sp_cancel_supplier_invoice 6517 6504 13 0.19 1 14947 719 0 13
sp_cancel_supplier_order 416 416 0 0.00 6 1039298 103275 0 0
sp_cancel_write_off 10773 10773 0 0.00 1 7837 107 0 0
sp_client_order 21516 21516 0 0.00 3 23566 317 0 0
sp_customer_reserve 477148 477148 0 0.00 0 89952 167 0 0
sp_fill_shopping_cart 32726 32726 0 0.00 1 11042 37 0 0
sp_fill_shopping_cart_clo_res 477143 477143 0 0.00 0 12824 12 0 0
sp_get_clo_for_invoice 5147 5147 0 0.00 0 24469 281 0 0
sp_kill_cost_storno 17762 17762 0 0.00 0 7012 55 0 0
sp_kill_qstorno_ret_qs2qd 1166021 1166021 0 0.00 0 41521 32 0 0
sp_kill_qty_storno 615316 615316 0 0.00 0 41523 69 0 0
sp_lock_selected_doc 21662 21662 0 0.00 0 7279 5 0 0
sp_make_cost_storno 29216 29216 0 0.00 0 18063 56 0 0
sp_multiply_rows_for_pdistr 29216 29216 0 0.00 0 7987 27 0 0
sp_multiply_rows_for_qdistr 21516 21516 0 0.00 0 23559 141 0 0
sp_pay_from_customer 7356 7356 0 0.00 4 12257 108 0 0
sp_pay_to_supplier 6107 6107 0 0.00 4 18067 138 0 0
sp_payment_common 13463 13463 0 0.00 1 10325 28 0 0
sp_qd_handle_on_cancel_clo 1808 1808 0 0.00 0 19716 320 0 0
sp_qd_handle_on_invoice_upd_sts 11045 11045 0 0.00 0 19281 330 0 0
sp_qd_handle_on_reserve_upd_sts 10606 10606 0 0.00 0 14015 56 0 0
sp_reserve_write_off 10606 10606 0 0.00 6 15052 182 0 0
sp_supplier_invoice 5613 5613 0 0.00 5 120409 3148 0 0
sp_supplier_order 5609 5609 0 0.00 5 21180 1139 0 0
srv_aggregate_perf_data 89 89 0 0.00 3048 590956 29904 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1666 154485 45731 0 0
srv_find_qd_qs_mism 1091717 1091717 0 0.00 0 12210 5 0 0
srv_make_invnt_saldo 573 573 0 0.00 30 79169 1135 0 0
srv_make_money_saldo 793 793 0 0.00 0 1939 31 0 0
srv_recalc_idx_stat 4 4 0 0.00 123553 302689 230806 0 0
t$perf-abend:sp_add_invoice_to_stock 159 159 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5153 5153 0 0.00 0 11386 170 0 0
t$perf-norm:sp_cancel_adding_invoice 385 385 0 0.00 0 6081 153 0 0
t$perf-norm:sp_cancel_client_order 1808 1808 0 0.00 0 2996 12 0 0
t$perf-norm:sp_cancel_customer_reserve 723 723 0 0.00 0 4627 18 0 0
t$perf-norm:sp_cancel_pay_from_customer 670 670 0 0.00 0 446 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 421 421 0 0.00 0 700 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 431 431 0 0.00 0 729 17 0 0
t$perf-norm:sp_cancel_supplier_order 416 416 0 0.00 0 18488 1415 0 0
t$perf-norm:sp_cancel_write_off 1056 1056 0 0.00 0 3434 13 0 0
t$perf-norm:sp_client_order 21516 21516 0 0.00 0 6004 18 0 0
t$perf-norm:sp_customer_reserve 1050 1050 0 0.00 0 2662 8 0 0
t$perf-norm:sp_pay_from_customer 7356 7356 0 0.00 0 4287 11 0 0
t$perf-norm:sp_pay_to_supplier 6108 6108 0 0.00 0 4215 6 0 0
t$perf-norm:sp_reserve_write_off 10606 10606 0 0.00 0 7774 11 0 0
t$perf-norm:sp_supplier_invoice 5613 5613 0 0.00 0 5116 17 0 0
t$perf-norm:sp_supplier_order 5609 5609 0 0.00 0 9036 18 0 0
t$perf-norm:srv_aggregate_perf_data 89 89 0 0.00 0 73 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 330 12 0 0
t$perf-norm:srv_make_invnt_saldo 573 573 0 0.00 0 3930 31 0 0
t$perf-norm:srv_make_money_saldo 793 793 0 0.00 0 4050 22 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 30 10 0 0
v_add_invoice_to_stock 10610 10292 318 2.99 0 8325 24 0 318
v_all_customers 39276 39276 0 0.00 0 27 1 0 0
v_all_suppliers 31134 31134 0 0.00 0 36 1 0 0
v_all_wares 43032 43032 0 0.00 0 25 1 0 0
v_cancel_adding_invoice 770 770 0 0.00 0 1907 10 0 0
v_cancel_client_order 3616 3616 0 0.00 0 1596 3 0 0
v_cancel_customer_prepayment 1340 1340 0 0.00 0 4466 20 0 0
v_cancel_customer_reserve 1446 1446 0 0.00 0 1736 7 0 0
v_cancel_payment_to_supplier 842 842 0 0.00 0 8015 14 0 0
v_cancel_supplier_invoice 888 862 26 2.92 0 3173 22 0 26
v_cancel_supplier_order 832 832 0 0.00 0 2848 7 0 0
v_cancel_write_off 2112 2112 0 0.00 0 2815 14 0 0
v_max_id_clo_ord 5608 5608 0 0.00 0 635 1 0 0
v_max_id_clo_res 1050 1050 0 0.00 0 2992 18 0 0
v_max_id_ord_sup 5602 5602 0 0.00 0 3956 5 0 0
v_max_non_paid_invoice 1750 1750 0 0.00 0 4825 7 0 0
v_max_non_paid_realizn 3925 3925 0 0.00 0 4992 6 0 0
v_min_id_clo_ord 5608 5608 0 0.00 0 3421 7 0 0
v_min_id_clo_res 1050 1050 0 0.00 0 2118 5 0 0
v_min_id_ord_sup 5602 5602 0 0.00 0 6893 7 0 0
v_min_non_paid_invoice 6107 6107 0 0.00 0 5530 19 0 0
v_min_non_paid_realizn 7356 7356 0 0.00 0 10320 19 0 0
v_our_firm 10618 10618 0 0.00 0 14 1 0 0
v_reserve_write_off 21212 21212 0 0.00 0 8820 10 0 0
x_lock_depdocs_on_canc_invoice 11971 11971 0 0.00 0 16934 114 0 0
x_lock_depdocs_on_canc_sup_ord 416 416 0 0.00 0 12936 366 0 0
x_make_qstorno_1000_1200 5609 5609 0 0.00 1 21146 1063 0 0
x_make_qstorno_1200_2000 5613 5613 0 0.00 2 120399 3012 0 0
x_make_qstorno_2100_3300 477148 477148 0 0.00 0 89950 152 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 14-07-2022 01:40:06 to 14-07-2022 01:40:10.

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:25 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:26 19121 8192000000 9906959104 10215395328 982328576 10.707 100 85 170 0 1010525440 5781904 22414960
23:10:56 25436 8192000000 9945477744 10266849280 1027534864 11.145 100 87 174 0 1058427200 8404768 22487568
23:12:26 21275 8192000000 9981933472 10311741440 1061628096 11.472 100 83 162 0 1090986160 8791472 20566592
23:13:50 6756 8192000000 10038893040 10386485248 1124924784 12.073 100 83 165 0 1155517264 10426704 20165776
23:14:59 4947 8192000000 10098244160 10457071616 1174266528 12.537 100 82 164 0 1204979376 12021440 18691408
23:16:10 3263 8192000000 10126911040 10497884160 1218900496 12.952 100 83 164 0 1249107008 11805472 18401040
23:17:14 1202 8192000000 10149825184 10522017792 1238506304 13.132 100 82 159 0 1268269664 12870016 16893344
23:18:18 1419 8192000000 10191126992 10572328960 1274881328 13.466 100 84 166 0 1307647600 13952848 18813424
23:19:20 766 8192000000 10207692560 10589700096 1292561040 13.628 100 87 175 2 1326103712 14367296 19175376
23:20:21 2620 8192000000 10205270736 10606022656 1304411680 13.735 100 65 130 0 1332471280 13716864 14342736
23:21:26 8062 8192000000 10199474816 10611335168 1312542768 13.809 100 23 45 1 1323503600 5670832 5290000
23:22:34 54233 8192000000 10214923792 10627330048 1316735696 13.847 100 35 71 0 1335818096 9962384 9120016
23:24:40 3500 8192000000 10225156720 10636587008 1324863056 13.921 100 37 73 1 1339929632 5575712 9490864
23:25:45 10642 8192000000 10220150352 10640625664 1331418064 13.980 100 13 26 0 1339964416 5554416 2991936
23:26:59 5335 8192000000 10216972304 10633285632 1315142080 13.833 99 24 46 0 1326454352 6030256 5282016
23:28:08 32726 8192000000 10204864192 10620338176 1287252864 13.579 97 21 40 0 1297553344 5496752 4803728
23:29:43 8634 8192000000 10172134288 10574045184 1230597712 13.060 95 27 54 0 1243184096 6414128 6172256
23:30:53 8679 8192000000 10143405616 10546462720 1217313984 12.937 96 23 47 0 1228592672 5991344 5287344
23:32:03 50528 8192000000 10116017472 10517577728 1181742160 12.606 94 19 38 0 1190649280 4563008 4344112
23:33:54 3452 8192000000 10167589584 10566713344 1256968288 13.302 99 45 88 1 1274831424 8080992 9782144
23:35:00 11091 8192000000 10178358272 10588585984 1286676320 13.574 100 16 32 0 1296224928 5519760 4028848
23:36:12 16948 8192000000 10203647792 10613923840 1302672736 13.720 100 35 71 0 1317723008 5345424 9704848
23:38:10 4039 8192000000 10223419776 10629398528 1321432016 13.890 100 36 70 1 1335611712 6161136 8018560
23:39:23 23983 8192000000 10243915680 10657050624 1342108816 14.076 100 36 71 0 1356946704 7020880 7817008
23:41:03 19400 8192000000 10266691360 10682851328 1360460768 14.241 100 47 94 0 1384195984 13050192 10685024
23:42:29 15876 8192000000 10276482032 10697306112 1368101216 14.310 100 41 82 2 1391084400 13283984 9699200
23:43:48 2807 8192000000 10287813152 10706276352 1372965232 14.354 100 57 114 1 1397296784 12062432 12269120
23:44:57 13382 8192000000 10293457440 10711511040 1375498048 14.376 100 48 95 1 1403860032 17268448 11093536
23:46:22 12796 8192000000 10298711360 10711785472 1373291408 14.357 100 65 130 0 1408366912 20091968 14983536
23:47:40 3575 8192000000 10314649968 10733473792 1385847472 14.469 100 57 112 0 1424733232 25845968 13039792
23:48:46 10376 8192000000 10311328144 10735751168 1385726928 14.468 100 54 102 2 1422371264 24407392 12236944
23:50:12 6279 8192000000 10324374864 10738405376 1380099120 14.417 99 65 128 3 1428091184 32214832 15777232
23:51:24 1836 8192000000 10324923920 10743930880 1388456304 14.492 100 51 101 0 1431041904 30426192 12159408
23:52:30 1981 8192000000 10323062368 10741526528 1385328480 14.464 100 58 117 0 1434110432 34776160 14005792
23:53:37 3833 8192000000 10338518464 10748141568 1385088272 14.462 100 71 140 1 1443753472 41705504 16959696
23:54:52 1665 8192000000 10351699136 10761093120 1393826960 14.540 100 87 174 2 1458777696 43329776 21620960
23:56:01 2207 8192000000 10348957696 10751995904 1376812624 14.388 99 78 156 0 1446386624 51002592 18571408
23:57:09 3701 8192000000 10348038000 10754441216 1373763520 14.361 99 67 131 0 1444296016 53440656 17091840
23:58:19 2146 8192000000 10367764112 10768625664 1389124416 14.498 100 83 165 0 1473657184 63544160 20988608
23:59:26 3862 8192000000 10354727184 10760548352 1369211056 14.320 100 78 151 2 1460474640 71800672 19462912
00:00:36 12049 8192000000 10345105712 10764394496 1356672064 14.207 99 45 88 1 1447455056 78315232 12467760
00:01:49 17179 8192000000 10330455744 10754363392 1374574176 14.368 100 41 81 0 1440780640 55138400 11068064
00:03:10 14823 8192000000 10339114320 10763476992 1376863840 14.389 100 33 66 0 1447290880 60645568 9781472
00:04:47 17454 8192000000 10333794000 10780663808 1369927792 14.326 100 25 50 0 1446138720 68163520 8047408
00:06:08 32517 8192000000 10310790976 10754772992 1339341424 14.051 99 17 34 1 1407165648 62769584 5054640
00:08:00 71662 8192000000 10277569120 10711126016 1350519936 14.152 99 27 52 0 1367682064 11640992 5521136
00:10:14 94311 8192000000 10274367936 10712772608 1315940944 13.840 96 14 27 1 1337099440 18324016 2834480
00:12:50 130283 8192000000 10275345232 10732556288 1315013472 13.832 96 25 50 0 1330982832 10102960 5866400
00:16:04 86883 8192000000 10296586736 10745782272 1386670400 14.476 100 19 36 0 1404975552 14390432 3914720
00:18:32 29454 8192000000 10309363168 10756734976 1398939504 14.586 100 15 30 0 1412389488 9516528 3933456
00:20:24 5474 8192000000 10331447680 10764161024 1400250048 14.597 100 68 135 1 1429343776 13126480 15967248
00:21:30 27349 8192000000 10304927248 10765053952 1399506336 14.591 100 17 34 2 1414912912 11987664 3418912
00:24:24 47797 8192000000 10310851104 10767675392 1402948016 14.621 100 21 40 2 1425726960 17907648 4871296
00:26:39 62122 8192000000 10308822288 10776215552 1399555792 14.591 100 14 28 0 1422031520 19566992 2908736
00:28:46 70661 8192000000 10316138800 10777350144 1403994240 14.631 100 19 38 0 1415991616 7560832 4436544
00:31:02 51347 8192000000 10311636032 10780917760 1387800000 14.486 99 14 28 0 1406232144 15178576 3253568
00:32:57 80688 8192000000 10301666000 10771767296 1375391984 14.375 98 15 30 0 1394956064 16153392 3410688
00:36:00 36288 8192000000 10294476048 10753060864 1371264736 14.338 98 22 44 0 1390944816 14402976 5277104
00:37:41 50072 8192000000 10275471264 10734399488 1322126960 13.896 95 9 18 0 1334326784 10506704 1693120
00:39:31 78217 8192000000 10285352864 10747498496 1326817696 13.938 96 9 18 0 1341109168 12729264 1562208
00:41:50 41792 8192000000 10292219712 10730196992 1324308992 13.916 96 20 40 0 1336426000 8001968 4115040
00:43:36 82055 8192000000 10258694432 10711855104 1266222976 13.387 93 17 34 0 1285118416 15456400 3439040
00:45:58 64846 8192000000 10256525872 10702721024 1354333152 14.186 99 40 80 0 1371303824 8174176 8796496
00:48:28 48625 8192000000 10266945104 10707185664 1352986544 14.174 100 36 72 0 1374174928 13200384 7988000
00:50:31 51296 8192000000 10266266272 10720935936 1349693808 14.145 99 11 22 0 1369138240 16761504 2682928
00:52:22 68058 8192000000 10259583920 10714685440 1330518496 13.972 97 18 36 0 1347468048 12178208 4771344
00:54:31 60348 8192000000 10248036224 10700931072 1289488608 13.600 95 14 28 0 1306478800 13537728 3452464
00:56:31 82902 8192000000 10270880864 10730065920 1330048880 13.968 98 13 26 1 1350858064 17739040 3070144
00:58:55 34151 8192000000 10276415920 10715529216 1340396528 14.061 98 48 97 0 1366657632 15058560 11202544
01:00:34 53089 8192000000 10270089104 10720759808 1349812896 14.146 99 14 28 0 1364520688 11667232 3040560
01:02:45 106339 8192000000 10287219424 10759921664 1360483280 14.242 99 17 34 0 1384223440 19833520 3906640
01:05:32 21914 8192000000 10302529408 10751885312 1382564960 14.439 100 29 58 0 1401000912 12429808 6006144
01:07:02 135818 8192000000 10284881968 10729119744 1366936160 14.300 98 20 40 0 1389667904 18661872 4069872
01:10:20 49851 8192000000 10288781808 10763612160 1385225648 14.463 99 11 22 0 1398358432 10719440 2413344
01:12:18 83105 8192000000 10311029712 10768609280 1388104400 14.489 99 9 18 0 1400149168 9701888 2342880
01:14:46 83348 8192000000 10336116368 10783326208 1398848448 14.585 99 20 38 0 1414386160 11262864 4274848
01:17:14 67778 8192000000 10318868576 10777022464 1395967008 14.559 99 19 38 1 1417030288 16277952 4785328
01:19:28 74078 8192000000 10298598752 10751266816 1342288224 14.078 96 11 22 0 1353136096 8408480 2439392
01:21:44 42859 8192000000 10305364384 10756669440 1358391536 14.223 97 26 52 0 1375213936 10964576 5857824
01:23:36 99038 8192000000 10282699984 10741403648 1348878192 14.137 97 15 30 0 1366290976 14046064 3366720
01:26:15 43910 8192000000 10297487568 10739240960 1348695440 14.136 98 23 46 0 1370660080 16609552 5355088
01:28:00 154485 8192000000 10272173520 10716528640 1285290112 13.561 94 18 36 0 1305721136 16359776 4071248
01:31:37 26636 8192000000 10273032976 10726105088 1345030448 14.103 98 24 48 0 1364823184 14697536 5095200
01:33:24 54269 8192000000 10248837856 10708127744 1336577920 14.027 99 9 18 0 1352693120 14107888 2007312
01:35:19 42988 8192000000 10235548976 10694131712 1309833872 13.785 97 14 28 1 1319924384 7197088 2893424
01:37:02 60965 8192000000 10240512352 10685595648 1319165888 13.869 98 25 50 0 1331895952 7171712 5558352

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 14-07-2022 01:40:12 to 14-07-2022 01:40:27.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 159 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 14-07-2022 01:40:28 to 14-07-2022 01: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          244784
MON$OLDEST_ACTIVE               246191
MON$OLDEST_SNAPSHOT             246191
MON$NEXT_TRANSACTION            246191
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                       2043040
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 ---
:1057: 
:1058: mscs-test-161.budzdorov.ru    Thu Jul 14 01:38:23 2022
:1059:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1060: 
--- end of diff output ---

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

14.07.22 01: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-07-14 01:40:33 No severe PSQL-related problems occured
14.07.22 01:40:35. End of report.