Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 09.03.2023 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 15158576 kB
MemAvailable 30423792 kB
Buffers 0 kB
Cached 16519316 kB
SwapCached 3028 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-03-09 01:38:40
DTS_END                         2023-03-09 01:38:40.9750
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_CLIENT_ORDER(32:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/45184
TRN_ID                          246489
ATT_ID                          688
EXC_UNIT                        2


Test workload details

SETTING_NAME SETTING_VALUE
WORKING_MODE SMALL_03
C_WARES_MAX_ID 400
C_CUSTOMER_DOC_MAX_ROWS 10
C_SUPPLIER_DOC_MAX_ROWS 50
C_CUSTOMER_DOC_MAX_QTY 15
C_SUPPLIER_DOC_MAX_QTY 50
C_NUMBER_OF_AGENTS 50

Indexes for heavy-loaded tables

TAB_NAME IDX_NAME IDX_KEY
XQD_1000_1200 XQD_1000_1200_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_ASC WORKER_ID,DOC_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_DEC WORKER_ID,DOC_ID
XQD_1200_2000 XQD_1200_2000_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2000_3300 XQD_2000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2100_3300 XQD_2100_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_3300_3400 XQD_3300_3400_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID

Performance in TOTAL

ACTION AVG_TIMES_PER_MINUTE AVG_ELAPSED_MS SUCCESSFUL_TIMES_DONE
*** OVERALL *** for 120 minutes: 9719.30 16254 1166316
customer order: creation 181.82 357 21818
customer order: refuse 15.50 358 1860
order to supplier: creation 47.99 1208 5759
order to supplier: removal 3.70 90004 444
invoice (draft): creation 48.66 3001 5839
invoice (draft): removal 55.11 764 6613
invoice accept: apply 43.61 16142 5233
invoice accept: cancel 49.91 744 5989
customer reserve: creation 4028.28 160 483394
customer reserve: removal 4935.91 52 592309
realization accept: apply 87.03 164 10444
realization accept: cancel 85.99 96 10319
payment from customer: creation 63.19 114 7583
payment from customer: removal 5.60 76 672
payment to supplier: creation 51.18 114 6142
payment to supplier: removal 3.47 79 416
service: total inventory turnovers 4.79 1126 575
service: total monetary turnovers 6.69 30 803
service: aggregate perf. data 0.83 24271 100
service: refresh index statistics 0.03 186222 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 09-03-2023 01:39:27 to 09-03-2023 01:39:29.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2718
TEST_TIME 2 2727
TEST_TIME 3 3679
TEST_TIME 4 4315
TEST_TIME 5 4343
TEST_TIME 6 4644
TEST_TIME 7 4786
TEST_TIME 8 5140
TEST_TIME 9 5579
TEST_TIME 10 5711
TEST_TIME 11 5896
TEST_TIME 12 6235
TEST_TIME 13 6393
TEST_TIME 14 6668
TEST_TIME 15 6610
TEST_TIME 16 6870
TEST_TIME 17 7202
TEST_TIME 18 7512
TEST_TIME 19 7667
TEST_TIME 20 8168
TEST_TIME 21 8205
TEST_TIME 22 8579
TEST_TIME 23 8555
TEST_TIME 24 8841
TEST_TIME 25 8870
TEST_TIME 26 9117
TEST_TIME 27 9723
TEST_TIME 28 9603
TEST_TIME 29 10224
TEST_TIME 30 10528
TEST_TIME 31 10336
TEST_TIME 32 10395
TEST_TIME 33 10510
TEST_TIME 34 10391
TEST_TIME 35 10262
TEST_TIME 36 10108
TEST_TIME 37 10088
TEST_TIME 38 10127
TEST_TIME 39 10143
TEST_TIME 40 10146
TEST_TIME 41 10127
TEST_TIME 42 10216
TEST_TIME 43 10095
TEST_TIME 44 9898
TEST_TIME 45 9785
TEST_TIME 46 9736
TEST_TIME 47 9664
TEST_TIME 48 9662
TEST_TIME 49 9641
TEST_TIME 50 9598
TEST_TIME 51 9764
TEST_TIME 52 9743
TEST_TIME 53 9702
TEST_TIME 54 9606
TEST_TIME 55 9623
TEST_TIME 56 9577
TEST_TIME 57 9543
TEST_TIME 58 9542
TEST_TIME 59 9458
TEST_TIME 60 9465
TEST_TIME 61 9493
TEST_TIME 62 9473
TEST_TIME 63 9496
TEST_TIME 64 9489
TEST_TIME 65 9596
TEST_TIME 66 9667
TEST_TIME 67 9661
TEST_TIME 68 9720
TEST_TIME 69 9759
TEST_TIME 70 9817
TEST_TIME 71 9778
TEST_TIME 72 9878
TEST_TIME 73 9850
TEST_TIME 74 9797
TEST_TIME 75 9765
TEST_TIME 76 9764
TEST_TIME 77 9774
TEST_TIME 78 9709
TEST_TIME 79 9728
TEST_TIME 80 9676
TEST_TIME 81 9634
TEST_TIME 82 9579
TEST_TIME 83 9529
TEST_TIME 84 9557
TEST_TIME 85 9550
TEST_TIME 86 9573
TEST_TIME 87 9541
TEST_TIME 88 9578
TEST_TIME 89 9598
TEST_TIME 90 9584
TEST_TIME 91 9577
TEST_TIME 92 9641
TEST_TIME 93 9600
TEST_TIME 94 9691
TEST_TIME 95 9682
TEST_TIME 96 9742
TEST_TIME 97 9790
TEST_TIME 98 9818
TEST_TIME 99 9848
TEST_TIME 100 9846
TEST_TIME 101 9788
TEST_TIME 102 9746
TEST_TIME 103 9723
TEST_TIME 104 9708
TEST_TIME 105 9682
TEST_TIME 106 9677
TEST_TIME 107 9669
TEST_TIME 108 9679
TEST_TIME 109 9634
TEST_TIME 110 9598
TEST_TIME 111 9629
TEST_TIME 112 9611
TEST_TIME 113 9599
TEST_TIME 114 9659
TEST_TIME 115 9619
TEST_TIME 116 9661
TEST_TIME 117 9632
TEST_TIME 118 9659
TEST_TIME 119 9753
TEST_TIME 120 9725

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 09-03-2023 01:39:31 to 09-03-2023 01:39:34.

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 1613934 1613934 0 0.00 0 23185 5 0 0
doc_list_biud 1613934 1613934 0 0.00 0 88656 25 0 0
sp_add_invoice_to_stock 5339 5233 106 1.98 56 185867 16143 0 106
sp_cancel_adding_invoice 5989 5989 0 0.00 4 60207 745 0 0
sp_cancel_client_order 1860 1860 0 0.00 4 8776 358 0 0
sp_cancel_customer_reserve 592309 592309 0 0.00 0 37656 53 0 0
sp_cancel_pay_from_customer 672 672 0 0.00 3 4218 77 0 0
sp_cancel_pay_to_supplier 416 416 0 0.00 3 6152 79 0 0
sp_cancel_supplier_invoice 6624 6613 11 0.16 2 88671 764 0 11
sp_cancel_supplier_order 444 444 0 0.00 6 995718 90004 0 0
sp_cancel_write_off 10319 10319 0 0.00 1 12120 96 0 0
sp_client_order 21818 21818 0 0.00 3 19430 357 0 0
sp_customer_reserve 483394 483394 0 0.00 0 129380 160 0 0
sp_fill_shopping_cart 33403 33403 0 0.00 1 11748 35 0 0
sp_fill_shopping_cart_clo_res 483392 483392 0 0.00 0 17286 13 0 0
sp_get_clo_for_invoice 5223 5223 0 0.00 0 22212 268 0 0
sp_kill_cost_storno 17396 17396 0 0.00 0 20806 48 0 0
sp_kill_qstorno_ret_qs2qd 1201994 1201994 0 0.00 0 88648 29 0 0
sp_kill_qty_storno 633198 633198 0 0.00 0 88653 63 0 0
sp_lock_selected_doc 21563 21563 0 0.00 0 9284 7 0 0
sp_make_cost_storno 29389 29389 0 0.00 0 15258 50 0 0
sp_multiply_rows_for_pdistr 29389 29389 0 0.00 0 6841 27 0 0
sp_multiply_rows_for_qdistr 21818 21818 0 0.00 0 11020 158 0 0
sp_pay_from_customer 7583 7583 0 0.00 4 13882 114 0 0
sp_pay_to_supplier 6142 6142 0 0.00 4 11576 115 0 0
sp_payment_common 13725 13725 0 0.00 1 11530 20 0 0
sp_qd_handle_on_cancel_clo 1860 1860 0 0.00 0 8771 325 0 0
sp_qd_handle_on_invoice_upd_sts 11209 11209 0 0.00 0 15467 301 0 0
sp_qd_handle_on_reserve_upd_sts 10444 10444 0 0.00 0 22128 58 0 0
sp_reserve_write_off 10444 10444 0 0.00 6 22176 165 0 0
sp_supplier_invoice 5839 5839 0 0.00 7 86108 3001 0 0
sp_supplier_order 5759 5759 0 0.00 5 22499 1208 0 0
srv_aggregate_perf_data 100 100 0 0.00 1877 742487 24272 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 868 140066 42403 0 0
srv_find_qd_qs_mism 1116167 1116167 0 0.00 0 23184 4 0 0
srv_make_invnt_saldo 575 575 0 0.00 23 108304 1126 0 0
srv_make_money_saldo 803 803 0 0.00 1 3918 30 0 0
srv_recalc_idx_stat 4 4 0 0.00 149530 248144 186223 0 0
t$perf-abend:sp_add_invoice_to_stock 106 106 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 5233 5233 0 0.00 0 20826 176 0 0
t$perf-norm:sp_cancel_adding_invoice 421 421 0 0.00 0 6910 106 0 0
t$perf-norm:sp_cancel_client_order 1860 1860 0 0.00 0 11584 23 0 0
t$perf-norm:sp_cancel_customer_reserve 679 679 0 0.00 0 1946 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 672 672 0 0.00 0 989 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 416 416 0 0.00 0 1334 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 416 416 0 0.00 0 699 14 0 0
t$perf-norm:sp_cancel_supplier_order 444 444 0 0.00 0 28027 1751 0 0
t$perf-norm:sp_cancel_write_off 991 991 0 0.00 0 2839 8 0 0
t$perf-norm:sp_client_order 21818 21818 0 0.00 0 13171 17 0 0
t$perf-norm:sp_customer_reserve 1063 1063 0 0.00 0 4746 15 0 0
t$perf-norm:sp_pay_from_customer 7583 7583 0 0.00 0 11975 17 0 0
t$perf-norm:sp_pay_to_supplier 6142 6142 0 0.00 0 5838 14 0 0
t$perf-norm:sp_reserve_write_off 10444 10444 0 0.00 0 18851 20 0 0
t$perf-norm:sp_supplier_invoice 5839 5839 0 0.00 0 10311 23 0 0
t$perf-norm:sp_supplier_order 5759 5759 0 0.00 0 6877 15 0 0
t$perf-norm:srv_aggregate_perf_data 100 100 0 0.00 0 327 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 45 2 0 0
t$perf-norm:srv_make_invnt_saldo 575 575 0 0.00 0 1296 24 0 0
t$perf-norm:srv_make_money_saldo 803 803 0 0.00 0 8740 29 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 10652 10440 212 1.99 0 7560 19 0 212
v_all_customers 39926 39926 0 0.00 0 129 1 0 0
v_all_suppliers 32230 32230 0 0.00 0 26 1 0 0
v_all_wares 43636 43636 0 0.00 0 39 1 0 0
v_cancel_adding_invoice 842 842 0 0.00 0 4867 13 0 0
v_cancel_client_order 3720 3720 0 0.00 0 2849 9 0 0
v_cancel_customer_prepayment 1344 1344 0 0.00 0 3073 12 0 0
v_cancel_customer_reserve 1358 1358 0 0.00 0 1785 6 0 0
v_cancel_payment_to_supplier 832 832 0 0.00 0 2244 9 0 0
v_cancel_supplier_invoice 854 832 22 2.57 0 2613 18 0 22
v_cancel_supplier_order 888 888 0 0.00 0 1401 5 0 0
v_cancel_write_off 1982 1982 0 0.00 0 2922 19 0 0
v_max_id_clo_ord 5759 5759 0 0.00 0 1634 2 0 0
v_max_id_clo_res 1063 1063 0 0.00 0 1637 12 0 0
v_max_id_ord_sup 5826 5826 0 0.00 0 6907 4 0 0
v_max_non_paid_invoice 1612 1612 0 0.00 0 3889 6 0 0
v_max_non_paid_realizn 3904 3904 0 0.00 0 3349 5 0 0
v_min_id_clo_ord 5759 5759 0 0.00 0 3203 6 0 0
v_min_id_clo_res 1063 1063 0 0.00 0 1532 4 0 0
v_min_id_ord_sup 5826 5826 0 0.00 0 4331 9 0 0
v_min_non_paid_invoice 6142 6142 0 0.00 0 7636 13 0 0
v_min_non_paid_realizn 7583 7583 0 0.00 0 4569 14 0 0
v_our_firm 11068 11068 0 0.00 0 37 1 0 0
v_reserve_write_off 20888 20888 0 0.00 0 4454 7 0 0
x_lock_depdocs_on_canc_invoice 12186 12186 0 0.00 0 29697 114 0 0
x_lock_depdocs_on_canc_sup_ord 444 444 0 0.00 0 6694 291 0 0
x_make_qstorno_1000_1200 5759 5759 0 0.00 1 22491 1135 0 0
x_make_qstorno_1200_2000 5839 5839 0 0.00 3 81292 2875 0 0
x_make_qstorno_2100_3300 483394 483394 0 0.00 0 129379 145 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 09-03-2023 01:39:35 to 09-03-2023 01:39:39.

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:44 2 8192000000 8799623456 8811085824 6019984 0.073 2 1 2 0 6119616 56848 42784
23:09:46 28907 8192000000 9912156608 10226659328 993885744 10.819 100 83 166 1 1022061264 6262144 21913376
23:11:26 17967 8192000000 9952964544 10275725312 1037697376 11.243 100 86 172 0 1067809040 8378304 21733360
23:12:50 20376 8192000000 9991767344 10327007232 1072990720 11.581 100 81 160 0 1102516272 9608608 19916944
23:14:14 9361 8192000000 10024583808 10375008256 1114265632 11.973 100 84 168 1 1144552144 10449040 19837472
23:15:30 6143 8192000000 10074829360 10432081920 1159443200 12.398 100 76 152 0 1189110864 12448784 17218880
23:16:37 3846 8192000000 10113466832 10479800320 1205892160 12.831 100 75 144 1 1234081920 12195216 15994544
23:17:43 2674 8192000000 10158533712 10531217408 1239629024 13.143 100 87 173 0 1273178544 14696288 18853232
23:18:47 3095 8192000000 10168758720 10548736000 1256546064 13.298 100 82 164 0 1287835280 13564544 17724672
23:19:52 1062 8192000000 10205679312 10589286400 1291093792 13.614 100 91 181 1 1324208960 12973424 20141744
23:20:55 13926 8192000000 10214719232 10600095744 1299346848 13.689 100 87 170 1 1331738656 13776512 18615296
23:22:10 3389 8192000000 10204205952 10605666304 1307354112 13.762 100 53 106 0 1333201744 14655840 11191792
23:23:22 8923 8192000000 10198412032 10616680448 1316296672 13.843 100 30 60 1 1329528912 6782192 6450048
23:24:37 13793 8192000000 10210650240 10629046272 1324634080 13.919 100 13 26 0 1333304128 5947376 2722672
23:25:51 20130 8192000000 10210667744 10636296192 1325902464 13.930 100 23 47 1 1336226480 4951600 5372416
23:27:12 8153 8192000000 10223611440 10648068096 1335682784 14.018 100 18 37 0 1345554000 5367840 4503376
23:28:20 10474 8192000000 10219295808 10636574720 1294874336 13.649 97 15 30 0 1304056000 5503200 3678464
23:29:33 68920 8192000000 10190786112 10604027904 1258687472 13.318 96 28 56 0 1274024784 8760048 6577264
23:31:45 7527 8192000000 10146663328 10547253248 1241856624 13.163 98 29 59 0 1254114272 5329280 6928368
23:32:56 9235 8192000000 10133273072 10522968064 1194101232 12.722 96 20 39 0 1204741648 6189088 4451328
23:34:06 51626 8192000000 10157994960 10566807552 1269022192 13.413 100 24 48 1 1279292160 4811392 5458576
23:35:58 7333 8192000000 10173884816 10589753344 1290194336 13.606 100 24 48 1 1301146000 5798912 5152752
23:37:08 8104 8192000000 10192750672 10605330432 1300548752 13.700 100 24 46 1 1311726400 5911264 5266384
23:38:16 8058 8192000000 10214189888 10631569408 1324064240 13.913 100 9 18 0 1330456448 4668336 1723872
23:39:35 14715 8192000000 10230405808 10643755008 1334792464 14.010 100 37 71 0 1348322000 5601632 7927904
23:40:54 12742 8192000000 10255056288 10668675072 1346483840 14.116 100 50 98 3 1369212400 11244896 11483664
23:42:16 34343 8192000000 10272033120 10695929856 1366794304 14.298 100 41 82 0 1392748240 15779712 10174224
23:43:52 17043 8192000000 10282671584 10704322560 1372982768 14.354 100 41 82 1 1398516080 15935120 9598192
23:45:15 4893 8192000000 10297615440 10720047104 1383517760 14.448 100 50 97 0 1411723712 16658224 11547728
23:46:38 25637 8192000000 10302784560 10726989824 1388618944 14.494 100 53 105 2 1420853120 20167984 12066192
23:48:09 6626 8192000000 10320623264 10744889344 1398644768 14.583 100 45 90 0 1430356512 21645264 10066480
23:49:25 1438 8192000000 10348032416 10765434880 1410801968 14.691 100 64 128 0 1452419616 26415872 15201776
23:50:32 1540 8192000000 10352951888 10769682432 1412354176 14.705 100 79 157 0 1462071600 29812720 19904704
23:51:38 2336 8192000000 10360296864 10774466560 1413516336 14.715 100 83 164 0 1463925696 30545152 19864208
23:52:45 3763 8192000000 10332698320 10747953152 1385223536 14.463 99 65 124 0 1433330896 32592896 15514464
23:53:57 1514 8192000000 10350239968 10759462912 1396650784 14.565 100 80 157 0 1453771408 37553152 19567472
23:55:04 866 8192000000 10363891968 10776678400 1411407584 14.696 100 78 157 2 1467247856 36455504 19384768
23:56:08 4854 8192000000 10340808992 10757890048 1393233456 14.535 100 62 124 0 1447461600 39013296 15214848
23:57:16 7602 8192000000 10326093504 10741653504 1378794656 14.406 100 60 119 0 1438487408 44597952 15094800
23:58:26 2563 8192000000 10329944368 10750824448 1378730112 14.405 100 49 98 0 1439645488 47472832 13442544
23:59:35 8369 8192000000 10306871440 10723557376 1351524816 14.161 99 49 92 2 1403816992 39960288 12331888
00:00:48 6140 8192000000 10320316864 10738143232 1359200928 14.230 99 44 88 0 1405041728 35084784 10756016
00:01:57 14175 8192000000 10298352656 10725621760 1333833232 14.002 97 29 58 0 1383712352 42144304 7734816
00:03:27 16269 8192000000 10296425760 10720284672 1346032848 14.112 100 22 42 2 1395925504 43284768 6607888
00:05:11 39195 8192000000 10297705504 10733629440 1362791632 14.262 100 26 52 0 1416100688 46206144 7102912
00:06:51 4632 8192000000 10298694976 10730291200 1349084608 14.139 99 40 80 0 1388264816 29240064 9940144
00:07:58 36518 8192000000 10281356944 10740355072 1357013168 14.211 99 9 18 0 1376717744 17853392 1851184
00:09:49 61737 8192000000 10296242896 10733006848 1370376784 14.330 99 23 44 0 1385110576 10437008 4296784
00:11:51 80040 8192000000 10288597840 10721366016 1338227648 14.041 97 27 54 0 1351486304 7634992 5623664
00:14:12 67401 8192000000 10293340224 10728906752 1365771248 14.289 99 28 56 0 1384654224 12224080 6658896
00:16:25 64067 8192000000 10282721392 10723250176 1340826784 14.065 97 19 38 0 1357821840 12855504 4139552
00:18:31 61358 8192000000 10294055472 10748567552 1372151920 14.346 99 11 20 0 1387708064 13162912 2393232
00:20:39 140052 8192000000 10313637920 10769108992 1396865680 14.567 100 16 32 2 1420150240 19855376 3429184
00:24:02 50357 8192000000 10328632144 10783096832 1413915488 14.719 100 37 76 0 1433976544 10927328 9133728
00:26:08 12426 8192000000 10323201136 10788728832 1411642432 14.699 100 16 33 0 1428214752 13264560 3307760
00:27:34 38259 8192000000 10328915008 10775191552 1396198944 14.561 99 22 44 0 1412289680 10099392 5991344
00:29:15 52343 8192000000 10292788176 10752589824 1372152576 14.346 98 21 42 0 1387557280 10204592 5200112
00:31:15 61741 8192000000 10305846944 10767474688 1402717088 14.619 100 10 19 0 1412727920 7952672 2058160
00:33:17 109423 8192000000 10299963952 10775273472 1397058928 14.569 99 11 22 0 1410807760 11145824 2603008
00:36:22 7095 8192000000 10322510400 10779684864 1408476864 14.670 100 31 63 0 1424972032 9727648 6767520
00:37:33 29545 8192000000 10293480512 10756943872 1384025120 14.453 99 13 26 0 1395932064 9119984 2786960
00:39:07 54781 8192000000 10304321616 10770518016 1382676240 14.440 98 14 28 1 1392779648 7510384 2593024
00:41:02 58820 8192000000 10304826224 10756866048 1323421984 13.908 94 20 40 0 1336182304 8493104 4267216
00:43:29 34198 8192000000 10284470688 10726772736 1314336464 13.825 95 14 29 0 1327979424 10624896 3018064
00:45:05 44562 8192000000 10269325024 10719195136 1333516928 13.999 98 15 30 0 1351929280 15302320 3110032
00:47:24 43798 8192000000 10256549808 10704322560 1340313248 14.060 99 33 65 0 1356959376 9684448 6961680
00:49:08 18019 8192000000 10256901680 10688212992 1322400480 13.898 99 36 72 0 1339550496 9676656 7473360
00:50:30 75710 8192000000 10256967488 10700308480 1273853344 13.457 94 13 26 0 1292910832 15742144 3315344
00:52:48 137288 8192000000 10244978048 10689368064 1289878480 13.603 96 19 38 0 1309241104 15205504 4157120
00:56:07 35269 8192000000 10252159904 10704678912 1340921552 14.066 100 25 51 1 1358087568 11367552 5798464
00:57:44 71102 8192000000 10243837664 10695581696 1324787024 13.920 98 6 12 0 1334943104 8780224 1375856
01:00:02 46823 8192000000 10251823680 10699272192 1326237728 13.933 98 25 47 0 1344121200 12814160 5069312
01:02:07 63976 8192000000 10266028496 10716848128 1336556160 14.026 99 20 40 0 1358074016 16482448 5035408
01:04:14 49774 8192000000 10247831968 10706907136 1341690144 14.073 99 9 17 0 1359652528 15823024 2139360
01:06:05 34951 8192000000 10247298320 10699464704 1320935504 13.885 98 27 53 0 1336259504 9838368 5485632
01:07:45 63448 8192000000 10289356400 10735382528 1353845728 14.182 99 24 47 0 1367944688 9134144 4964816
01:09:49 89431 8192000000 10307645952 10757038080 1372963808 14.354 99 17 34 0 1392178336 15819216 3395312
01:12:19 70371 8192000000 10297429488 10751127552 1349441824 14.142 97 18 36 0 1365793536 12571312 3780400
01:14:31 44667 8192000000 10314745552 10775089152 1377453584 14.394 99 14 28 0 1406967824 26306224 3208016
01:16:16 76258 8192000000 10315266096 10778333184 1384489840 14.457 99 14 28 0 1407464000 19981376 2992784
01:18:33 101330 8192000000 10309782096 10769874944 1397867744 14.576 100 22 44 0 1411205264 8478848 4858672
01:21:24 69261 8192000000 10317558496 10781548544 1405232432 14.642 100 16 32 0 1424029024 15288704 3507888
01:23:33 56452 8192000000 10310812400 10779664384 1392038976 14.524 99 12 24 0 1413110448 18177648 2893824
01:25:30 50309 8192000000 10284422192 10742370304 1332195424 13.987 95 19 38 0 1345498544 8710592 4592528
01:27:20 54438 8192000000 10274667136 10742427648 1366931712 14.300 99 7 14 1 1379883696 10986192 1965792
01:29:24 60324 8192000000 10277967424 10732896256 1361521152 14.251 99 7 14 0 1372423232 9411008 1491072
01:31:24 34128 8192000000 10274093168 10725011456 1310597552 13.791 95 13 26 0 1322746112 9141760 3006800
01:33:00 56747 8192000000 10256109984 10701910016 1294021488 13.641 95 25 50 0 1316498992 17066608 5410896
01:34:57 69494 8192000000 10278729344 10724487168 1324928144 13.921 97 26 51 0 1346090240 15386784 5775312
01:37:18 29193 8192000000 10270622592 10714824704 1310095712 13.787 96 28 55 0 1330156112 14029344 6031056

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 09-03-2023 01:39:41 to 09-03-2023 01:39:57.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 106 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 09-03-2023 01:39:57 to 09-03-2023 01:39:59.

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          244050
MON$OLDEST_ACTIVE               246655
MON$OLDEST_SNAPSHOT             246655
MON$NEXT_TRANSACTION            246655
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                       2041184
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.33665 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33665 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33665 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33665 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 ---
:1217: 
:1218: mscs-test-161.budzdorov.ru    Thu Mar  9 01:38:44 2023
:1219:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1220: 
:1221: 
:1222: mscs-test-161.budzdorov.ru    Thu Mar  9 01:38:48 2023
:1223:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1224: 
--- end of diff output ---

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

09.03.23 01:40:01. 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-03-09 01:40:03 No severe PSQL-related problems occured
09.03.23 01:40:05. End of report.