Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 23.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 15440364 kB
MemAvailable 30398044 kB
Buffers 0 kB
Cached 16256920 kB
SwapCached 3140 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-23 16:38:42
DTS_END                         2023-02-23 16:38:42.7570
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_AGGREGATE_PERF_DATA(83:5) ==> TMP_AGGREGATE_PERF_DATA_AUTOGEN(120:9) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/36882
TRN_ID                          246483
ATT_ID                          654
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: 8893.31 25778 1067197
customer order: creation 175.62 394 21074
customer order: refuse 15.38 327 1845
order to supplier: creation 46.90 1273 5628
order to supplier: removal 3.23 85681 388
invoice (draft): creation 46.19 3430 5543
invoice (draft): removal 48.38 672 5805
invoice accept: apply 42.76 16375 5131
invoice accept: cancel 44.38 704 5325
customer reserve: creation 3854.18 165 462501
customer reserve: removal 4316.95 50 518034
realization accept: apply 85.14 180 10217
realization accept: cancel 82.42 111 9890
payment from customer: creation 60.80 153 7296
payment from customer: removal 5.75 60 690
payment to supplier: creation 49.43 171 5932
payment to supplier: removal 3.66 88 439
service: total inventory turnovers 4.53 924 543
service: total monetary turnovers 6.73 32 808
service: aggregate perf. data 0.87 22245 104
service: refresh index statistics 0.03 382515 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 23-02-2023 16:40:00 to 23-02-2023 16:40:02.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2450
TEST_TIME 2 3048
TEST_TIME 3 3174
TEST_TIME 4 2807
TEST_TIME 5 3154
TEST_TIME 6 3990
TEST_TIME 7 4254
TEST_TIME 8 4251
TEST_TIME 9 5049
TEST_TIME 10 5152
TEST_TIME 11 5495
TEST_TIME 12 5453
TEST_TIME 13 5911
TEST_TIME 14 7200
TEST_TIME 15 7433
TEST_TIME 16 7648
TEST_TIME 17 7872
TEST_TIME 18 7985
TEST_TIME 19 8042
TEST_TIME 20 8297
TEST_TIME 21 8808
TEST_TIME 22 8972
TEST_TIME 23 8852
TEST_TIME 24 9577
TEST_TIME 25 9689
TEST_TIME 26 10247
TEST_TIME 27 10227
TEST_TIME 28 9986
TEST_TIME 29 10124
TEST_TIME 30 10314
TEST_TIME 31 10178
TEST_TIME 32 10068
TEST_TIME 33 9803
TEST_TIME 34 9914
TEST_TIME 35 9791
TEST_TIME 36 9649
TEST_TIME 37 9668
TEST_TIME 38 9607
TEST_TIME 39 9542
TEST_TIME 40 9577
TEST_TIME 41 9485
TEST_TIME 42 9355
TEST_TIME 43 9232
TEST_TIME 44 9180
TEST_TIME 45 9189
TEST_TIME 46 9043
TEST_TIME 47 8948
TEST_TIME 48 8851
TEST_TIME 49 8905
TEST_TIME 50 8824
TEST_TIME 51 8759
TEST_TIME 52 8751
TEST_TIME 53 8638
TEST_TIME 54 8642
TEST_TIME 55 8690
TEST_TIME 56 8694
TEST_TIME 57 8644
TEST_TIME 58 8717
TEST_TIME 59 8763
TEST_TIME 60 8803
TEST_TIME 61 8909
TEST_TIME 62 8933
TEST_TIME 63 8953
TEST_TIME 64 8969
TEST_TIME 65 8958
TEST_TIME 66 9004
TEST_TIME 67 9003
TEST_TIME 68 9045
TEST_TIME 69 9013
TEST_TIME 70 8942
TEST_TIME 71 8865
TEST_TIME 72 8895
TEST_TIME 73 8857
TEST_TIME 74 8893
TEST_TIME 75 8823
TEST_TIME 76 8781
TEST_TIME 77 8768
TEST_TIME 78 8778
TEST_TIME 79 8808
TEST_TIME 80 8786
TEST_TIME 81 8792
TEST_TIME 82 8814
TEST_TIME 83 8851
TEST_TIME 84 8855
TEST_TIME 85 8873
TEST_TIME 86 8877
TEST_TIME 87 8953
TEST_TIME 88 8977
TEST_TIME 89 9038
TEST_TIME 90 9031
TEST_TIME 91 9044
TEST_TIME 92 9007
TEST_TIME 93 8949
TEST_TIME 94 8901
TEST_TIME 95 8874
TEST_TIME 96 8891
TEST_TIME 97 8888
TEST_TIME 98 8863
TEST_TIME 99 8833
TEST_TIME 100 8905
TEST_TIME 101 8927
TEST_TIME 102 8898
TEST_TIME 103 8914
TEST_TIME 104 8935
TEST_TIME 105 8909
TEST_TIME 106 8984
TEST_TIME 107 8996
TEST_TIME 108 8973
TEST_TIME 109 8988
TEST_TIME 110 9008
TEST_TIME 111 8960
TEST_TIME 112 8958
TEST_TIME 113 8888
TEST_TIME 114 8924
TEST_TIME 115 8890
TEST_TIME 116 8837
TEST_TIME 117 8891
TEST_TIME 118 8906
TEST_TIME 119 8896
TEST_TIME 120 8894

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 23-02-2023 16:40:03 to 23-02-2023 16:40:07.

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 1495422 1495422 0 0.00 0 31558 6 0 0
doc_list_biud 1495421 1495421 0 0.00 0 38743 23 0 0
sp_add_invoice_to_stock 5271 5131 140 2.65 26 174091 16375 0 140
sp_cancel_adding_invoice 5325 5325 0 0.00 4 58418 705 0 0
sp_cancel_client_order 1845 1845 0 0.00 4 8335 327 0 0
sp_cancel_customer_reserve 518034 518034 0 0.00 0 30887 51 0 0
sp_cancel_pay_from_customer 690 690 0 0.00 2 4402 60 0 0
sp_cancel_pay_to_supplier 439 439 0 0.00 3 6595 89 0 0
sp_cancel_supplier_invoice 5817 5805 12 0.20 1 19242 672 0 12
sp_cancel_supplier_order 388 388 0 0.00 6 867775 85682 0 0
sp_cancel_write_off 9890 9890 0 0.00 1 11509 111 0 0
sp_client_order 21074 21074 0 0.00 3 30158 395 0 0
sp_customer_reserve 462501 462501 0 0.00 0 42959 165 0 0
sp_fill_shopping_cart 32245 32245 0 0.00 1 26760 45 0 0
sp_fill_shopping_cart_clo_res 462500 462500 0 0.00 0 23791 15 0 0
sp_get_clo_for_invoice 5130 5130 0 0.00 0 40249 337 0 0
sp_kill_cost_storno 16344 16344 0 0.00 0 9315 51 0 0
sp_kill_qstorno_ret_qs2qd 1052151 1052151 0 0.00 0 30786 28 0 0
sp_kill_qty_storno 556634 556634 0 0.00 0 38742 60 0 0
sp_lock_selected_doc 21263 21263 0 0.00 0 10273 7 0 0
sp_make_cost_storno 28575 28575 0 0.00 0 25550 67 0 0
sp_multiply_rows_for_pdistr 28575 28575 0 0.00 0 9462 28 0 0
sp_multiply_rows_for_qdistr 21074 21074 0 0.00 0 30053 187 0 0
sp_pay_from_customer 7296 7296 0 0.00 4 29932 154 0 0
sp_pay_to_supplier 5932 5932 0 0.00 4 29721 172 0 0
sp_payment_common 13228 13228 0 0.00 1 18527 50 0 0
sp_qd_handle_on_cancel_clo 1845 1845 0 0.00 0 8330 292 0 0
sp_qd_handle_on_invoice_upd_sts 10455 10455 0 0.00 0 38741 293 0 0
sp_qd_handle_on_reserve_upd_sts 10217 10217 0 0.00 0 21550 53 0 0
sp_reserve_write_off 10217 10217 0 0.00 6 31571 180 0 0
sp_supplier_invoice 5543 5543 0 0.00 6 93266 3431 0 0
sp_supplier_order 5628 5628 0 0.00 5 41633 1273 0 0
srv_aggregate_perf_data 104 104 0 0.00 2137 310824 22245 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 2902 124046 44000 0 0
srv_find_qd_qs_mism 1018850 1018850 0 0.00 0 10975 5 0 0
srv_make_invnt_saldo 543 543 0 0.00 32 43709 924 0 0
srv_make_money_saldo 808 808 0 0.00 0 5965 32 0 0
srv_recalc_idx_stat 4 4 0 0.00 101735 674504 382515 0 0
t$perf-abend:sp_add_invoice_to_stock 140 140 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5131 5131 0 0.00 0 35877 150 0 0
t$perf-norm:sp_cancel_adding_invoice 417 417 0 0.00 0 7239 101 0 0
t$perf-norm:sp_cancel_client_order 1845 1845 0 0.00 0 8761 15 0 0
t$perf-norm:sp_cancel_customer_reserve 672 672 0 0.00 0 1748 8 0 0
t$perf-norm:sp_cancel_pay_from_customer 690 690 0 0.00 0 1154 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 439 439 0 0.00 0 734 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 384 384 0 0.00 0 3412 25 0 0
t$perf-norm:sp_cancel_supplier_order 388 388 0 0.00 0 28520 1502 0 0
t$perf-norm:sp_cancel_write_off 1081 1081 0 0.00 0 1014 6 0 0
t$perf-norm:sp_client_order 21074 21074 0 0.00 0 16598 16 0 0
t$perf-norm:sp_customer_reserve 1000 1000 0 0.00 0 3937 16 0 0
t$perf-norm:sp_pay_from_customer 7296 7296 0 0.00 0 9498 11 0 0
t$perf-norm:sp_pay_to_supplier 5932 5932 0 0.00 0 9681 12 0 0
t$perf-norm:sp_reserve_write_off 10217 10217 0 0.00 0 3909 8 0 0
t$perf-norm:sp_supplier_invoice 5543 5543 0 0.00 0 11154 17 0 0
t$perf-norm:sp_supplier_order 5628 5628 0 0.00 0 9359 13 0 0
t$perf-norm:srv_aggregate_perf_data 104 104 0 0.00 0 325 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 2118 33 0 0
t$perf-norm:srv_make_invnt_saldo 543 543 0 0.00 0 6142 32 0 0
t$perf-norm:srv_make_money_saldo 808 808 0 0.00 0 1309 14 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 8 4 0 0
v_add_invoice_to_stock 10540 10260 280 2.65 0 10616 19 0 280
v_all_customers 38240 38240 0 0.00 0 30 0 0 0
v_all_suppliers 30522 30522 0 0.00 0 38 1 0 0
v_all_wares 42148 42148 0 0.00 0 25 1 0 0
v_cancel_adding_invoice 834 834 0 0.00 0 1097 9 0 0
v_cancel_client_order 3690 3690 0 0.00 0 5292 9 0 0
v_cancel_customer_prepayment 1380 1380 0 0.00 0 1283 4 0 0
v_cancel_customer_reserve 1344 1344 0 0.00 0 2099 6 0 0
v_cancel_payment_to_supplier 878 878 0 0.00 0 2403 12 0 0
v_cancel_supplier_invoice 792 768 24 3.03 0 4471 20 0 24
v_cancel_supplier_order 776 776 0 0.00 0 504 4 0 0
v_cancel_write_off 2162 2162 0 0.00 0 10172 20 0 0
v_max_id_clo_ord 5628 5628 0 0.00 0 8847 3 0 0
v_max_id_clo_res 1000 1000 0 0.00 0 1802 17 0 0
v_max_id_ord_sup 5543 5543 0 0.00 0 2954 4 0 0
v_max_non_paid_invoice 1842 1842 0 0.00 0 2529 4 0 0
v_max_non_paid_realizn 3883 3883 0 0.00 0 3635 5 0 0
v_min_id_clo_ord 5628 5628 0 0.00 0 17568 15 0 0
v_min_id_clo_res 1000 1000 0 0.00 0 4715 11 0 0
v_min_id_ord_sup 5543 5543 0 0.00 0 5917 11 0 0
v_min_non_paid_invoice 5932 5932 0 0.00 0 18520 39 0 0
v_min_non_paid_realizn 7296 7296 0 0.00 1 16360 45 0 0
v_our_firm 10734 10734 0 0.00 0 59 1 0 0
v_reserve_write_off 20434 20434 0 0.00 0 5640 9 0 0
x_lock_depdocs_on_canc_invoice 10746 10746 0 0.00 0 14059 112 0 0
x_lock_depdocs_on_canc_sup_ord 388 388 0 0.00 0 13083 367 0 0
x_make_qstorno_1000_1200 5628 5628 0 0.00 1 41626 1184 0 0
x_make_qstorno_1200_2000 5543 5543 0 0.00 3 93244 3261 0 0
x_make_qstorno_2100_3300 462501 462501 0 0.00 0 40513 148 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 23-02-2023 16:40:08 to 23-02-2023 16:40:12.

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 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:09:48 25132 8192000000 9891981664 10203054080 975766832 10.643 100 80 160 0 1002727632 5974160 20986640
14:11:18 17983 8192000000 9950548720 10270486528 1030924544 11.177 100 93 186 0 1063072336 8239312 23908480
14:12:40 12964 8192000000 9982398272 10314956800 1063761760 11.492 100 77 154 0 1092282880 9502800 19018320
14:13:56 7453 8192000000 10018656368 10363604992 1109941392 11.932 100 75 150 0 1138779536 10797760 18040384
14:15:07 8419 8192000000 10081601696 10438107136 1169239040 12.490 100 77 151 0 1197655040 11453552 16962448
14:16:17 4454 8192000000 10116928992 10482733056 1210026720 12.869 100 75 150 1 1239351488 13086160 16238608
14:17:25 1870 8192000000 10159186864 10531688448 1244930512 13.192 100 78 157 0 1275722256 13333712 17458032
14:18:28 2177 8192000000 10180280432 10561835008 1270168544 13.423 100 77 155 0 1299670720 12381280 17120896
14:19:33 1141 8192000000 10209738144 10593591296 1295915600 13.658 100 89 176 0 1328815504 13403392 19496512
14:20:35 25839 8192000000 10204519680 10606092288 1305960448 13.749 100 47 91 0 1323796304 6939808 10896048
14:22:02 7269 8192000000 10201152032 10610970624 1313941872 13.822 100 29 55 1 1326521344 6628432 5951040
14:23:10 6583 8192000000 10211950576 10622570496 1323496624 13.908 100 29 58 1 1337653680 7806544 6350512
14:24:22 10341 8192000000 10217853824 10635853824 1331752800 13.983 100 30 59 0 1344595904 6384688 6458416
14:25:36 53398 8192000000 10245255184 10653384704 1338634448 14.045 100 43 82 0 1353527472 5383536 9509488
14:27:29 6730 8192000000 10231843872 10644152320 1333471824 13.999 100 38 75 1 1347668352 5903376 8293152
14:28:37 9072 8192000000 10228430976 10642100224 1329987856 13.967 100 19 39 0 1339901984 4948832 4965296
14:29:49 61119 8192000000 10178126704 10589958144 1253523552 13.271 96 54 106 1 1271668112 4742576 13401984
14:31:51 5956 8192000000 10120615920 10522685440 1206847952 12.840 96 22 44 0 1217738928 6346176 4544800
14:32:58 5533 8192000000 10144820576 10537082880 1217094672 12.935 97 33 66 2 1230170512 5182624 7893216
14:34:06 3221 8192000000 10167462000 10571104256 1272219472 13.442 100 31 62 0 1286022288 5650672 8152144
14:35:11 46412 8192000000 10175147744 10589724672 1278609856 13.500 99 35 71 0 1291950816 4982240 8358720
14:36:58 6390 8192000000 10196538848 10611826688 1307056416 13.759 100 22 44 0 1317437424 5485664 4895344
14:38:07 6827 8192000000 10221139232 10634870784 1324750576 13.920 100 27 54 0 1336387456 5368320 6268560
14:39:18 4036 8192000000 10245122976 10656534528 1342718576 14.082 100 46 93 0 1359970672 6266752 10985344
14:40:29 47658 8192000000 10261575920 10682970112 1359183664 14.230 100 36 74 1 1377850320 9724320 8942336
14:42:25 75707 8192000000 10273093696 10703638528 1370374336 14.330 100 25 50 0 1391543616 15140544 6028736
14:44:51 7273 8192000000 10300425280 10724384768 1386417488 14.474 100 48 95 0 1412054896 14766960 10870448
14:46:02 8084 8192000000 10306279440 10728103936 1383811824 14.451 99 53 102 1 1412906656 17057696 12037136
14:47:16 9948 8192000000 10330511616 10755170304 1406033216 14.649 100 58 115 0 1441640560 21741520 13865824
14:48:35 16094 8192000000 10328546784 10754981888 1409747664 14.682 100 55 110 0 1441099952 17695264 13657024
14:49:55 2900 8192000000 10322627696 10731286528 1389477424 14.501 99 74 144 1 1427810224 21803504 16529296
14:51:02 3118 8192000000 10341165152 10760716288 1403903072 14.630 100 59 114 0 1449690736 31687008 14100656
14:52:09 3913 8192000000 10342069248 10754826240 1395450480 14.554 100 72 141 1 1445447376 32955200 17041696
14:53:17 7254 8192000000 10308088032 10730504192 1384648096 14.458 100 51 100 1 1421904336 25257280 11998960
14:54:28 3384 8192000000 10304549904 10721763328 1374986128 14.372 100 50 99 0 1412855424 25957472 11911824
14:55:38 12796 8192000000 10273120896 10694569984 1345106496 14.103 99 30 60 0 1380737088 27611152 8019440
14:56:54 12236 8192000000 10285059872 10714206208 1361504208 14.251 100 38 75 0 1403296464 32139584 9652672
14:58:16 16576 8192000000 10279225008 10706534400 1341890736 14.074 99 36 72 1 1382453296 31296368 9266192
14:59:36 11429 8192000000 10293074784 10730745856 1354265872 14.186 99 28 55 0 1394891488 33205568 7420048
15:00:50 9513 8192000000 10284180576 10710990848 1345340848 14.106 99 30 60 0 1383918112 30366464 8210800
15:02:08 5622 8192000000 10291098960 10712793088 1343106640 14.085 99 47 95 1 1393256048 38354080 11795328
15:03:17 20968 8192000000 10257782192 10687352832 1315179136 13.833 99 19 38 0 1349073632 28810240 5084256
15:04:41 48739 8192000000 10257308560 10683904000 1312716080 13.811 98 16 32 0 1348229888 31204736 4309072
15:06:40 15823 8192000000 10271614176 10695905280 1343928416 14.093 100 42 83 1 1371963632 18223152 9812064
15:07:57 22650 8192000000 10267322144 10716094464 1360077888 14.238 100 15 30 1 1377974960 14241248 3655824
15:10:05 73192 8192000000 10280110688 10717294592 1364252848 14.276 100 14 28 0 1380186560 13093632 2840080
15:12:19 67751 8192000000 10264572576 10714894336 1342859088 14.083 98 7 14 0 1353646784 9493584 1294112
15:14:30 59639 8192000000 10277164736 10724519936 1356290144 14.204 98 13 26 0 1368938784 9487760 3160880
15:17:13 93463 8192000000 10293458672 10744737792 1381260400 14.428 100 15 30 0 1397603808 13137888 3205520
15:19:48 39583 8192000000 10289833920 10737053696 1391434336 14.519 100 24 48 0 1411146992 14052336 5660320
15:21:31 49574 8192000000 10306913744 10756571136 1403274912 14.624 100 35 71 0 1419728608 8300512 8153184
15:23:21 76800 8192000000 10308749984 10767392768 1405349728 14.643 100 18 36 0 1425220704 15971664 3899312
15:25:41 39724 8192000000 10307107984 10764271616 1408449472 14.670 100 49 101 0 1430726368 10867168 11409728
15:27:21 69158 8192000000 10293962880 10757664768 1396241584 14.562 99 15 26 1 1406831136 7976096 2613456
15:29:41 21785 8192000000 10308340576 10765242368 1407487792 14.662 100 24 48 0 1428096944 14958208 5650944
15:31:03 73848 8192000000 10304918640 10767667200 1398220176 14.579 99 14 27 1 1413195344 12023296 2951872
15:33:45 62960 8192000000 10297261536 10760908800 1383758144 14.450 98 22 44 0 1397543648 8789936 4995568
15:35:49 36993 8192000000 10300775888 10756513792 1374393488 14.366 98 37 74 0 1394202256 11814912 7993856
15:37:26 76898 8192000000 10310283536 10767155200 1365624032 14.288 96 21 40 0 1382689168 12132176 4932960
15:39:44 105727 8192000000 10279416912 10745856000 1314766912 13.829 94 19 36 0 1329645280 10800176 4078192
15:42:29 57012 8192000000 10296968112 10740187136 1347554368 14.125 97 27 54 0 1363484400 10418272 5511760
15:44:28 22654 8192000000 10278076272 10715283456 1344564080 14.099 97 38 75 0 1361879360 8825008 8490272
15:45:53 19022 8192000000 10296564048 10734632960 1374441264 14.367 100 22 44 0 1387619696 8594928 4583504
15:47:16 124044 8192000000 10248326704 10689863680 1295544624 13.655 95 19 38 0 1306560256 6979488 4036144
15:50:21 33077 8192000000 10233141600 10683830272 1303364976 13.726 96 43 83 1 1327173952 14347648 9461328
15:51:58 48463 8192000000 10218999568 10670768128 1297572816 13.673 97 3 6 0 1310254384 11926080 755488
15:53:48 22420 8192000000 10223368304 10666004480 1308289536 13.771 98 25 50 0 1320100560 6007024 5804000
15:55:15 59590 8192000000 10231716064 10687901696 1334306256 14.006 100 7 14 0 1344034176 8300128 1427792
15:57:14 15269 8192000000 10253862576 10688880640 1332540928 13.990 99 34 67 0 1347537760 7817440 7179392
15:58:36 6520 8192000000 10243339504 10678661120 1329141184 13.959 99 45 92 0 1345650592 6902240 9607168
15:59:43 52058 8192000000 10256309360 10702131200 1336535440 14.026 99 12 24 0 1351259152 11721968 3001744
16:01:40 74524 8192000000 10272605968 10717970432 1341060848 14.067 99 23 46 0 1361483856 15199744 5223264
16:04:01 58211 8192000000 10274246192 10722619392 1336310576 14.024 98 18 36 0 1361486016 20875216 4300224
16:06:07 69105 8192000000 10271978640 10732478464 1359287440 14.231 100 20 40 0 1381790064 18109776 4392848
16:08:17 46422 8192000000 10314264496 10742472704 1377915344 14.398 100 26 52 0 1391683616 8365360 5402912
16:10:04 113189 8192000000 10289677984 10755473408 1377022336 14.390 99 24 48 0 1394794848 12551760 5220752
16:13:02 63827 8192000000 10295588128 10752159744 1380370576 14.420 99 10 20 0 1391742512 9286336 2085600
16:15:07 65528 8192000000 10291050384 10746114048 1362271088 14.258 98 6 12 0 1374540160 11275664 993408
16:17:15 45308 8192000000 10313357456 10764636160 1398808832 14.584 100 41 80 0 1424167920 15576928 9782160
16:19:05 52332 8192000000 10279672768 10732908544 1336595328 14.027 96 10 20 0 1353111264 14411328 2104608
16:20:59 11441 8192000000 10295568064 10734993408 1369582032 14.323 99 44 82 0 1391071184 12370464 9118688
16:22:10 80953 8192000000 10305514224 10758807552 1360783536 14.244 98 17 34 1 1380244720 15773536 3687648
16:24:32 103037 8192000000 10265977584 10703179776 1249866704 13.237 90 16 32 0 1265422320 11954736 3600880
16:27:29 58801 8192000000 10273213824 10722140160 1306011440 13.750 95 21 42 0 1322827040 12315088 4500512
16:29:31 95934 8192000000 10251838928 10708602880 1360073616 14.238 100 31 60 0 1374801248 7750832 6976800
16:32:08 34112 8192000000 10259904976 10710577152 1362193232 14.257 100 18 36 0 1379998432 13775984 4029216
16:35:21 56916 8192000000 10261716880 10729664512 1359471168 14.233 100 11 22 0 1381828464 19638640 2718656
16:37:23 57311 8192000000 10231981312 10684321792 1237821920 13.126 91 11 22 0 1251871200 11519840 2529440

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 140 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 23-02-2023 16:40:30 to 23-02-2023 16:40:32.

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          245206
MON$OLDEST_ACTIVE               246664
MON$OLDEST_SNAPSHOT             246664
MON$NEXT_TRANSACTION            246664
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                       2011264
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 ---
:466: 
:467: mscs-test-161.budzdorov.ru    Thu Feb 23 16:38:43 2023
:468:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:469: 
:470: 
:471: mscs-test-161.budzdorov.ru    Thu Feb 23 16:38:44 2023
:472:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:473: 
:474: 
:475: mscs-test-161.budzdorov.ru    Thu Feb 23 16:38:44 2023
:476:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:477: 
:478: 
:479: mscs-test-161.budzdorov.ru    Thu Feb 23 16:38:45 2023
:480:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:481: 
:482: 
:483: mscs-test-161.budzdorov.ru    Thu Feb 23 16:38:45 2023
:484:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:485: 
--- end of diff output ---

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

23.02.23 16:40:33. 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-23 16:40:36 No severe PSQL-related problems occured
23.02.23 16:40:37. End of report.