Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 28.12.2022 22:38
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 15234588 kB
MemAvailable 30088420 kB
Buffers 0 kB
Cached 16462664 kB
SwapCached 3556 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-12-28 22:38:27
DTS_END                         2022-12-28 22:38:27.7850
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_MAKE_MONEY_SALDO(33:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/41028
TRN_ID                          236958
ATT_ID                          681
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: 9174.44 21175 1100933
customer order: creation 168.99 367 20279
customer order: refuse 14.58 348 1750
order to supplier: creation 44.93 1298 5392
order to supplier: removal 3.54 87680 425
invoice (draft): creation 44.44 3011 5333
invoice (draft): removal 52.56 707 6307
invoice accept: apply 40.92 18552 4911
invoice accept: cancel 47.45 718 5694
customer reserve: creation 3839.92 183 460790
customer reserve: removal 4633.29 53 555995
realization accept: apply 82.58 179 9909
realization accept: cancel 73.86 112 8863
payment from customer: creation 58.38 100 7005
payment from customer: removal 6.00 69 720
payment to supplier: creation 47.86 115 5743
payment to supplier: removal 3.38 64 406
service: total inventory turnovers 4.25 1260 510
service: total monetary turnovers 6.72 23 806
service: aggregate perf. data 0.76 19459 91
service: refresh index statistics 0.03 289199 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 28-12-2022 22:39:22 to 28-12-2022 22:39:24.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2141
TEST_TIME 2 3208
TEST_TIME 3 3547
TEST_TIME 4 3969
TEST_TIME 5 3603
TEST_TIME 6 3689
TEST_TIME 7 3747
TEST_TIME 8 3727
TEST_TIME 9 4399
TEST_TIME 10 4986
TEST_TIME 11 5431
TEST_TIME 12 5645
TEST_TIME 13 6176
TEST_TIME 14 6378
TEST_TIME 15 6698
TEST_TIME 16 6744
TEST_TIME 17 6844
TEST_TIME 18 8126
TEST_TIME 19 8373
TEST_TIME 20 8930
TEST_TIME 21 9520
TEST_TIME 22 9593
TEST_TIME 23 10040
TEST_TIME 24 10408
TEST_TIME 25 10297
TEST_TIME 26 9983
TEST_TIME 27 10069
TEST_TIME 28 10142
TEST_TIME 29 9926
TEST_TIME 30 9992
TEST_TIME 31 9775
TEST_TIME 32 9660
TEST_TIME 33 9562
TEST_TIME 34 9696
TEST_TIME 35 9689
TEST_TIME 36 9463
TEST_TIME 37 9486
TEST_TIME 38 9337
TEST_TIME 39 9335
TEST_TIME 40 9342
TEST_TIME 41 9287
TEST_TIME 42 9437
TEST_TIME 43 9343
TEST_TIME 44 9391
TEST_TIME 45 9376
TEST_TIME 46 9308
TEST_TIME 47 9394
TEST_TIME 48 9453
TEST_TIME 49 9407
TEST_TIME 50 9363
TEST_TIME 51 9408
TEST_TIME 52 9318
TEST_TIME 53 9183
TEST_TIME 54 9126
TEST_TIME 55 9189
TEST_TIME 56 9101
TEST_TIME 57 9047
TEST_TIME 58 9060
TEST_TIME 59 8980
TEST_TIME 60 9041
TEST_TIME 61 8985
TEST_TIME 62 8945
TEST_TIME 63 8944
TEST_TIME 64 9017
TEST_TIME 65 9100
TEST_TIME 66 9077
TEST_TIME 67 9075
TEST_TIME 68 9220
TEST_TIME 69 9215
TEST_TIME 70 9163
TEST_TIME 71 9156
TEST_TIME 72 9188
TEST_TIME 73 9177
TEST_TIME 74 9215
TEST_TIME 75 9216
TEST_TIME 76 9224
TEST_TIME 77 9341
TEST_TIME 78 9323
TEST_TIME 79 9228
TEST_TIME 80 9166
TEST_TIME 81 9127
TEST_TIME 82 9120
TEST_TIME 83 9133
TEST_TIME 84 9094
TEST_TIME 85 9061
TEST_TIME 86 9041
TEST_TIME 87 9080
TEST_TIME 88 9061
TEST_TIME 89 9084
TEST_TIME 90 9069
TEST_TIME 91 9134
TEST_TIME 92 9135
TEST_TIME 93 9111
TEST_TIME 94 9162
TEST_TIME 95 9244
TEST_TIME 96 9226
TEST_TIME 97 9255
TEST_TIME 98 9291
TEST_TIME 99 9296
TEST_TIME 100 9348
TEST_TIME 101 9404
TEST_TIME 102 9333
TEST_TIME 103 9255
TEST_TIME 104 9215
TEST_TIME 105 9182
TEST_TIME 106 9144
TEST_TIME 107 9186
TEST_TIME 108 9198
TEST_TIME 109 9180
TEST_TIME 110 9136
TEST_TIME 111 9188
TEST_TIME 112 9145
TEST_TIME 113 9195
TEST_TIME 114 9179
TEST_TIME 115 9201
TEST_TIME 116 9218
TEST_TIME 117 9186
TEST_TIME 118 9195
TEST_TIME 119 9187
TEST_TIME 120 9178

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 28-12-2022 22:39:25 to 28-12-2022 22:39:29.

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 1526953 1526953 0 0.00 0 15561 6 0 0
doc_list_biud 1526950 1526950 0 0.00 0 28751 25 0 0
sp_add_invoice_to_stock 5005 4911 94 1.87 24 210967 18552 0 94
sp_cancel_adding_invoice 5694 5694 0 0.00 3 55706 718 0 0
sp_cancel_client_order 1750 1750 0 0.00 4 9147 349 0 0
sp_cancel_customer_reserve 555995 555995 0 0.00 0 28078 53 0 0
sp_cancel_pay_from_customer 720 720 0 0.00 3 5513 70 0 0
sp_cancel_pay_to_supplier 406 406 0 0.00 2 1836 64 0 0
sp_cancel_supplier_invoice 6318 6307 11 0.17 1 19248 707 0 11
sp_cancel_supplier_order 425 425 0 0.00 9 1113249 87680 0 0
sp_cancel_write_off 8863 8863 0 0.00 1 28882 112 0 0
sp_client_order 20279 20279 0 0.00 3 28143 367 0 0
sp_customer_reserve 460790 460790 0 0.00 0 100798 183 0 0
sp_fill_shopping_cart 30996 30996 0 0.00 1 15182 38 0 0
sp_fill_shopping_cart_clo_res 460785 460785 0 0.00 0 16646 15 0 0
sp_get_clo_for_invoice 4903 4903 0 0.00 0 31307 334 0 0
sp_kill_cost_storno 15683 15683 0 0.00 0 9966 46 0 0
sp_kill_qstorno_ret_qs2qd 1127585 1127585 0 0.00 0 28749 29 0 0
sp_kill_qty_storno 593844 593844 0 0.00 0 28751 63 0 0
sp_lock_selected_doc 20529 20529 0 0.00 0 10500 5 0 0
sp_make_cost_storno 27560 27560 0 0.00 0 12590 49 0 0
sp_multiply_rows_for_pdistr 27560 27560 0 0.00 0 8094 30 0 0
sp_multiply_rows_for_qdistr 20279 20279 0 0.00 0 28094 174 0 0
sp_pay_from_customer 7005 7005 0 0.00 4 12601 101 0 0
sp_pay_to_supplier 5743 5743 0 0.00 5 10080 116 0 0
sp_payment_common 12747 12747 0 0.00 1 8935 24 0 0
sp_qd_handle_on_cancel_clo 1750 1750 0 0.00 0 9142 310 0 0
sp_qd_handle_on_invoice_upd_sts 10597 10597 0 0.00 0 22674 291 0 0
sp_qd_handle_on_reserve_upd_sts 9907 9907 0 0.00 0 16619 60 0 0
sp_reserve_write_off 9909 9909 0 0.00 6 16636 179 0 0
sp_supplier_invoice 5333 5333 0 0.00 6 74389 3012 0 0
sp_supplier_order 5392 5392 0 0.00 5 35713 1298 0 0
srv_aggregate_perf_data 91 91 0 0.00 2387 150903 19459 0 0
srv_fill_mon_cache_memory 59 59 0 0.00 1482 205495 49568 0 0
srv_find_qd_qs_mism 1053466 1053466 0 0.00 0 15561 6 0 0
srv_make_invnt_saldo 510 510 0 0.00 28 47162 1261 0 0
srv_make_money_saldo 806 806 0 0.00 0 1608 24 0 0
srv_recalc_idx_stat 4 4 0 0.00 153151 496958 289199 0 0
t$perf-abend:sp_add_invoice_to_stock 94 94 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 4911 4911 0 0.00 0 7596 138 0 0
t$perf-norm:sp_cancel_adding_invoice 424 424 0 0.00 1 7023 82 0 0
t$perf-norm:sp_cancel_client_order 1750 1750 0 0.00 0 2972 16 0 0
t$perf-norm:sp_cancel_customer_reserve 667 667 0 0.00 0 12604 40 0 0
t$perf-norm:sp_cancel_pay_from_customer 720 720 0 0.00 0 9659 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 406 406 0 0.00 0 442 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 383 383 0 0.00 0 364 9 0 0
t$perf-norm:sp_cancel_supplier_order 425 425 0 0.00 0 28322 1316 0 0
t$perf-norm:sp_cancel_write_off 944 944 0 0.00 0 7187 18 0 0
t$perf-norm:sp_client_order 20279 20279 0 0.00 0 6373 16 0 0
t$perf-norm:sp_customer_reserve 990 990 0 0.00 0 24940 31 0 0
t$perf-norm:sp_pay_from_customer 7005 7005 0 0.00 0 19478 16 0 0
t$perf-norm:sp_pay_to_supplier 5743 5743 0 0.00 0 6421 8 0 0
t$perf-norm:sp_reserve_write_off 9909 9909 0 0.00 0 9322 10 0 0
t$perf-norm:sp_supplier_invoice 5333 5333 0 0.00 0 3186 13 0 0
t$perf-norm:sp_supplier_order 5392 5392 0 0.00 0 24193 17 0 0
t$perf-norm:srv_aggregate_perf_data 91 91 0 0.00 0 163 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 59 59 0 0.00 0 188 5 0 0
t$perf-norm:srv_make_invnt_saldo 510 510 0 0.00 0 1113 24 0 0
t$perf-norm:srv_make_money_saldo 806 806 0 0.00 0 6414 27 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 3 0 0
v_add_invoice_to_stock 9992 9804 188 1.88 0 12879 18 0 188
v_all_customers 36988 36988 0 0.00 0 234 1 0 0
v_all_suppliers 29632 29632 0 0.00 0 25 1 0 0
v_all_wares 40558 40558 0 0.00 0 164 1 0 0
v_cancel_adding_invoice 848 848 0 0.00 0 1661 9 0 0
v_cancel_client_order 3500 3500 0 0.00 0 4260 6 0 0
v_cancel_customer_prepayment 1440 1440 0 0.00 0 5495 12 0 0
v_cancel_customer_reserve 1334 1334 0 0.00 0 10970 14 0 0
v_cancel_payment_to_supplier 812 812 0 0.00 0 1478 6 0 0
v_cancel_supplier_invoice 788 766 22 2.79 0 2537 17 0 22
v_cancel_supplier_order 850 850 0 0.00 0 510 5 0 0
v_cancel_write_off 1888 1888 0 0.00 0 7310 20 0 0
v_max_id_clo_ord 5392 5392 0 0.00 0 582 1 0 0
v_max_id_clo_res 990 990 0 0.00 0 4153 16 0 0
v_max_id_ord_sup 5325 5325 0 0.00 0 5234 4 0 0
v_max_non_paid_invoice 1642 1642 0 0.00 0 4753 10 0 0
v_max_non_paid_realizn 3731 3731 0 0.00 0 4752 7 0 0
v_min_id_clo_ord 5392 5392 0 0.00 0 2750 6 0 0
v_min_id_clo_res 990 990 0 0.00 0 1741 4 0 0
v_min_id_ord_sup 5325 5325 0 0.00 0 5348 7 0 0
v_min_non_paid_invoice 5740 5740 0 0.00 0 6096 16 0 0
v_min_non_paid_realizn 7005 7005 0 0.00 0 4334 13 0 0
v_our_firm 10118 10118 0 0.00 0 53 1 0 0
v_reserve_write_off 19814 19814 0 0.00 0 10631 7 0 0
x_lock_depdocs_on_canc_invoice 11618 11618 0 0.00 0 25262 128 0 0
x_lock_depdocs_on_canc_sup_ord 425 425 0 0.00 0 15476 313 0 0
x_make_qstorno_1000_1200 5392 5392 0 0.00 1 35627 1226 0 0
x_make_qstorno_1200_2000 5333 5333 0 0.00 2 73463 2869 0 0
x_make_qstorno_2100_3300 460790 460790 0 0.00 0 100796 166 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 28-12-2022 22:39:30 to 28-12-2022 22:39:34.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
20:08:31 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:32 20685 8192000000 9899678352 10207350784 979567552 10.680 100 90 180 0 1008234384 5305216 23361616
20:10:57 21429 8192000000 9951202688 10276188160 1036746832 11.233 100 91 183 0 1067539936 7579248 23213856
20:12:24 15996 8192000000 9984093840 10319405056 1069344288 11.546 100 87 175 1 1100050528 9232736 21473504
20:13:41 7984 8192000000 10024080912 10368966656 1115615872 11.986 100 78 156 0 1144800768 10627744 18557152
20:14:51 7239 8192000000 10075706368 10432360448 1163569856 12.437 100 87 174 0 1196846864 13321280 19955728
20:16:02 5195 8192000000 10114502064 10480680960 1200374016 12.780 100 72 144 0 1229153520 12613936 16165568
20:17:10 2463 8192000000 10149886080 10520698880 1238006528 13.128 100 87 173 1 1270761424 13617184 19137712
20:18:15 1934 8192000000 10173568928 10551894016 1265286272 13.378 100 86 170 1 1296993456 13892416 17814768
20:19:18 1552 8192000000 10203085712 10589216768 1291969888 13.622 100 78 153 2 1321703392 12317744 17415760
20:20:23 16068 8192000000 10215625008 10605969408 1305078544 13.741 100 74 148 1 1333146816 12010592 16057680
20:21:42 7630 8192000000 10212093984 10616692736 1312465792 13.808 100 45 87 2 1334062352 12043168 9553392
20:22:54 5854 8192000000 10212630384 10626080768 1323942016 13.912 100 28 54 1 1337573008 7226128 6404864
20:24:03 7854 8192000000 10213908288 10632761344 1326144896 13.932 100 35 70 0 1341283072 7605600 7532576
20:25:12 19023 8192000000 10217347536 10636210176 1333997936 14.003 100 14 28 0 1342735552 5913328 2824288
20:26:33 22007 8192000000 10225028880 10651455488 1336818160 14.029 100 25 50 1 1348618368 5963216 5836992
20:27:56 2795 8192000000 10212787152 10627764224 1310549168 13.791 99 26 53 0 1322328256 5004448 6774640
20:29:01 10783 8192000000 10208474720 10623803392 1297714688 13.674 98 21 42 0 1307897648 5071616 5111344
20:30:13 16168 8192000000 10166931440 10569125888 1222438544 12.984 95 28 55 0 1234402736 5448992 6515200
20:31:29 17429 8192000000 10111048288 10508259328 1157883072 12.383 93 27 54 1 1170292112 6346928 6062112
20:32:48 3754 8192000000 10136873888 10537201664 1229527248 13.050 98 32 65 3 1242803696 6480880 6795568
20:33:52 9890 8192000000 10173924016 10573545472 1266560688 13.390 99 32 64 1 1279576448 5371504 7644256
20:35:05 11772 8192000000 10170485872 10581524480 1283297168 13.543 100 35 70 2 1297092896 5510608 8285120
20:36:21 17737 8192000000 10168919072 10590248960 1289077536 13.596 100 17 34 0 1298302896 5774176 3451184
20:37:39 6699 8192000000 10193081280 10603929600 1301247440 13.707 100 25 50 1 1312438832 5534592 5656800
20:38:56 28726 8192000000 10226974160 10635489280 1325985216 13.931 100 42 83 0 1341230880 5798384 9447280
20:40:52 28350 8192000000 10265860928 10681331712 1357047344 14.211 100 30 60 0 1377310432 12853952 7409136
20:42:23 55836 8192000000 10292182832 10711302144 1373104624 14.355 100 36 69 1 1397790208 15722832 8962752
20:45:21 32715 8192000000 10307397456 10734903296 1392266720 14.526 100 29 57 0 1420702928 21038656 7397552
20:47:00 10824 8192000000 10321818672 10750644224 1400351280 14.598 100 52 104 0 1442142336 29289024 12502032
20:48:19 8059 8192000000 10343636624 10769145856 1411239968 14.695 100 52 102 0 1453175296 28991184 12944144
20:49:31 2594 8192000000 10360157536 10774343680 1415851840 14.736 100 86 174 0 1471206096 34620176 20734080
20:50:44 1480 8192000000 10363085088 10778345472 1416192160 14.739 100 72 139 1 1467077088 33671056 17213872
20:51:48 5813 8192000000 10361144528 10779217920 1410311408 14.687 100 72 143 1 1467665392 39895216 17458768
20:52:59 1806 8192000000 10356866208 10766802944 1406747840 14.655 100 85 168 0 1465514192 37994112 20772240
20:54:06 5766 8192000000 10337147472 10752847872 1392875872 14.532 100 56 101 1 1443721984 37851072 12995040
20:55:19 3315 8192000000 10335322384 10752155648 1362563472 14.260 98 61 121 2 1426245360 46297072 17384816
20:56:24 24984 8192000000 10322220944 10760916992 1376757280 14.388 99 31 62 0 1437181344 51135072 9288992
20:57:53 8729 8192000000 10278388160 10704674816 1341309440 14.069 100 24 48 0 1387412240 39440352 6662448
20:59:10 19027 8192000000 10299656272 10732666880 1362519152 14.260 100 32 64 1 1404094432 32762720 8812560
21:00:43 29668 8192000000 10297165504 10737782784 1356394208 14.205 99 22 44 1 1383977216 22348496 5234512
21:02:16 16954 8192000000 10266598976 10701746176 1326037424 13.931 98 22 44 0 1354540672 21477840 7025408
21:03:41 20820 8192000000 10263530624 10698149888 1339150880 14.050 99 37 71 0 1363375344 14988992 9235472
21:05:19 24824 8192000000 10253716672 10693464064 1320444768 13.881 98 19 38 0 1344715696 19678768 4592160
21:06:44 56473 8192000000 10231694896 10684448768 1294957152 13.649 97 10 20 0 1317235136 19698768 2579216
21:09:09 21003 8192000000 10250121248 10688069632 1344040640 14.094 100 18 36 0 1361073248 12963712 4068896
21:11:02 70311 8192000000 10281376304 10726768640 1366673424 14.297 100 36 72 0 1389703616 14871856 8158336
21:13:19 68879 8192000000 10257379936 10710945792 1353880864 14.182 99 10 20 0 1365807936 9325696 2601376
21:15:56 62991 8192000000 10278088432 10720129024 1373303168 14.357 100 26 53 0 1387095328 7326208 6465952
21:18:01 63444 8192000000 10302630736 10746245120 1383727936 14.450 100 18 35 0 1396525840 8866224 3931680
21:20:05 74773 8192000000 10297880976 10758709248 1389741776 14.504 100 14 30 0 1406718256 13582128 3394352
21:22:20 121214 8192000000 10307397104 10748469248 1364783056 14.280 98 15 30 0 1378890128 11210224 2896848
21:25:27 85994 8192000000 10293604720 10756296704 1368166512 14.311 98 11 22 1 1383219200 12501472 2551216
21:27:58 62692 8192000000 10296883040 10740895744 1330778848 13.974 95 25 50 0 1345772528 9892832 5100848
21:30:07 95197 8192000000 10283430640 10731925504 1360544208 14.242 98 31 62 0 1374624432 7367536 6712688
21:32:49 75701 8192000000 10287259696 10747027456 1381922080 14.434 99 32 62 0 1404032352 14715840 7394432
21:35:07 24764 8192000000 10308642992 10746925056 1358828752 14.227 97 44 88 0 1383303824 14852848 9622224
21:36:32 205471 8192000000 10276338752 10727596032 1285362368 13.562 91 18 36 0 1305483936 15841872 4279696
21:41:52 82783 8192000000 10283989968 10757656576 1356190784 14.203 98 11 22 0 1373018608 14849248 1978576
21:44:19 47618 8192000000 10275696960 10723246080 1339784768 14.055 97 14 28 0 1354548064 11496528 3266768
21:46:07 74998 8192000000 10259934896 10719047680 1312113264 13.805 96 9 18 0 1331483104 16752016 2617824
21:48:22 19886 8192000000 10258726256 10691284992 1319511024 13.872 98 45 86 0 1338227200 9773664 8942512
21:49:45 13677 8192000000 10278764144 10711048192 1356474976 14.206 100 38 76 1 1375654288 11090400 8088912
21:51:01 67937 8192000000 10267652656 10706575360 1269802448 13.420 93 25 50 0 1289648352 14105440 5740464
21:53:11 32971 8192000000 10271207504 10710261760 1336267312 14.024 99 24 47 0 1350699728 9374592 5057824
21:54:47 61514 8192000000 10266163440 10702856192 1306315120 13.753 96 12 24 0 1324609008 15651264 2642624
21:56:49 101018 8192000000 10264835968 10713767936 1357726976 14.217 99 27 54 1 1372175424 8414640 6033808
21:59:32 19606 8192000000 10268769024 10716917760 1368498368 14.314 100 23 46 0 1384988640 11064640 5425632
22:01:31 32399 8192000000 10241883232 10699767808 1321101968 13.887 97 7 15 0 1338729472 16273456 1354048
22:03:15 75814 8192000000 10252436368 10699677696 1339223808 14.050 98 18 36 0 1354803472 11339872 4239792
22:06:46 45215 8192000000 10278046640 10727354368 1337965440 14.039 97 14 28 0 1349132496 7996608 3170448
22:08:47 50215 8192000000 10293471920 10756415488 1394978896 14.550 100 32 64 0 1417884112 15702032 7203184
22:10:38 47307 8192000000 10299707520 10749292544 1374961232 14.371 99 12 24 0 1386015232 8442576 2611424
22:12:27 80662 8192000000 10300579600 10769727488 1396493840 14.564 100 15 30 0 1421764944 22036720 3234384
22:14:48 10756 8192000000 10321045856 10761748480 1400617168 14.600 100 44 88 2 1419162992 9071680 9474144
22:16:04 99680 8192000000 10294593776 10755362816 1304214864 13.734 93 33 66 0 1327933584 16605408 7113312
22:18:46 117235 8192000000 10300630144 10769862656 1382634384 14.440 99 7 14 0 1399625664 15200160 1791120
22:22:23 90487 8192000000 10316808704 10769756160 1382989200 14.443 99 25 50 0 1403551200 15158992 5403008
22:27:26 48384 8192000000 10299150064 10757292032 1384615408 14.458 99 12 24 0 1399089648 12000960 2473280
22:29:19 45569 8192000000 10282773840 10750676992 1359952928 14.237 98 7 14 0 1378977376 17145392 1879056
22:31:05 30950 8192000000 10311066832 10756616192 1364060384 14.274 99 40 78 1 1393128144 19507808 9559952
22:32:37 58709 8192000000 10289027264 10737647616 1366806448 14.298 99 29 58 0 1384330560 10040304 7483808
22:34:53 50714 8192000000 10272474832 10711977984 1345754144 14.109 98 18 36 0 1358448848 8961312 3733392
22:36:46 99110 8192000000 10258091440 10710790144 1324603984 13.918 98 27 52 1 1345846368 15512800 5729584

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 28-12-2022 22:39:36 to 28-12-2022 22:39:50.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 94 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 28-12-2022 22:39:51 to 28-12-2022 22:39:53.

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          235630
MON$OLDEST_ACTIVE               237070
MON$OLDEST_SNAPSHOT             237070
MON$NEXT_TRANSACTION            237070
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                       2008512
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 ---
:806: 
:807: mscs-test-161.budzdorov.ru    Wed Dec 28 22:38:39 2022
:808:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:809: 
--- end of diff output ---

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

28.12.22 22:39:55. 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-12-28 22:39:57 No severe PSQL-related problems occured
28.12.22 22:39:58. End of report.