Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 05.01.2023 16: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 15445780 kB
MemAvailable 30370224 kB
Buffers 0 kB
Cached 16257916 kB
SwapCached 4540 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 2023-01-05 16:38:35
DTS_END                         2023-01-05 16:38:35.5190
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/47384
TRN_ID                          242067
ATT_ID                          657
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: 9183.85 19946 1102062
customer order: creation 172.99 373 20759
customer order: refuse 14.78 337 1773
order to supplier: creation 45.03 1289 5404
order to supplier: removal 3.68 90472 442
invoice (draft): creation 45.48 3266 5458
invoice (draft): removal 52.78 845 6334
invoice accept: apply 41.23 19092 4948
invoice accept: cancel 47.67 798 5720
customer reserve: creation 3822.60 190 458712
customer reserve: removal 4650.59 55 558071
realization accept: apply 84.24 176 10109
realization accept: cancel 73.99 121 8879
payment from customer: creation 58.48 110 7018
payment from customer: removal 5.63 99 676
payment to supplier: creation 49.48 150 5937
payment to supplier: removal 3.39 79 407
service: total inventory turnovers 4.63 1042 556
service: total monetary turnovers 6.34 36 761
service: aggregate perf. data 0.78 22337 94
service: refresh index statistics 0.03 258053 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 05-01-2023 16:39:56 to 05-01-2023 16:39:58.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4318
TEST_TIME 2 5546
TEST_TIME 3 5657
TEST_TIME 4 5993
TEST_TIME 5 5939
TEST_TIME 6 6037
TEST_TIME 7 5752
TEST_TIME 8 5602
TEST_TIME 9 5568
TEST_TIME 10 5892
TEST_TIME 11 6155
TEST_TIME 12 6433
TEST_TIME 13 6903
TEST_TIME 14 6910
TEST_TIME 15 7026
TEST_TIME 16 7493
TEST_TIME 17 7392
TEST_TIME 18 7660
TEST_TIME 19 7689
TEST_TIME 20 7835
TEST_TIME 21 8083
TEST_TIME 22 8452
TEST_TIME 23 8775
TEST_TIME 24 9565
TEST_TIME 25 9629
TEST_TIME 26 10039
TEST_TIME 27 10779
TEST_TIME 28 10486
TEST_TIME 29 10203
TEST_TIME 30 10163
TEST_TIME 31 10125
TEST_TIME 32 10022
TEST_TIME 33 9901
TEST_TIME 34 9751
TEST_TIME 35 9645
TEST_TIME 36 9482
TEST_TIME 37 9354
TEST_TIME 38 9364
TEST_TIME 39 9323
TEST_TIME 40 9319
TEST_TIME 41 9264
TEST_TIME 42 9159
TEST_TIME 43 9220
TEST_TIME 44 9131
TEST_TIME 45 9192
TEST_TIME 46 9234
TEST_TIME 47 9360
TEST_TIME 48 9353
TEST_TIME 49 9402
TEST_TIME 50 9347
TEST_TIME 51 9502
TEST_TIME 52 9442
TEST_TIME 53 9500
TEST_TIME 54 9455
TEST_TIME 55 9405
TEST_TIME 56 9340
TEST_TIME 57 9265
TEST_TIME 58 9238
TEST_TIME 59 9206
TEST_TIME 60 9206
TEST_TIME 61 9133
TEST_TIME 62 9107
TEST_TIME 63 9022
TEST_TIME 64 9059
TEST_TIME 65 9055
TEST_TIME 66 9035
TEST_TIME 67 9073
TEST_TIME 68 9100
TEST_TIME 69 9129
TEST_TIME 70 9081
TEST_TIME 71 9080
TEST_TIME 72 9143
TEST_TIME 73 9174
TEST_TIME 74 9165
TEST_TIME 75 9167
TEST_TIME 76 9218
TEST_TIME 77 9185
TEST_TIME 78 9262
TEST_TIME 79 9318
TEST_TIME 80 9224
TEST_TIME 81 9149
TEST_TIME 82 9166
TEST_TIME 83 9096
TEST_TIME 84 9066
TEST_TIME 85 9102
TEST_TIME 86 9026
TEST_TIME 87 9020
TEST_TIME 88 9083
TEST_TIME 89 9043
TEST_TIME 90 9086
TEST_TIME 91 9082
TEST_TIME 92 9067
TEST_TIME 93 9085
TEST_TIME 94 9175
TEST_TIME 95 9136
TEST_TIME 96 9093
TEST_TIME 97 9152
TEST_TIME 98 9161
TEST_TIME 99 9169
TEST_TIME 100 9243
TEST_TIME 101 9244
TEST_TIME 102 9324
TEST_TIME 103 9325
TEST_TIME 104 9283
TEST_TIME 105 9255
TEST_TIME 106 9234
TEST_TIME 107 9197
TEST_TIME 108 9193
TEST_TIME 109 9221
TEST_TIME 110 9212
TEST_TIME 111 9191
TEST_TIME 112 9163
TEST_TIME 113 9136
TEST_TIME 114 9091
TEST_TIME 115 9100
TEST_TIME 116 9168
TEST_TIME 117 9144
TEST_TIME 118 9187
TEST_TIME 119 9222
TEST_TIME 120 9191

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 05-01-2023 16:40:00 to 05-01-2023 16:40:03.

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 1526154 1526154 0 0.00 0 25487 7 0 0
doc_list_biud 1526150 1526150 0 0.00 0 51113 26 0 0
sp_add_invoice_to_stock 5094 4948 146 2.86 19 199546 19092 0 146
sp_cancel_adding_invoice 5720 5720 0 0.00 3 61381 798 0 0
sp_cancel_client_order 1773 1773 0 0.00 4 10955 337 0 0
sp_cancel_customer_reserve 558071 558071 0 0.00 0 51114 56 0 0
sp_cancel_pay_from_customer 676 676 0 0.00 2 7389 99 0 0
sp_cancel_pay_to_supplier 407 407 0 0.00 3 4131 80 0 0
sp_cancel_supplier_invoice 6345 6334 11 0.17 1 15092 846 0 11
sp_cancel_supplier_order 442 442 0 0.00 9 1008318 90473 0 0
sp_cancel_write_off 8879 8879 0 0.00 1 8913 122 0 0
sp_client_order 20759 20759 0 0.00 3 20627 374 0 0
sp_customer_reserve 458712 458712 0 0.00 0 54179 191 0 0
sp_fill_shopping_cart 31621 31621 0 0.00 1 11871 39 0 0
sp_fill_shopping_cart_clo_res 458705 458705 0 0.00 0 10895 13 0 0
sp_get_clo_for_invoice 4937 4937 0 0.00 0 24207 305 0 0
sp_kill_cost_storno 15682 15682 0 0.00 0 6862 54 0 0
sp_kill_qstorno_ret_qs2qd 1131797 1131797 0 0.00 0 51111 31 0 0
sp_kill_qty_storno 596262 596262 0 0.00 0 51113 67 0 0
sp_lock_selected_doc 20852 20852 0 0.00 0 7765 4 0 0
sp_make_cost_storno 27998 27998 0 0.00 0 17204 52 0 0
sp_multiply_rows_for_pdistr 27998 27998 0 0.00 0 8211 26 0 0
sp_multiply_rows_for_qdistr 20759 20759 0 0.00 0 13691 153 0 0
sp_pay_from_customer 7018 7018 0 0.00 4 16408 110 0 0
sp_pay_to_supplier 5937 5937 0 0.00 4 13947 150 0 0
sp_payment_common 12955 12955 0 0.00 1 12126 32 0 0
sp_qd_handle_on_cancel_clo 1773 1773 0 0.00 0 10940 300 0 0
sp_qd_handle_on_invoice_upd_sts 10654 10654 0 0.00 0 14597 302 0 0
sp_qd_handle_on_reserve_upd_sts 10109 10109 0 0.00 0 22615 69 0 0
sp_reserve_write_off 10109 10109 0 0.00 6 22681 176 0 0
sp_supplier_invoice 5458 5458 0 0.00 6 74020 3266 0 0
sp_supplier_order 5404 5404 0 0.00 5 35201 1289 0 0
srv_aggregate_perf_data 94 94 0 0.00 2705 421653 22338 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1843 137940 45075 0 0
srv_find_qd_qs_mism 1054190 1054190 0 0.00 0 25486 6 0 0
srv_make_invnt_saldo 556 556 0 0.00 29 27405 1042 0 0
srv_make_money_saldo 761 761 0 0.00 1 3193 36 0 0
srv_recalc_idx_stat 4 4 0 0.00 136742 345045 258054 0 0
t$perf-abend:sp_add_invoice_to_stock 146 146 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4948 4948 0 0.00 0 10363 169 0 0
t$perf-norm:sp_cancel_adding_invoice 430 430 0 0.00 0 2365 77 0 0
t$perf-norm:sp_cancel_client_order 1773 1773 0 0.00 0 1042 12 0 0
t$perf-norm:sp_cancel_customer_reserve 692 692 0 0.00 0 1028 8 0 0
t$perf-norm:sp_cancel_pay_from_customer 676 676 0 0.00 0 3084 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 407 407 0 0.00 0 931 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 420 420 0 0.00 0 529 9 0 0
t$perf-norm:sp_cancel_supplier_order 442 442 0 0.00 0 28182 1485 0 0
t$perf-norm:sp_cancel_write_off 969 969 0 0.00 0 3214 15 0 0
t$perf-norm:sp_client_order 20759 20759 0 0.00 0 14008 18 0 0
t$perf-norm:sp_customer_reserve 1017 1017 0 0.00 0 1194 4 0 0
t$perf-norm:sp_pay_from_customer 7018 7018 0 0.00 0 5243 8 0 0
t$perf-norm:sp_pay_to_supplier 5937 5937 0 0.00 0 6014 10 0 0
t$perf-norm:sp_reserve_write_off 10109 10109 0 0.00 0 4826 9 0 0
t$perf-norm:sp_supplier_invoice 5458 5458 0 0.00 0 11393 21 0 0
t$perf-norm:sp_supplier_order 5404 5404 0 0.00 0 8006 16 0 0
t$perf-norm:srv_aggregate_perf_data 94 94 0 0.00 0 1100 19 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 105 2 0 0
t$perf-norm:srv_make_invnt_saldo 556 556 0 0.00 0 905 19 0 0
t$perf-norm:srv_make_money_saldo 761 761 0 0.00 0 1000 14 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 10160 9868 292 2.87 0 13121 21 0 292
v_all_customers 37696 37696 0 0.00 0 18 1 0 0
v_all_suppliers 30246 30246 0 0.00 0 20 1 0 0
v_all_wares 41518 41518 0 0.00 0 59 1 0 0
v_cancel_adding_invoice 860 860 0 0.00 0 1935 12 0 0
v_cancel_client_order 3546 3546 0 0.00 0 4434 8 0 0
v_cancel_customer_prepayment 1352 1352 0 0.00 0 7365 9 0 0
v_cancel_customer_reserve 1384 1384 0 0.00 0 2349 7 0 0
v_cancel_payment_to_supplier 814 814 0 0.00 0 805 6 0 0
v_cancel_supplier_invoice 862 840 22 2.55 0 10858 27 0 22
v_cancel_supplier_order 884 884 0 0.00 0 2146 7 0 0
v_cancel_write_off 1938 1938 0 0.00 0 6158 17 0 0
v_max_id_clo_ord 5404 5404 0 0.00 0 204 1 0 0
v_max_id_clo_res 1017 1017 0 0.00 0 5790 13 0 0
v_max_id_ord_sup 5457 5457 0 0.00 0 3977 4 0 0
v_max_non_paid_invoice 1675 1675 0 0.00 0 5785 9 0 0
v_max_non_paid_realizn 3794 3794 0 0.00 0 2151 4 0 0
v_min_id_clo_ord 5404 5404 0 0.00 0 10969 6 0 0
v_min_id_clo_res 1017 1017 0 0.00 0 473 2 0 0
v_min_id_ord_sup 5457 5457 0 0.00 0 10081 9 0 0
v_min_non_paid_invoice 5937 5937 0 0.00 0 7723 21 0 0
v_min_non_paid_realizn 7018 7018 0 0.00 0 12116 27 0 0
v_our_firm 10270 10270 0 0.00 0 26 1 0 0
v_reserve_write_off 20218 20218 0 0.00 0 12404 8 0 0
x_lock_depdocs_on_canc_invoice 11634 11634 0 0.00 0 18606 116 0 0
x_lock_depdocs_on_canc_sup_ord 442 442 0 0.00 0 7565 275 0 0
x_make_qstorno_1000_1200 5404 5404 0 0.00 1 35196 1221 0 0
x_make_qstorno_1200_2000 5458 5458 0 0.00 2 68441 3108 0 0
x_make_qstorno_2100_3300 458712 458712 0 0.00 0 54176 175 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 05-01-2023 16:40:04 to 05-01-2023 16:40:08.

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:08:39 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:09:41 25476 8192000000 9898244464 10212753408 985293328 10.736 100 85 170 1 1013909008 6002240 22613440
14:11:09 23287 8192000000 9948323728 10269061120 1030026480 11.169 100 89 178 0 1061083488 8115456 22941552
14:12:36 3640 8192000000 9978857504 10309427200 1065195952 11.506 100 85 171 0 1095910560 9735696 20978912
14:13:46 10346 8192000000 10034142688 10381443072 1114029856 11.971 100 77 154 0 1142967440 10814832 18122752
14:15:00 5143 8192000000 10081452208 10440691712 1168193232 12.480 100 74 148 0 1196755280 11356544 17205504
14:16:08 2735 8192000000 10126277840 10494980096 1211377248 12.882 100 83 166 0 1242863808 12862288 18624272
14:17:17 1342 8192000000 10161599376 10535034880 1247565808 13.216 100 92 182 0 1280719712 13717200 19436704
14:18:21 1724 8192000000 10192138176 10574966784 1276388096 13.480 100 88 174 0 1308874464 13237248 19249120
14:19:24 748 8192000000 10213659168 10598027264 1299441712 13.690 100 96 190 2 1333973200 13979072 20552416
14:20:25 2362 8192000000 10201105248 10601238528 1298653920 13.683 100 54 108 2 1318994064 8408512 11931632
14:21:31 7505 8192000000 10207211888 10622078976 1312624112 13.810 100 37 73 0 1332570736 12007008 7939616
14:22:42 7229 8192000000 10209601888 10626899968 1318365472 13.862 100 21 41 0 1329906064 6993104 4547488
14:23:50 7193 8192000000 10206398832 10624618496 1320784464 13.884 100 19 38 0 1330038640 4936432 4317744
14:24:59 32445 8192000000 10228948400 10641223680 1323901408 13.912 100 33 66 2 1337112976 4802288 8409280
14:26:32 3200 8192000000 10236125408 10649673728 1337373584 14.034 100 41 85 1 1357688192 10536800 9777808
14:27:36 6876 8192000000 10216226128 10636849152 1305611296 13.746 98 18 36 0 1315371216 6013776 3746144
14:28:43 15401 8192000000 10195387472 10621329408 1283933072 13.549 97 14 26 0 1292020096 5269072 2817952
14:29:59 7966 8192000000 10157929360 10564452352 1220833232 12.969 95 21 42 1 1231235808 5577504 4825072
14:31:07 8193 8192000000 10151615888 10557239296 1224406480 13.002 96 16 32 0 1233307840 5316784 3584576
14:32:16 67734 8192000000 10137898544 10534363136 1187964064 12.664 94 40 81 0 1202265664 4998272 9303328
14:34:24 5919 8192000000 10174058336 10579812352 1279296608 13.507 100 24 48 1 1290237568 5730896 5210064
14:35:30 5905 8192000000 10184268208 10596532224 1296739088 13.666 100 22 43 0 1307082752 5444736 4898928
14:36:39 71283 8192000000 10198234016 10616680448 1309149776 13.778 100 26 52 0 1319900768 4854656 5896336
14:38:54 10448 8192000000 10238807248 10650890240 1337534304 14.035 100 41 80 1 1353677408 7190768 8952336
14:40:13 3295 8192000000 10268102544 10677923840 1355168624 14.194 100 62 122 2 1379694912 11089184 13437104
14:41:19 14631 8192000000 10274393136 10690060288 1364997696 14.282 100 54 110 1 1388836880 11798608 12040576
14:42:40 13777 8192000000 10272793280 10701819904 1370185408 14.329 100 31 62 1 1389373104 11565248 7622448
14:44:00 24562 8192000000 10300092048 10715103232 1357008160 14.210 98 42 84 0 1382296096 15226416 10061520
14:45:30 10033 8192000000 10316378368 10739027968 1397417648 14.572 100 54 105 0 1426724528 17463424 11843456
14:46:53 13758 8192000000 10312192496 10734821376 1390390576 14.509 100 51 100 1 1420367824 18167824 11809424
14:48:11 18991 8192000000 10325578816 10753630208 1389673312 14.503 99 47 93 0 1428699360 27922208 11103840
14:49:37 15488 8192000000 10345675744 10769498112 1412730688 14.708 100 62 124 1 1458873760 30738928 15404144
14:51:00 1842 8192000000 10363187856 10772840448 1410749984 14.691 100 84 169 2 1458721664 27329632 20642048
14:52:06 2316 8192000000 10338196496 10753224704 1405475520 14.644 100 70 136 2 1449166592 26339984 17351088
14:53:12 2785 8192000000 10325664112 10731786240 1369666256 14.324 99 77 150 1 1419225664 30912976 18646432
14:54:20 7600 8192000000 10343968784 10759831552 1398829488 14.585 100 66 132 1 1450483776 35048432 16605856
14:55:36 7156 8192000000 10335197392 10755153920 1398482752 14.581 100 56 109 0 1444642944 31734528 14425664
14:56:48 5129 8192000000 10353008144 10772721664 1414914336 14.728 100 61 121 0 1460867312 29914528 16038448
14:57:57 5271 8192000000 10315725792 10727677952 1367939120 14.309 100 60 118 1 1421536608 37877088 15720400
14:59:24 5221 8192000000 10325885456 10742882304 1372806768 14.352 100 59 118 1 1431498480 42347456 16344256
15:00:34 9587 8192000000 10305631232 10726785024 1361632032 14.252 100 40 77 1 1411275088 38023392 11619664
15:01:50 26787 8192000000 10309393760 10737422336 1357070272 14.211 99 45 90 1 1412959552 43660400 12228880
15:03:19 15415 8192000000 10297650416 10738319360 1360507184 14.242 99 26 52 0 1403415616 35503744 7404688
15:04:52 58952 8192000000 10262248800 10712608768 1333745008 14.001 98 10 20 1 1356802880 20373888 2683984
15:06:52 70851 8192000000 10279097792 10711441408 1351071808 14.157 99 41 81 3 1368607584 7722080 9813696
15:09:04 30261 8192000000 10267021744 10697138176 1347696832 14.127 99 30 58 1 1361009456 7124944 6187680
15:10:36 36137 8192000000 10279530864 10732077056 1381446272 14.429 100 12 24 0 1395256784 10845408 2965104
15:12:15 53294 8192000000 10295722960 10748534784 1385535024 14.466 100 15 30 2 1404853584 15528368 3790192
15:14:09 29367 8192000000 10277270800 10723250176 1372394064 14.348 99 14 28 0 1389763072 13917200 3451808
15:15:39 65762 8192000000 10282689344 10748649472 1376944032 14.389 100 17 34 0 1399520512 17671824 4904656
15:17:51 26166 8192000000 10279199168 10731044864 1380332864 14.420 100 47 92 0 1399670832 8908320 10429648
15:19:40 37415 8192000000 10281565024 10738606080 1381487232 14.430 100 9 16 0 1390771968 7556000 1728736
15:21:18 52358 8192000000 10287017120 10741366784 1362024208 14.256 98 19 39 0 1377283344 10812992 4446144
15:23:17 75854 8192000000 10307534832 10757668864 1382869872 14.442 99 17 34 1 1401646432 14933024 3843536
15:25:50 49073 8192000000 10309397840 10755923968 1393582576 14.538 100 17 35 0 1406942528 9348816 4011136
15:27:40 50148 8192000000 10302512864 10760888320 1395664768 14.556 100 16 30 1 1408950160 10179600 3105792
15:29:34 85320 8192000000 10308595856 10763599872 1380243024 14.419 98 18 36 0 1395090288 11315408 3531856
15:32:35 137277 8192000000 10298295456 10751139840 1357838576 14.218 97 24 48 0 1372364032 9306128 5219328
15:35:52 84399 8192000000 10300880816 10761515008 1393434912 14.537 100 24 48 1 1410111824 11102768 5574144
15:38:30 76140 8192000000 10279150800 10748669952 1361084464 14.247 97 15 30 0 1373851280 9694928 3071888
15:41:35 59481 8192000000 10264339264 10730475520 1363149056 14.266 99 9 18 0 1375031440 9972592 1909792
15:43:35 19974 8192000000 10266257824 10707410944 1340934864 14.066 98 30 58 1 1358050448 10502144 6613440
15:44:56 37783 8192000000 10239869696 10672283648 1246276992 13.204 92 40 79 0 1263598784 7791136 9530656
15:46:37 60331 8192000000 10243498032 10691489792 1291925520 13.622 96 17 34 0 1307123616 11655680 3542416
15:48:41 68203 8192000000 10261057712 10704580608 1324734272 13.920 98 13 26 0 1341936000 14217360 2984368
15:50:49 24081 8192000000 10261265008 10691850240 1319709792 13.874 98 35 70 0 1340524976 13043648 7771536
15:52:30 32661 8192000000 10261973744 10696851456 1328760304 13.956 99 28 56 0 1351705232 17043136 5901792
15:54:08 84568 8192000000 10253289072 10688065536 1268859744 13.411 94 20 40 0 1283909536 10843952 4205840
15:56:36 137940 8192000000 10259625024 10718580736 1336677104 14.027 98 10 20 0 1355539136 16481536 2380496
16:00:18 89609 8192000000 10279255424 10723094528 1353237696 14.177 99 25 50 0 1368587440 9343488 6006256
16:02:48 101017 8192000000 10285099472 10752155648 1384687472 14.458 100 15 30 0 1401592432 13510272 3394688
16:05:32 53421 8192000000 10284343488 10741776384 1369429088 14.322 99 28 56 0 1392609280 16205696 6974496
16:07:30 47872 8192000000 10300980656 10756382720 1398261440 14.580 100 23 46 0 1411836384 8898736 4676208
16:09:32 49912 8192000000 10283944816 10731356160 1330451616 13.971 95 30 59 0 1343839808 7350464 6037728
16:11:23 95675 8192000000 10285948944 10757500928 1362918896 14.264 98 13 26 0 1380682816 14665792 3098128
16:14:03 17504 8192000000 10306827264 10754379776 1391928128 14.523 100 43 86 0 1411338224 9998992 9411104
16:15:40 23213 8192000000 10320212640 10765389824 1378150176 14.400 98 32 64 0 1393921024 9083472 6687376
16:17:12 62836 8192000000 10327297120 10787143680 1399659488 14.592 100 26 52 0 1426353088 20866816 5826784
16:19:52 114203 8192000000 10309056848 10783936512 1365052144 14.283 97 5 10 0 1384100912 18080672 968096
16:22:47 96814 8192000000 10302618416 10753769472 1368344576 14.312 98 22 44 1 1383242336 9235232 5662528
16:25:26 44900 8192000000 10286259376 10742771712 1377559152 14.395 99 21 42 1 1394174720 11532304 5083264
16:27:46 66130 8192000000 10264753040 10734436352 1340021776 14.058 97 6 11 0 1351893264 10940704 930784
16:29:52 70589 8192000000 10284637264 10736971776 1353756192 14.181 98 18 36 0 1368307312 10139360 4411760
16:32:07 87236 8192000000 10290540112 10753306624 1379287088 14.410 99 13 26 0 1401588832 19065120 3236624
16:34:55 43979 8192000000 10293066368 10750840832 1382942768 14.443 100 14 27 0 1400281632 14559248 2779616
16:36:40 40471 8192000000 10273096656 10715701248 1329713024 13.965 96 18 36 0 1347653648 13691920 4248704
16:38:24 21444 8192000000 9086410128 9319579648 250657184 2.968 19 2 4 0 254150832 3229056 264592

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 05-01-2023 16:40:10 to 05-01-2023 16:40:25.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 146 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 05-01-2023 16:40:26 to 05-01-2023 16:40:28.

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          242374
MON$OLDEST_ACTIVE               242375
MON$OLDEST_SNAPSHOT             242375
MON$NEXT_TRANSACTION            242375
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                       2012288
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.33650 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33650 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33650 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33650 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 ---
--- end of diff output ---

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

05.01.23 16:40:29. 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
2023-01-05 16:40:32 No severe PSQL-related problems occured
05.01.23 16:40:33. End of report.