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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 14967396 kB
MemAvailable 30384040 kB
Buffers 0 kB
Cached 16717288 kB
SwapCached 3348 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-16 16:38:42
DTS_END                         2023-02-16 16:38:42.9100
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/42702
TRN_ID                          250626
ATT_ID                          730
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: 9847.49 20159 1181699
customer order: creation 177.36 308 21283
customer order: refuse 14.64 342 1757
order to supplier: creation 47.88 1238 5746
order to supplier: removal 3.40 110499 408
invoice (draft): creation 46.46 3410 5575
invoice (draft): removal 57.54 838 6905
invoice accept: apply 43.53 17247 5224
invoice accept: cancel 52.48 760 6298
customer reserve: creation 3919.70 176 470364
customer reserve: removal 5173.69 56 620843
realization accept: apply 87.54 165 10505
realization accept: cancel 88.08 96 10570
payment from customer: creation 61.93 99 7431
payment from customer: removal 5.74 109 689
payment to supplier: creation 51.84 113 6221
payment to supplier: removal 3.38 92 405
service: total inventory turnovers 4.92 1206 590
service: total monetary turnovers 6.62 34 794
service: aggregate perf. data 0.73 27248 87
service: refresh index statistics 0.03 239136 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 16-02-2023 16:39:55 to 16-02-2023 16:39:57.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6317
TEST_TIME 2 4890
TEST_TIME 3 5171
TEST_TIME 4 5929
TEST_TIME 5 6022
TEST_TIME 6 6387
TEST_TIME 7 6513
TEST_TIME 8 6085
TEST_TIME 9 6297
TEST_TIME 10 6156
TEST_TIME 11 6547
TEST_TIME 12 6488
TEST_TIME 13 6820
TEST_TIME 14 6654
TEST_TIME 15 6723
TEST_TIME 16 6765
TEST_TIME 17 6924
TEST_TIME 18 6817
TEST_TIME 19 6773
TEST_TIME 20 7223
TEST_TIME 21 7490
TEST_TIME 22 7564
TEST_TIME 23 7735
TEST_TIME 24 8013
TEST_TIME 25 8338
TEST_TIME 26 8342
TEST_TIME 27 8345
TEST_TIME 28 8405
TEST_TIME 29 8627
TEST_TIME 30 8560
TEST_TIME 31 9041
TEST_TIME 32 9177
TEST_TIME 33 10125
TEST_TIME 34 10810
TEST_TIME 35 10617
TEST_TIME 36 10579
TEST_TIME 37 10398
TEST_TIME 38 10328
TEST_TIME 39 10360
TEST_TIME 40 10195
TEST_TIME 41 10094
TEST_TIME 42 10112
TEST_TIME 43 10157
TEST_TIME 44 10112
TEST_TIME 45 9956
TEST_TIME 46 9956
TEST_TIME 47 9900
TEST_TIME 48 10035
TEST_TIME 49 10143
TEST_TIME 50 10089
TEST_TIME 51 9987
TEST_TIME 52 9922
TEST_TIME 53 9848
TEST_TIME 54 9695
TEST_TIME 55 9863
TEST_TIME 56 9788
TEST_TIME 57 9751
TEST_TIME 58 9837
TEST_TIME 59 9816
TEST_TIME 60 9895
TEST_TIME 61 9954
TEST_TIME 62 10024
TEST_TIME 63 9999
TEST_TIME 64 10020
TEST_TIME 65 10215
TEST_TIME 66 10209
TEST_TIME 67 10137
TEST_TIME 68 10246
TEST_TIME 69 10239
TEST_TIME 70 10213
TEST_TIME 71 10102
TEST_TIME 72 10057
TEST_TIME 73 10089
TEST_TIME 74 10014
TEST_TIME 75 10051
TEST_TIME 76 10000
TEST_TIME 77 9966
TEST_TIME 78 9888
TEST_TIME 79 9804
TEST_TIME 80 9822
TEST_TIME 81 9814
TEST_TIME 82 9823
TEST_TIME 83 9857
TEST_TIME 84 9921
TEST_TIME 85 9898
TEST_TIME 86 9972
TEST_TIME 87 9963
TEST_TIME 88 9965
TEST_TIME 89 9929
TEST_TIME 90 10004
TEST_TIME 91 10011
TEST_TIME 92 10008
TEST_TIME 93 10043
TEST_TIME 94 9967
TEST_TIME 95 9920
TEST_TIME 96 9889
TEST_TIME 97 9906
TEST_TIME 98 9916
TEST_TIME 99 9863
TEST_TIME 100 9871
TEST_TIME 101 9837
TEST_TIME 102 9834
TEST_TIME 103 9799
TEST_TIME 104 9793
TEST_TIME 105 9766
TEST_TIME 106 9729
TEST_TIME 107 9652
TEST_TIME 108 9665
TEST_TIME 109 9696
TEST_TIME 110 9741
TEST_TIME 111 9753
TEST_TIME 112 9803
TEST_TIME 113 9795
TEST_TIME 114 9817
TEST_TIME 115 9800
TEST_TIME 116 9786
TEST_TIME 117 9812
TEST_TIME 118 9893
TEST_TIME 119 9865
TEST_TIME 120 9850

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 16-02-2023 16:39:58 to 16-02-2023 16:40:02.

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 1618123 1618123 0 0.00 0 37320 6 0 0
doc_list_biud 1618123 1618123 0 0.00 0 56819 27 0 0
sp_add_invoice_to_stock 5434 5224 210 3.86 40 137758 17247 0 210
sp_cancel_adding_invoice 6298 6298 0 0.00 2 59886 760 0 0
sp_cancel_client_order 1757 1757 0 0.00 4 9197 342 0 0
sp_cancel_customer_reserve 620843 620843 0 0.00 1 18136 56 0 0
sp_cancel_pay_from_customer 689 689 0 0.00 3 8310 110 0 0
sp_cancel_pay_to_supplier 405 405 0 0.00 3 3572 92 0 0
sp_cancel_supplier_invoice 6915 6905 10 0.14 1 56824 839 0 10
sp_cancel_supplier_order 408 408 0 0.00 5 1010738 110500 0 0
sp_cancel_write_off 10570 10570 0 0.00 1 5393 96 0 0
sp_client_order 21283 21283 0 0.00 3 23767 308 0 0
sp_customer_reserve 470364 470364 0 0.00 0 97162 176 0 0
sp_fill_shopping_cart 32602 32602 0 0.00 1 10006 39 0 0
sp_fill_shopping_cart_clo_res 470359 470359 0 0.00 0 16019 12 0 0
sp_get_clo_for_invoice 5218 5218 0 0.00 0 22768 231 0 0
sp_kill_cost_storno 17962 17962 0 0.00 0 7264 51 0 0
sp_kill_qstorno_ret_qs2qd 1259569 1259569 0 0.00 0 56815 31 0 0
sp_kill_qty_storno 662504 662504 0 0.00 0 56818 66 0 0
sp_lock_selected_doc 21526 21526 0 0.00 0 11201 6 0 0
sp_make_cost_storno 29375 29375 0 0.00 0 37304 50 0 0
sp_multiply_rows_for_pdistr 29375 29375 0 0.00 0 8179 26 0 0
sp_multiply_rows_for_qdistr 21283 21283 0 0.00 0 14808 129 0 0
sp_pay_from_customer 7431 7431 0 0.00 3 17064 99 0 0
sp_pay_to_supplier 6221 6221 0 0.00 4 8151 113 0 0
sp_payment_common 13652 13652 0 0.00 1 8136 26 0 0
sp_qd_handle_on_cancel_clo 1757 1757 0 0.00 0 8894 311 0 0
sp_qd_handle_on_invoice_upd_sts 11516 11516 0 0.00 0 11196 303 0 0
sp_qd_handle_on_reserve_upd_sts 10505 10505 0 0.00 0 15374 56 0 0
sp_reserve_write_off 10505 10505 0 0.00 6 15904 166 0 0
sp_supplier_invoice 5575 5575 0 0.00 5 113753 3411 0 0
sp_supplier_order 5746 5746 0 0.00 5 26230 1239 0 0
srv_aggregate_perf_data 87 87 0 0.00 3020 327923 27249 0 0
srv_fill_mon_cache_memory 68 68 0 0.00 1665 110779 40294 0 0
srv_find_qd_qs_mism 1132942 1132942 0 0.00 0 16221 5 0 0
srv_make_invnt_saldo 590 590 0 0.00 42 79057 1206 0 0
srv_make_money_saldo 794 794 0 0.00 1 7015 35 0 0
srv_recalc_idx_stat 4 4 0 0.00 104204 339036 239137 0 0
t$perf-abend:sp_add_invoice_to_stock 210 210 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 5224 5224 0 0.00 0 13480 156 0 0
t$perf-norm:sp_cancel_adding_invoice 417 417 0 0.00 0 4161 71 0 0
t$perf-norm:sp_cancel_client_order 1757 1757 0 0.00 0 4524 17 0 0
t$perf-norm:sp_cancel_customer_reserve 747 747 0 0.00 0 657 8 0 0
t$perf-norm:sp_cancel_pay_from_customer 689 689 0 0.00 0 797 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 405 405 0 0.00 0 1568 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 375 375 0 0.00 0 2530 20 0 0
t$perf-norm:sp_cancel_supplier_order 408 408 0 0.00 0 32202 1768 0 0
t$perf-norm:sp_cancel_write_off 1007 1007 0 0.00 0 3452 8 0 0
t$perf-norm:sp_client_order 21283 21283 0 0.00 0 15479 20 0 0
t$perf-norm:sp_customer_reserve 1071 1071 0 0.00 0 3257 8 0 0
t$perf-norm:sp_pay_from_customer 7431 7431 0 0.00 0 15952 11 0 0
t$perf-norm:sp_pay_to_supplier 6221 6221 0 0.00 0 10809 17 0 0
t$perf-norm:sp_reserve_write_off 10505 10505 0 0.00 0 11024 14 0 0
t$perf-norm:sp_supplier_invoice 5575 5575 0 0.00 0 4661 16 0 0
t$perf-norm:sp_supplier_order 5746 5746 0 0.00 0 17191 20 0 0
t$perf-norm:srv_aggregate_perf_data 87 87 0 0.00 0 172 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 68 68 0 0.00 0 2285 45 0 0
t$perf-norm:srv_make_invnt_saldo 590 590 0 0.00 0 7893 36 0 0
t$perf-norm:srv_make_money_saldo 794 794 0 0.00 0 4833 22 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 15 6 0 0
v_add_invoice_to_stock 10852 10432 420 3.87 0 11791 17 0 420
v_all_customers 38200 38200 0 0.00 0 311 1 0 0
v_all_suppliers 31232 31232 0 0.00 0 23 1 0 0
v_all_wares 42566 42566 0 0.00 0 26 1 0 0
v_cancel_adding_invoice 834 834 0 0.00 0 5339 13 0 0
v_cancel_client_order 3514 3514 0 0.00 0 4306 5 0 0
v_cancel_customer_prepayment 1378 1378 0 0.00 0 5084 15 0 0
v_cancel_customer_reserve 1494 1494 0 0.00 0 2483 8 0 0
v_cancel_payment_to_supplier 810 810 0 0.00 0 1650 6 0 0
v_cancel_supplier_invoice 770 750 20 2.59 0 8454 28 0 20
v_cancel_supplier_order 816 816 0 0.00 0 3193 10 0 0
v_cancel_write_off 2014 2014 0 0.00 0 4755 16 0 0
v_max_id_clo_ord 5744 5744 0 0.00 0 2151 2 0 0
v_max_id_clo_res 1071 1071 0 0.00 0 2722 13 0 0
v_max_id_ord_sup 5573 5573 0 0.00 0 3658 4 0 0
v_max_non_paid_invoice 1922 1922 0 0.00 0 1037 3 0 0
v_max_non_paid_realizn 4242 4242 0 0.00 0 2599 4 0 0
v_min_id_clo_ord 5744 5744 0 0.00 0 3761 10 0 0
v_min_id_clo_res 1071 1071 0 0.00 0 10211 24 0 0
v_min_id_ord_sup 5573 5573 0 0.00 0 7128 13 0 0
v_min_non_paid_invoice 6221 6221 0 0.00 0 8132 15 0 0
v_min_non_paid_realizn 7431 7431 0 0.00 0 7361 22 0 0
v_our_firm 10744 10744 0 0.00 0 16 1 0 0
v_reserve_write_off 21010 21010 0 0.00 0 4267 5 0 0
x_lock_depdocs_on_canc_invoice 12828 12828 0 0.00 0 17759 125 0 0
x_lock_depdocs_on_canc_sup_ord 408 408 0 0.00 0 13235 399 0 0
x_make_qstorno_1000_1200 5746 5746 0 0.00 2 26188 1150 0 0
x_make_qstorno_1200_2000 5575 5575 0 0.00 2 112002 3274 0 0
x_make_qstorno_2100_3300 470364 470364 0 0.00 0 97160 162 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 16-02-2023 16:40:03 to 16-02-2023 16:40:07.

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:46 13 8192000000 8806540608 8820334592 11753312 0.143 3 3 4 0 11937216 76800 107104
14:09:47 31599 8192000000 9919865248 10232979456 996705600 10.847 100 93 186 0 1027001792 6673616 23622576
14:11:28 27646 8192000000 9949995152 10276499456 1031447088 11.182 100 85 170 0 1062313552 9115248 21751216
14:13:03 18175 8192000000 10007486704 10347384832 1081488752 11.662 100 72 144 0 1109186496 10583648 17114096
14:14:24 5873 8192000000 10052254768 10404671488 1141456832 12.229 100 80 157 1 1170512240 10759744 18295664
14:15:32 4241 8192000000 10083642224 10443145216 1171195232 12.508 100 80 157 0 1201852512 12712784 17944496
14:16:38 3394 8192000000 10127521440 10497716224 1216104976 12.926 100 81 161 0 1247392144 13996464 17290704
14:17:43 2331 8192000000 10161227504 10538172416 1248301600 13.223 100 81 160 0 1278460848 12686976 17472272
14:18:47 1073 8192000000 10192287616 10574733312 1279381600 13.507 100 78 155 0 1308644752 11876016 17387136
14:19:50 1036 8192000000 10217084400 10601009152 1302434048 13.717 100 89 175 1 1335203792 13140224 19629520
14:20:52 13974 8192000000 10195012896 10613456896 1311547264 13.800 100 41 81 0 1327794224 5585888 10661072
14:22:07 16473 8192000000 10210981488 10623840256 1320313872 13.880 100 10 21 0 1327600544 5246032 2040640
14:23:24 9202 8192000000 10218301680 10636763136 1329272736 13.961 100 16 32 0 1338455984 5747360 3435888
14:24:40 17406 8192000000 10231629104 10649419776 1339296576 14.051 100 22 42 1 1349414800 5250992 4867232
14:26:54 7098 8192000000 10247051680 10666156032 1346471424 14.116 100 27 54 0 1360219808 7415872 6332512
14:28:03 6522 8192000000 10211096240 10623262720 1302069088 13.714 98 29 57 0 1313445728 5484256 5892384
14:29:12 17451 8192000000 10175421856 10588127232 1238180352 13.129 95 14 28 0 1247353312 5676464 3496496
14:30:31 28258 8192000000 10144267584 10556788736 1231679536 13.070 97 30 58 0 1243951776 5145312 7126928
14:32:00 5392 8192000000 10136291072 10535694336 1226513648 13.022 98 30 61 1 1239034320 5897392 6623280
14:33:07 5370 8192000000 10152239568 10554269696 1250001088 13.238 99 32 65 1 1264229952 6969488 7259376
14:34:12 16850 8192000000 10172646848 10584760320 1283838144 13.548 100 20 39 1 1293803024 5028464 4936416
14:35:32 18123 8192000000 10183546496 10598285312 1293587872 13.637 100 10 20 0 1305451456 9476976 2386608
14:36:50 6121 8192000000 10195067728 10611916800 1304828800 13.739 100 25 50 1 1316666400 5702928 6134672
14:38:00 9987 8192000000 10212253360 10626924544 1316594192 13.846 100 15 31 0 1323994896 4505952 2894752
14:39:19 13185 8192000000 10245861184 10658873344 1344756384 14.100 100 39 75 1 1360600752 7061424 8782944
14:40:42 27116 8192000000 10247671984 10673328128 1351742576 14.163 100 32 64 0 1365801584 7440704 6618304
14:42:15 2332 8192000000 10276616768 10688667648 1364716640 14.280 100 59 117 0 1389494096 11602320 13175136
14:43:25 12100 8192000000 10287264912 10705473536 1372951040 14.353 100 59 118 0 1400259296 14419008 12889248
14:44:43 10615 8192000000 10283956304 10707288064 1362784704 14.262 99 53 106 0 1391228272 16324992 12118576
14:46:07 11687 8192000000 10273856320 10695311360 1363480432 14.269 99 44 86 1 1384077024 10151840 10444752
14:47:21 5876 8192000000 10289710112 10706407424 1361803776 14.254 99 61 120 1 1391425952 15255120 14367056
14:48:31 10426 8192000000 10288476144 10709127168 1359661200 14.234 99 49 97 0 1388475424 17377184 11437040
14:49:46 25805 8192000000 10286432080 10704322560 1358671088 14.225 99 47 94 0 1388259808 18692704 10896016
14:51:16 9042 8192000000 10302243968 10719420416 1378046208 14.399 100 66 131 2 1417867232 23580464 16240560
14:52:33 5512 8192000000 10296755984 10706878464 1360077232 14.238 99 62 119 2 1396132624 22265344 13790048
14:53:42 1663 8192000000 10299542016 10708647936 1362942272 14.264 100 59 117 2 1404194816 27396528 13856016
14:54:49 5315 8192000000 10299814832 10702532608 1352752384 14.172 100 73 145 1 1405730192 35524656 17453152
14:55:59 6112 8192000000 10286426928 10695446528 1350800448 14.155 100 49 98 0 1399857440 37083120 11973872
14:57:08 5495 8192000000 10300742928 10711506944 1359272736 14.231 100 54 107 2 1410500336 37477120 13750480
14:58:34 2929 8192000000 10314003648 10718814208 1362788624 14.262 100 64 127 1 1421134592 42641040 15704928
14:59:44 6615 8192000000 10295612880 10709475328 1353540960 14.179 99 36 72 3 1401096208 37491136 10064112
15:00:56 3076 8192000000 10321677440 10730942464 1364025744 14.273 100 60 120 0 1423933024 44862224 15045056
15:02:05 6384 8192000000 10321335712 10738200576 1366633616 14.297 100 60 120 1 1432712480 50787296 15291568
15:03:28 5860 8192000000 10346279904 10760105984 1388484400 14.492 100 53 106 0 1448766176 46058656 14223120
15:04:38 11737 8192000000 10361072272 10781011968 1405970544 14.648 100 46 87 0 1466804784 48805184 12029056
15:05:52 15159 8192000000 10372019264 10795544576 1407008320 14.657 100 41 81 0 1475243568 57036688 11198560
15:07:12 12643 8192000000 10385610016 10828476416 1421321840 14.784 100 36 72 0 1502008272 69971616 10714816
15:08:38 7270 8192000000 10387538352 10818273280 1414425200 14.723 100 36 72 0 1491163024 66083232 10654592
15:09:56 24471 8192000000 10373478160 10810884096 1386307424 14.473 99 32 64 0 1459857136 64551552 8998160
15:12:02 68628 8192000000 10312480000 10761875456 1395840608 14.558 100 11 22 0 1432684384 33957728 2886048
15:14:11 54500 8192000000 10315295264 10755706880 1392618256 14.529 100 24 48 1 1406735520 9038528 5078736
15:16:08 43596 8192000000 10309928944 10753978368 1398108368 14.578 100 30 60 2 1415179744 10494480 6576896
15:17:55 104431 8192000000 10317299616 10770722816 1405946416 14.648 100 26 52 1 1427353632 14788080 6619136
15:20:43 56427 8192000000 10319431424 10763980800 1395776352 14.557 99 49 99 1 1415216976 7985376 11455248
15:22:40 24313 8192000000 10305976192 10756759552 1390106256 14.507 99 22 44 0 1404531040 9588784 4836000
15:25:18 35450 8192000000 10306756080 10757206016 1385783424 14.468 99 26 51 0 1409524752 17026768 6714560
15:27:00 31252 8192000000 10302180896 10753388544 1385255008 14.464 99 47 92 2 1405374192 9936928 10182256
15:28:32 28776 8192000000 10291883328 10760658944 1382724224 14.441 99 5 10 1 1399233520 15665184 844112
15:30:02 71270 8192000000 10277269664 10733572096 1337413488 14.034 96 15 30 0 1348876112 8185840 3276784
15:32:18 77397 8192000000 10268436384 10732617728 1353665488 14.180 99 15 30 1 1371647840 14543056 3439296
15:34:57 42269 8192000000 10276665824 10734030848 1354182864 14.185 98 21 42 1 1368817344 9904416 4730064
15:36:40 46120 8192000000 10268798704 10723753984 1331065152 13.977 97 25 50 2 1348096400 10958720 6072528
15:38:29 43069 8192000000 10284447392 10729234432 1352859760 14.173 98 15 31 2 1366803728 10359216 3584752
15:40:13 68530 8192000000 10258541744 10707984384 1288663152 13.592 94 9 18 0 1300772656 10393856 1715648
15:42:21 54287 8192000000 10254342384 10710482944 1325575840 13.927 98 15 30 0 1347600624 18331568 3693216
15:44:16 39262 8192000000 10266666640 10702778368 1331083520 13.977 99 26 52 1 1347350400 10661936 5604944
15:45:56 35087 8192000000 10254078368 10702893056 1322108992 13.896 98 27 52 0 1339710064 11772848 5828224
15:47:32 51446 8192000000 10272620240 10723241984 1361242384 14.249 100 17 33 1 1374783808 10189536 3351888
15:49:23 110778 8192000000 10275117152 10734272512 1362940672 14.264 99 40 80 0 1383089872 11098416 9050784
15:52:20 33094 8192000000 10265693392 10719485952 1362342368 14.258 99 10 20 0 1376016352 11541664 2132320
15:53:54 49117 8192000000 10260156816 10718203904 1331630336 13.982 97 14 28 0 1347218656 12472048 3116272
15:55:43 62588 8192000000 10270531136 10721456128 1341937872 14.075 97 15 30 0 1353884992 8350752 3596368
15:57:46 46072 8192000000 10296864192 10756247552 1394135472 14.543 100 19 38 0 1420741824 21838240 4768112
15:59:36 36064 8192000000 10285977008 10740117504 1354808000 14.191 98 22 44 1 1376991840 16876608 5307232
16:01:16 93287 8192000000 10272602112 10725138432 1333513520 13.999 97 10 20 0 1347590640 11504352 2572768
16:03:51 45803 8192000000 10304716432 10764279808 1374663136 14.369 99 16 32 0 1394941680 16981456 3297088
16:05:38 57190 8192000000 10302253504 10760867840 1395206320 14.552 100 11 22 0 1406180480 8829824 2144336
16:07:35 41405 8192000000 10304542080 10757210112 1386858768 14.478 99 40 80 1 1408172864 12299440 9014656
16:09:20 64707 8192000000 10323140656 10765688832 1401524592 14.609 100 25 50 2 1417210208 9872960 5812656
16:11:25 98432 8192000000 10309192144 10766872576 1363450336 14.268 97 17 34 0 1381099456 14163744 3485376
16:14:09 61059 8192000000 10309440592 10776846336 1386390976 14.474 99 22 42 1 1414085552 23217664 4476912
16:16:11 52401 8192000000 10287633296 10761068544 1351396080 14.160 96 29 58 0 1375815552 17170432 7249040
16:18:03 85647 8192000000 10275191488 10728181760 1309315136 13.780 94 11 22 0 1321745408 9853776 2576496
16:20:40 55790 8192000000 10276027024 10741923840 1380555872 14.422 100 9 18 0 1390954608 8428832 1969904
16:22:51 74295 8192000000 10286456032 10752585728 1306600784 13.755 94 19 38 0 1318524096 8351024 3572288
16:25:06 63805 8192000000 10294890080 10756247552 1369507936 14.323 99 14 28 0 1389707984 17036960 3163088
16:27:10 64583 8192000000 10268022256 10740146176 1336249472 14.024 97 11 22 0 1360861696 21894896 2717328
16:29:19 70602 8192000000 10267421536 10739273728 1353693920 14.181 99 18 36 0 1372637488 15009136 3934432
16:31:29 32469 8192000000 10269846304 10716016640 1304496256 13.736 96 26 52 0 1324674448 14323728 5854464
16:33:02 91635 8192000000 10256202608 10703757312 1239110752 13.138 91 26 52 0 1256159328 11524640 5523936
16:35:36 51358 8192000000 10276673872 10732756992 1335302112 14.015 99 27 53 0 1361924256 21055792 5566352
16:37:37 73155 8192000000 8973859808 9206439936 115222288 1.387 9 2 4 0 119312624 3730992 359344

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 16-02-2023 16:40:09 to 16-02-2023 16:40:24.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 210 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 16-02-2023 16:40:25 to 16-02-2023 16:40:27.

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          251136
MON$OLDEST_ACTIVE               251137
MON$OLDEST_SNAPSHOT             251137
MON$NEXT_TRANSACTION            251137
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                       2066400
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 ---
--- end of diff output ---

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

16.02.23 16:40:28. 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-16 16:40:31 No severe PSQL-related problems occured
16.02.23 16:40:32. End of report.