Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 06.02.2023 16:40
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 15349952 kB
MemAvailable 30418984 kB
Buffers 0 kB
Cached 16384772 kB
SwapCached 3564 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-02-06 16:38:38
DTS_END                         2023-02-06 16:38:38.4930
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/57618
TRN_ID                          239690
ATT_ID                          713
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: 9464.17 16354 1135700
customer order: creation 177.74 343 21329
customer order: refuse 14.70 348 1764
order to supplier: creation 46.78 1239 5614
order to supplier: removal 3.30 103806 396
invoice (draft): creation 46.29 3199 5555
invoice (draft): removal 53.64 743 6437
invoice accept: apply 42.17 17138 5060
invoice accept: cancel 48.98 791 5878
customer reserve: creation 3964.58 167 475750
customer reserve: removal 4765.33 56 571839
realization accept: apply 84.71 174 10165
realization accept: cancel 84.91 110 10189
payment from customer: creation 59.05 107 7086
payment from customer: removal 5.57 72 668
payment to supplier: creation 51.23 123 6147
payment to supplier: removal 3.34 62 401
service: total inventory turnovers 4.63 1126 556
service: total monetary turnovers 6.35 28 762
service: aggregate perf. data 0.83 24532 100
service: refresh index statistics 0.03 172918 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 06-02-2023 16:40:46 to 06-02-2023 16:40:49.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3455
TEST_TIME 2 3691
TEST_TIME 3 4658
TEST_TIME 4 4125
TEST_TIME 5 4517
TEST_TIME 6 4786
TEST_TIME 7 5437
TEST_TIME 8 5579
TEST_TIME 9 5543
TEST_TIME 10 5389
TEST_TIME 11 5353
TEST_TIME 12 5656
TEST_TIME 13 5817
TEST_TIME 14 5899
TEST_TIME 15 5873
TEST_TIME 16 6010
TEST_TIME 17 6314
TEST_TIME 18 6502
TEST_TIME 19 6657
TEST_TIME 20 7144
TEST_TIME 21 8109
TEST_TIME 22 8422
TEST_TIME 23 8481
TEST_TIME 24 8762
TEST_TIME 25 8836
TEST_TIME 26 9039
TEST_TIME 27 9929
TEST_TIME 28 10754
TEST_TIME 29 10744
TEST_TIME 30 10958
TEST_TIME 31 10808
TEST_TIME 32 10620
TEST_TIME 33 10491
TEST_TIME 34 10449
TEST_TIME 35 10253
TEST_TIME 36 10332
TEST_TIME 37 10434
TEST_TIME 38 10288
TEST_TIME 39 10222
TEST_TIME 40 10064
TEST_TIME 41 10232
TEST_TIME 42 10195
TEST_TIME 43 10334
TEST_TIME 44 10246
TEST_TIME 45 10124
TEST_TIME 46 10031
TEST_TIME 47 10007
TEST_TIME 48 9853
TEST_TIME 49 9793
TEST_TIME 50 10018
TEST_TIME 51 9900
TEST_TIME 52 9840
TEST_TIME 53 9722
TEST_TIME 54 9698
TEST_TIME 55 9728
TEST_TIME 56 9667
TEST_TIME 57 9676
TEST_TIME 58 9606
TEST_TIME 59 9740
TEST_TIME 60 9841
TEST_TIME 61 9874
TEST_TIME 62 9916
TEST_TIME 63 9936
TEST_TIME 64 9885
TEST_TIME 65 9988
TEST_TIME 66 10068
TEST_TIME 67 10028
TEST_TIME 68 10082
TEST_TIME 69 10130
TEST_TIME 70 10055
TEST_TIME 71 9935
TEST_TIME 72 9901
TEST_TIME 73 9884
TEST_TIME 74 9852
TEST_TIME 75 9824
TEST_TIME 76 9800
TEST_TIME 77 9718
TEST_TIME 78 9705
TEST_TIME 79 9675
TEST_TIME 80 9643
TEST_TIME 81 9650
TEST_TIME 82 9627
TEST_TIME 83 9628
TEST_TIME 84 9640
TEST_TIME 85 9640
TEST_TIME 86 9618
TEST_TIME 87 9650
TEST_TIME 88 9627
TEST_TIME 89 9703
TEST_TIME 90 9695
TEST_TIME 91 9875
TEST_TIME 92 9855
TEST_TIME 93 9768
TEST_TIME 94 9710
TEST_TIME 95 9662
TEST_TIME 96 9664
TEST_TIME 97 9607
TEST_TIME 98 9553
TEST_TIME 99 9508
TEST_TIME 100 9492
TEST_TIME 101 9492
TEST_TIME 102 9481
TEST_TIME 103 9498
TEST_TIME 104 9493
TEST_TIME 105 9599
TEST_TIME 106 9592
TEST_TIME 107 9584
TEST_TIME 108 9545
TEST_TIME 109 9580
TEST_TIME 110 9625
TEST_TIME 111 9642
TEST_TIME 112 9694
TEST_TIME 113 9651
TEST_TIME 114 9583
TEST_TIME 115 9597
TEST_TIME 116 9611
TEST_TIME 117 9586
TEST_TIME 118 9534
TEST_TIME 119 9497
TEST_TIME 120 9469

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 06-02-2023 16:40:50 to 06-02-2023 16:40:53.

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 1574404 1574404 0 0.00 0 34389 6 0 0
doc_list_biud 1574402 1574402 0 0.00 0 76838 26 0 0
sp_add_invoice_to_stock 5173 5060 113 2.18 27 159206 17139 0 113
sp_cancel_adding_invoice 5878 5878 0 0.00 4 97077 792 0 0
sp_cancel_client_order 1764 1764 0 0.00 4 13085 349 0 0
sp_cancel_customer_reserve 571839 571839 0 0.00 0 77606 56 0 0
sp_cancel_pay_from_customer 668 668 0 0.00 2 2960 73 0 0
sp_cancel_pay_to_supplier 401 401 0 0.00 2 1549 62 0 0
sp_cancel_supplier_invoice 6447 6437 10 0.15 1 22981 743 0 10
sp_cancel_supplier_order 396 396 0 0.00 8 1028121 103806 0 0
sp_cancel_write_off 10189 10189 0 0.00 1 20513 111 0 0
sp_client_order 21329 21329 0 0.00 3 15711 343 0 0
sp_customer_reserve 475750 475750 0 0.00 0 114965 167 0 0
sp_fill_shopping_cart 32487 32487 0 0.00 1 11254 35 0 0
sp_fill_shopping_cart_clo_res 475743 475743 0 0.00 0 13201 12 0 0
sp_get_clo_for_invoice 5053 5053 0 0.00 0 23913 299 0 0
sp_kill_cost_storno 17136 17136 0 0.00 0 34381 56 0 0
sp_kill_qstorno_ret_qs2qd 1160700 1160700 0 0.00 0 76836 30 0 0
sp_kill_qty_storno 611721 611721 0 0.00 0 76838 66 0 0
sp_lock_selected_doc 20962 20962 0 0.00 0 6433 6 0 0
sp_make_cost_storno 28451 28451 0 0.00 0 22718 52 0 0
sp_multiply_rows_for_pdistr 28451 28451 0 0.00 0 10789 27 0 0
sp_multiply_rows_for_qdistr 21329 21329 0 0.00 0 11825 149 0 0
sp_pay_from_customer 7086 7086 0 0.00 4 11018 108 0 0
sp_pay_to_supplier 6147 6147 0 0.00 4 22730 124 0 0
sp_payment_common 13233 13233 0 0.00 1 10940 27 0 0
sp_qd_handle_on_cancel_clo 1764 1764 0 0.00 0 13079 321 0 0
sp_qd_handle_on_invoice_upd_sts 10931 10931 0 0.00 0 43352 328 0 0
sp_qd_handle_on_reserve_upd_sts 10165 10165 0 0.00 0 19875 53 0 0
sp_reserve_write_off 10165 10165 0 0.00 6 19890 175 0 0
sp_supplier_invoice 5555 5555 0 0.00 5 98065 3200 0 0
sp_supplier_order 5614 5614 0 0.00 5 31829 1239 0 0
srv_aggregate_perf_data 100 100 0 0.00 2638 703501 24532 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1067 150077 44578 0 0
srv_find_qd_qs_mism 1085920 1085920 0 0.00 0 22721 5 0 0
srv_make_invnt_saldo 556 556 0 0.00 22 81358 1126 0 0
srv_make_money_saldo 762 762 0 0.00 1 2463 28 0 0
srv_recalc_idx_stat 4 4 0 0.00 105952 212109 172919 0 0
t$perf-abend:sp_add_invoice_to_stock 113 113 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5062 5062 0 0.00 0 20092 186 0 0
t$perf-norm:sp_cancel_adding_invoice 430 430 0 0.00 0 7913 87 0 0
t$perf-norm:sp_cancel_client_order 1764 1764 0 0.00 0 10912 25 0 0
t$perf-norm:sp_cancel_customer_reserve 715 715 0 0.00 0 1310 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 668 668 0 0.00 0 1623 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 401 401 0 0.00 0 1097 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 386 386 0 0.00 0 975 16 0 0
t$perf-norm:sp_cancel_supplier_order 396 396 0 0.00 0 29298 1868 0 0
t$perf-norm:sp_cancel_write_off 984 984 0 0.00 0 4561 9 0 0
t$perf-norm:sp_client_order 21329 21329 0 0.00 0 11910 19 0 0
t$perf-norm:sp_customer_reserve 1056 1056 0 0.00 0 22671 31 0 0
t$perf-norm:sp_pay_from_customer 7086 7086 0 0.00 0 13553 12 0 0
t$perf-norm:sp_pay_to_supplier 6147 6147 0 0.00 0 23088 20 0 0
t$perf-norm:sp_reserve_write_off 10165 10165 0 0.00 0 23086 13 0 0
t$perf-norm:sp_supplier_invoice 5555 5555 0 0.00 0 8454 20 0 0
t$perf-norm:sp_supplier_order 5614 5614 0 0.00 0 12927 18 0 0
t$perf-norm:srv_aggregate_perf_data 100 100 0 0.00 0 28 1 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 38 2 0 0
t$perf-norm:srv_make_invnt_saldo 556 556 0 0.00 0 1783 22 0 0
t$perf-norm:srv_make_money_saldo 762 762 0 0.00 0 862 16 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 128 34 0 0
v_add_invoice_to_stock 10332 10106 226 2.18 0 8270 19 0 226
v_all_customers 38766 38766 0 0.00 0 23 1 0 0
v_all_suppliers 31590 31590 0 0.00 0 22 1 0 0
v_all_wares 42658 42658 0 0.00 0 30 1 0 0
v_cancel_adding_invoice 860 860 0 0.00 0 3269 23 0 0
v_cancel_client_order 3528 3528 0 0.00 0 3551 7 0 0
v_cancel_customer_prepayment 1336 1336 0 0.00 0 2929 7 0 0
v_cancel_customer_reserve 1430 1430 0 0.00 0 1733 5 0 0
v_cancel_payment_to_supplier 802 802 0 0.00 0 612 4 0 0
v_cancel_supplier_invoice 792 772 20 2.52 0 1189 16 0 20
v_cancel_supplier_order 792 792 0 0.00 0 812 6 0 0
v_cancel_write_off 1968 1968 0 0.00 0 3579 16 0 0
v_max_id_clo_ord 5613 5613 0 0.00 0 1259 2 0 0
v_max_id_clo_res 1056 1056 0 0.00 0 4392 20 0 0
v_max_id_ord_sup 5545 5545 0 0.00 0 6165 5 0 0
v_max_non_paid_invoice 1510 1510 0 0.00 0 4795 8 0 0
v_max_non_paid_realizn 3775 3775 0 0.00 0 4306 4 0 0
v_min_id_clo_ord 5613 5613 0 0.00 0 4927 11 0 0
v_min_id_clo_res 1056 1056 0 0.00 0 11060 23 0 0
v_min_id_ord_sup 5545 5545 0 0.00 0 11245 9 0 0
v_min_non_paid_invoice 6147 6147 0 0.00 0 10785 17 0 0
v_min_non_paid_realizn 7086 7086 0 0.00 0 9291 22 0 0
v_our_firm 10514 10514 0 0.00 0 203 1 0 0
v_reserve_write_off 20330 20330 0 0.00 0 8300 7 0 0
x_lock_depdocs_on_canc_invoice 11929 11929 0 0.00 0 19296 111 0 0
x_lock_depdocs_on_canc_sup_ord 396 396 0 0.00 0 22205 546 0 0
x_make_qstorno_1000_1200 5614 5614 0 0.00 1 31811 1163 0 0
x_make_qstorno_1200_2000 5555 5555 0 0.00 2 98033 3074 0 0
x_make_qstorno_2100_3300 475750 475750 0 0.00 0 114963 152 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 06-02-2023 16:40:54 to 06-02-2023 16:40:58.

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:42 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:09:43 31705 8192000000 9904040864 10213285888 983097840 10.714 100 87 174 0 1011670304 6238848 22333616
14:11:19 20069 8192000000 9955274928 10276306944 1030863456 11.177 100 87 174 0 1061433040 8059008 22510576
14:12:48 14265 8192000000 9987097072 10323058688 1070703888 11.559 100 75 147 1 1097700576 8851088 18145600
14:14:10 16569 8192000000 10035218064 10387668992 1127708224 12.100 100 76 152 0 1155884576 9911600 18264752
14:15:29 10979 8192000000 10083391008 10447261696 1171099136 12.507 100 74 146 0 1198441616 10770336 16572144
14:16:45 4426 8192000000 10126735952 10500415488 1221109616 12.972 100 78 155 0 1250673632 12574608 16989408
14:17:50 2266 8192000000 10161262240 10540015616 1251441344 13.251 100 75 147 0 1280607072 13189760 15975968
14:18:55 1878 8192000000 10188873072 10566479872 1276439472 13.480 100 84 169 1 1307072480 12246576 18386432
14:19:58 1038 8192000000 10205287856 10590482432 1289029232 13.595 100 89 176 0 1321665968 12857088 19779648
14:21:01 1515 8192000000 10209726928 10600087552 1298559760 13.682 100 74 145 0 1326840080 12226192 16054128
14:22:04 5359 8192000000 10189746080 10602606592 1306571296 13.755 100 31 63 0 1320741072 7112064 7057712
14:23:11 9079 8192000000 10195029888 10614333440 1318361232 13.862 100 18 35 1 1327520368 5545696 3613440
14:24:24 8151 8192000000 10221697200 10632757248 1327883520 13.948 100 33 65 0 1342058544 6249472 7925552
14:25:35 19190 8192000000 10212160944 10637082624 1333169952 13.996 100 26 52 1 1345136544 5466288 6500304
14:26:55 18742 8192000000 10231534560 10651975680 1338154384 14.041 100 16 31 0 1347017760 5478272 3385104
14:28:16 6332 8192000000 10229150736 10648080384 1324847776 13.921 99 35 70 0 1338590336 5394048 8348512
14:29:23 9676 8192000000 10208805664 10623074304 1299714672 13.693 98 15 30 0 1307856896 4999760 3142464
14:30:35 57415 8192000000 10184185264 10578345984 1221374736 12.974 94 48 94 1 1237981104 4861680 11744688
14:32:32 5039 8192000000 10134664640 10529415168 1208734720 12.857 97 38 78 0 1229550480 11938640 8877120
14:33:38 5644 8192000000 10142531344 10539274240 1242325760 13.168 100 26 50 0 1262348160 14054496 5967904
14:34:46 9277 8192000000 10181200496 10589392896 1284927344 13.558 100 26 52 0 1297481024 5478016 7075664
14:36:01 27044 8192000000 10188071712 10599297024 1295161296 13.651 100 51 101 0 1313720304 6449472 12109536
14:37:34 6948 8192000000 10199327424 10610163712 1302639312 13.719 100 24 48 0 1313558976 5595600 5324064
14:38:45 29760 8192000000 10230978016 10642034688 1327055568 13.941 100 33 65 1 1339714304 5332112 7326624
14:40:25 11736 8192000000 10257375776 10665746432 1344077776 14.094 100 49 98 0 1365695216 10595680 11021760
14:41:43 29093 8192000000 10260925632 10679762944 1357876784 14.218 100 37 72 0 1375534976 9053792 8604400
14:43:20 12597 8192000000 10276282880 10697154560 1366580160 14.296 100 44 87 0 1388506432 12141232 9785040
14:44:50 1099 8192000000 10300431872 10719268864 1381283296 14.428 100 54 104 1 1414170400 21225232 11661872
14:46:04 6106 8192000000 10309319296 10733301760 1395047600 14.551 100 45 87 1 1420582032 15580960 9953472
14:47:28 12211 8192000000 10330187328 10755432448 1413158416 14.712 100 46 92 0 1442552368 17032032 12361920
14:48:45 15537 8192000000 10337831232 10768711680 1422422384 14.794 100 48 96 1 1457262160 23031152 11808624
14:50:09 16711 8192000000 10359649216 10788540416 1431259824 14.872 100 46 92 1 1467968032 25566448 11141760
14:51:33 3657 8192000000 10366989104 10789847040 1430962416 14.870 100 71 141 0 1475528544 27313104 17253024
14:52:46 2233 8192000000 10366916992 10787581952 1424631760 14.814 99 61 121 1 1471114464 32127280 14355424
14:53:53 9223 8192000000 10373436464 10791661568 1426518944 14.830 100 72 143 1 1482540512 37677040 18344528
14:55:06 6089 8192000000 10385653456 10802950144 1410945264 14.692 98 76 151 2 1471664448 42142880 18576304
14:56:19 3548 8192000000 10406108272 10823098368 1442498896 14.972 100 76 152 0 1510612720 48466448 19647376
14:57:29 3775 8192000000 10409356720 10818306048 1427805632 14.842 100 88 176 0 1507113280 57087072 22220576
14:58:42 4154 8192000000 10413042784 10830774272 1429362256 14.856 100 70 140 0 1514035264 66089072 18583936
14:59:55 1969 8192000000 10382736912 10797957120 1410452240 14.688 100 77 152 1 1474544624 44359872 19732512
15:01:04 10521 8192000000 10359102448 10784989184 1399906272 14.594 100 38 76 0 1459897232 49506448 10484512
15:02:18 21139 8192000000 10339452176 10777800704 1381048576 14.426 99 26 52 0 1438649072 50315376 7285120
15:03:42 18810 8192000000 10320471456 10765385728 1371743952 14.343 100 24 48 0 1429139776 50001856 7393968
15:05:24 27706 8192000000 10339358240 10780520448 1373192576 14.356 100 28 55 0 1444837232 64560368 7084288
15:07:14 25694 8192000000 10282559168 10731319296 1371816896 14.343 100 22 42 2 1386527248 9987600 4722752
15:08:43 75476 8192000000 10276749392 10719354880 1340821808 14.065 97 13 27 1 1355808160 12630032 2356320
15:11:06 29246 8192000000 10283690160 10724057088 1363889200 14.272 99 27 54 2 1379212864 8835424 6488240
15:12:40 71728 8192000000 10292559872 10736504832 1376787568 14.388 100 19 38 0 1396336816 14626528 4922720
15:14:52 74493 8192000000 10285056320 10738229248 1327561360 13.945 96 22 42 1 1350586480 18029184 4995936
15:17:07 99870 8192000000 10284958592 10735108096 1354142144 14.185 98 16 31 0 1372574528 14793184 3639200
15:19:49 72293 8192000000 10283483424 10734735360 1342662048 14.081 97 21 42 0 1368768544 20730096 5376400
15:22:08 24163 8192000000 10277280320 10739286016 1364177696 14.275 99 9 18 0 1380316784 14501696 1637392
15:23:34 34236 8192000000 10281448704 10732556288 1368792976 14.316 99 34 64 2 1383120224 7036128 7291120
15:25:11 31662 8192000000 10284544928 10744127488 1390676512 14.512 100 17 32 1 1401300816 7452800 3171504
15:26:44 111862 8192000000 10306976912 10770067456 1390380704 14.509 100 17 34 0 1414976048 20782304 3813040
15:29:47 43108 8192000000 10278252608 10740703232 1377726752 14.396 99 20 40 0 1394769744 12863424 4179568
15:31:51 56304 8192000000 10295871712 10770939904 1396612816 14.565 100 7 12 0 1409868640 12125232 1130592
15:33:48 89799 8192000000 10292344000 10749669376 1331353808 13.979 95 17 33 0 1352889232 18188272 3347152
15:36:22 46280 8192000000 10296217120 10751811584 1343605200 14.090 96 21 41 0 1363718560 15125200 4988160
15:38:26 61730 8192000000 10281772304 10746683392 1368241472 14.311 99 10 20 0 1378948864 8723248 1984144
15:40:29 76454 8192000000 10286065344 10741886976 1343294512 14.087 97 15 30 0 1353103760 7120144 2689104
15:42:49 43412 8192000000 10308712800 10751610880 1364113968 14.274 99 40 80 0 1392071760 19228496 8729296
15:44:34 140665 8192000000 10281594880 10736537600 1293285360 13.634 93 8 15 0 1309573952 15046672 1241920
15:48:57 110285 8192000000 10284875168 10737618944 1359517232 14.233 99 18 36 0 1379381808 15973312 3891264
15:52:08 29844 8192000000 10274289920 10721304576 1357229504 14.212 99 33 66 0 1376621440 11309840 8082096
15:53:57 72224 8192000000 10246838944 10709147648 1308179104 13.770 96 8 16 0 1328986784 18959136 1848544
15:57:51 111213 8192000000 10256449312 10708058112 1306135328 13.751 96 11 21 0 1317550816 9432608 1982880
16:00:43 21857 8192000000 10262950272 10699563008 1352200784 14.167 100 41 81 0 1370097152 7977824 9918544
16:02:07 27302 8192000000 10262693360 10709798912 1356832560 14.209 100 26 52 1 1372321232 9956000 5532672
16:04:16 38530 8192000000 10264909152 10707480576 1332012096 13.985 98 20 39 0 1343848016 8184064 3651856
16:05:58 26842 8192000000 10274391136 10707234816 1343861472 14.092 99 49 97 0 1368012768 12497808 11653488
16:08:11 47417 8192000000 10282533920 10725826560 1338063664 14.040 98 34 68 0 1358641840 13057536 7520640
16:09:59 150076 8192000000 10277929936 10717499392 1315677424 13.838 96 27 54 0 1334950960 13017120 6256416
16:13:32 1067 8192000000 10287668400 10726158336 1364484240 14.278 99 48 97 3 1384568800 8733504 11351056
16:14:34 51395 8192000000 10252107920 10712940544 1329782448 13.965 97 16 30 1 1341742144 8877424 3082272
16:16:29 51373 8192000000 10256664912 10714537984 1358792832 14.227 99 8 16 1 1366342352 5763552 1785968
16:18:23 75138 8192000000 10270802208 10735177728 1366144336 14.292 99 16 32 0 1381622416 11057968 4420112
16:21:02 44747 8192000000 10268948160 10717155328 1329779664 13.965 97 14 28 0 1341354240 7890304 3684272
16:23:01 85131 8192000000 10255101328 10703216640 1291829120 13.621 94 14 28 0 1315028560 19907184 3292256
16:25:26 47569 8192000000 10289606864 10732716032 1371955840 14.345 100 22 44 0 1387644560 10949584 4739136
16:27:14 29740 8192000000 10273782256 10711830528 1328704624 13.955 97 38 75 0 1349444976 12532800 8207552
16:28:45 77355 8192000000 10274931280 10723635200 1337213024 14.032 98 10 20 0 1352325968 12870816 2242128
16:31:06 62246 8192000000 10276236288 10723844096 1344421520 14.097 98 10 20 0 1354707840 8071504 2214816
16:33:19 84825 8192000000 10247008944 10714480640 1296714080 13.665 95 14 28 1 1320651184 20462144 3474960
16:36:11 48979 8192000000 10257863280 10712510464 1337652608 14.036 98 11 22 0 1350933424 10741216 2539600
16:38:00 56967 8192000000 9015006720 9228775424 194225856 2.316 15 2 4 0 196402656 1835616 341184

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 06-02-2023 16:41:00 to 06-02-2023 16:41:16.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 113 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 06-02-2023 16:41:17 to 06-02-2023 16:41:19.

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          240121
MON$OLDEST_ACTIVE               240122
MON$OLDEST_SNAPSHOT             240122
MON$NEXT_TRANSACTION            240122
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                       2023744
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.33659 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33659 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33659 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33659 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'

06.02.23 16:41:20. 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-02-06 16:41:22 No severe PSQL-related problems occured
06.02.23 16:41:23. End of report.