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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16098892 kB
MemAvailable 31291384 kB
Buffers 0 kB
Cached 15579624 kB
SwapCached 2448 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.10
DB_NAME                         /home/bases/oltp30-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3333
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp30-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp30
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-06-07 01:38:20
DTS_END                         2022-06-07 01:38:20.8090
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/47884
TRN_ID                          252686
ATT_ID                          666
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: 9530.94 22124 1143713
customer order: creation 179.56 301 21547
customer order: refuse 15.05 342 1806
order to supplier: creation 48.30 1176 5796
order to supplier: removal 3.37 104220 404
invoice (draft): creation 47.97 3263 5756
invoice (draft): removal 53.87 796 6464
invoice accept: apply 43.58 17398 5230
invoice accept: cancel 49.31 697 5917
customer reserve: creation 3968.41 175 476209
customer reserve: removal 4806.49 57 576779
realization accept: apply 88.27 181 10592
realization accept: cancel 90.11 104 10813
payment from customer: creation 62.64 110 7517
payment from customer: removal 5.89 91 707
payment to supplier: creation 51.57 143 6188
payment to supplier: removal 3.44 59 413
service: total inventory turnovers 5.00 1157 600
service: total monetary turnovers 7.23 52 868
service: aggregate perf. data 0.86 17308 103
service: refresh index statistics 0.03 294844 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 07-06-2022 01:39:19 to 07-06-2022 01:39:21.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4660
TEST_TIME 2 3041
TEST_TIME 3 3223
TEST_TIME 4 3510
TEST_TIME 5 3596
TEST_TIME 6 3753
TEST_TIME 7 4481
TEST_TIME 8 4798
TEST_TIME 9 5396
TEST_TIME 10 5396
TEST_TIME 11 6307
TEST_TIME 12 6940
TEST_TIME 13 7291
TEST_TIME 14 7479
TEST_TIME 15 7327
TEST_TIME 16 7453
TEST_TIME 17 7607
TEST_TIME 18 7868
TEST_TIME 19 7874
TEST_TIME 20 8229
TEST_TIME 21 8703
TEST_TIME 22 9281
TEST_TIME 23 9326
TEST_TIME 24 9687
TEST_TIME 25 10110
TEST_TIME 26 9979
TEST_TIME 27 9828
TEST_TIME 28 10570
TEST_TIME 29 10640
TEST_TIME 30 11400
TEST_TIME 31 11364
TEST_TIME 32 11109
TEST_TIME 33 11010
TEST_TIME 34 11189
TEST_TIME 35 11086
TEST_TIME 36 11022
TEST_TIME 37 11044
TEST_TIME 38 10912
TEST_TIME 39 10883
TEST_TIME 40 10848
TEST_TIME 41 10677
TEST_TIME 42 10576
TEST_TIME 43 10477
TEST_TIME 44 10429
TEST_TIME 45 10381
TEST_TIME 46 10287
TEST_TIME 47 10253
TEST_TIME 48 10271
TEST_TIME 49 10279
TEST_TIME 50 10447
TEST_TIME 51 10320
TEST_TIME 52 10232
TEST_TIME 53 10202
TEST_TIME 54 10059
TEST_TIME 55 9945
TEST_TIME 56 9938
TEST_TIME 57 9993
TEST_TIME 58 9915
TEST_TIME 59 9838
TEST_TIME 60 9736
TEST_TIME 61 9631
TEST_TIME 62 9807
TEST_TIME 63 9805
TEST_TIME 64 9799
TEST_TIME 65 9885
TEST_TIME 66 9852
TEST_TIME 67 9890
TEST_TIME 68 9917
TEST_TIME 69 9951
TEST_TIME 70 9911
TEST_TIME 71 9890
TEST_TIME 72 10002
TEST_TIME 73 10015
TEST_TIME 74 9986
TEST_TIME 75 9983
TEST_TIME 76 9961
TEST_TIME 77 9978
TEST_TIME 78 9928
TEST_TIME 79 9915
TEST_TIME 80 9885
TEST_TIME 81 9818
TEST_TIME 82 9776
TEST_TIME 83 9755
TEST_TIME 84 9703
TEST_TIME 85 9659
TEST_TIME 86 9633
TEST_TIME 87 9583
TEST_TIME 88 9671
TEST_TIME 89 9657
TEST_TIME 90 9807
TEST_TIME 91 9763
TEST_TIME 92 9741
TEST_TIME 93 9890
TEST_TIME 94 9880
TEST_TIME 95 9831
TEST_TIME 96 9852
TEST_TIME 97 9911
TEST_TIME 98 9931
TEST_TIME 99 9948
TEST_TIME 100 9967
TEST_TIME 101 9871
TEST_TIME 102 9883
TEST_TIME 103 9861
TEST_TIME 104 9845
TEST_TIME 105 9812
TEST_TIME 106 9812
TEST_TIME 107 9765
TEST_TIME 108 9734
TEST_TIME 109 9730
TEST_TIME 110 9682
TEST_TIME 111 9661
TEST_TIME 112 9643
TEST_TIME 113 9598
TEST_TIME 114 9578
TEST_TIME 115 9594
TEST_TIME 116 9556
TEST_TIME 117 9526
TEST_TIME 118 9543
TEST_TIME 119 9594
TEST_TIME 120 9536

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 07-06-2022 01:39:22 to 07-06-2022 01:39:26.

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 1585898 1585898 0 0.00 0 20266 6 0 0
doc_list_biud 1585877 1585877 0 0.00 0 90748 26 0 0
sp_add_invoice_to_stock 5334 5230 104 1.94 12 192668 17399 0 104
sp_cancel_adding_invoice 5917 5917 0 0.00 4 58613 697 0 0
sp_cancel_client_order 1806 1806 0 0.00 4 8873 343 0 0
sp_cancel_customer_reserve 576779 576779 0 0.00 1 32095 57 0 0
sp_cancel_pay_from_customer 707 707 0 0.00 2 8344 92 0 0
sp_cancel_pay_to_supplier 413 413 0 0.00 2 2360 60 0 0
sp_cancel_supplier_invoice 6478 6464 14 0.21 2 90774 797 0 14
sp_cancel_supplier_order 404 404 0 0.00 6 1029041 104221 0 0
sp_cancel_write_off 10813 10813 0 0.00 1 27175 105 0 0
sp_client_order 21547 21547 0 0.00 3 26454 301 0 0
sp_customer_reserve 476209 476209 0 0.00 0 106753 176 0 0
sp_fill_shopping_cart 33099 33099 0 0.00 1 16618 38 0 0
sp_fill_shopping_cart_clo_res 476203 476203 0 0.00 0 31463 14 0 0
sp_get_clo_for_invoice 5224 5224 0 0.00 0 31772 251 0 0
sp_kill_cost_storno 17850 17850 0 0.00 0 7725 55 0 0
sp_kill_qstorno_ret_qs2qd 1171239 1171239 0 0.00 0 90733 31 0 0
sp_kill_qty_storno 617990 617990 0 0.00 0 90742 67 0 0
sp_lock_selected_doc 21806 21806 0 0.00 0 5797 5 0 0
sp_make_cost_storno 29521 29521 0 0.00 0 20264 56 0 0
sp_multiply_rows_for_pdistr 29509 29509 0 0.00 0 12000 29 0 0
sp_multiply_rows_for_qdistr 21547 21547 0 0.00 0 10464 142 0 0
sp_pay_from_customer 7517 7517 0 0.00 4 13955 110 0 0
sp_pay_to_supplier 6188 6188 0 0.00 5 20272 144 0 0
sp_payment_common 13694 13694 0 0.00 1 10463 26 0 0
sp_qd_handle_on_cancel_clo 1806 1806 0 0.00 0 8837 305 0 0
sp_qd_handle_on_invoice_upd_sts 11138 11138 0 0.00 0 35606 314 0 0
sp_qd_handle_on_reserve_upd_sts 10586 10586 0 0.00 0 24758 63 0 0
sp_reserve_write_off 10592 10592 0 0.00 6 24775 182 0 0
sp_supplier_invoice 5756 5756 0 0.00 6 89710 3264 0 0
sp_supplier_order 5796 5796 0 0.00 5 111920 1177 0 0
srv_aggregate_perf_data 103 103 0 0.00 2214 143381 17308 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 2166 169257 43559 0 0
srv_find_qd_qs_mism 1094528 1094528 0 0.00 0 18928 5 0 0
srv_make_invnt_saldo 600 600 0 0.00 26 94720 1158 0 0
srv_make_money_saldo 868 868 0 0.00 1 25228 53 0 0
srv_recalc_idx_stat 4 4 0 0.00 222677 461214 294844 0 0
t$perf-abend:sp_add_invoice_to_stock 104 104 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5232 5232 0 0.00 0 11775 162 0 0
t$perf-norm:sp_cancel_adding_invoice 467 467 0 0.00 1 3822 78 0 0
t$perf-norm:sp_cancel_client_order 1806 1806 0 0.00 0 5860 23 0 0
t$perf-norm:sp_cancel_customer_reserve 739 739 0 0.00 0 3533 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 707 707 0 0.00 0 7199 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 413 413 0 0.00 0 2925 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 420 420 0 0.00 0 6165 43 0 0
t$perf-norm:sp_cancel_supplier_order 404 404 0 0.00 0 43429 1826 0 0
t$perf-norm:sp_cancel_write_off 1043 1043 0 0.00 0 3766 19 0 0
t$perf-norm:sp_client_order 21547 21547 0 0.00 0 10078 21 0 0
t$perf-norm:sp_customer_reserve 1046 1046 0 0.00 0 3320 11 0 0
t$perf-norm:sp_pay_from_customer 7517 7517 0 0.00 0 9571 17 0 0
t$perf-norm:sp_pay_to_supplier 6188 6188 0 0.00 0 10741 17 0 0
t$perf-norm:sp_reserve_write_off 10592 10592 0 0.00 0 8079 13 0 0
t$perf-norm:sp_supplier_invoice 5756 5756 0 0.00 0 7132 20 0 0
t$perf-norm:sp_supplier_order 5796 5796 0 0.00 0 4524 16 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 1014 26 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 4 0 0 0
t$perf-norm:srv_make_invnt_saldo 600 600 0 0.00 0 2015 22 0 0
t$perf-norm:srv_make_money_saldo 868 868 0 0.00 0 2351 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 7 4 0 0
v_add_invoice_to_stock 10650 10442 208 1.95 0 6686 20 0 208
v_all_customers 39008 39008 0 0.00 0 30 1 0 0
v_all_suppliers 32042 32042 0 0.00 0 29 1 0 0
v_all_wares 43094 43094 0 0.00 0 19 1 0 0
v_cancel_adding_invoice 934 934 0 0.00 0 1011 7 0 0
v_cancel_client_order 3612 3612 0 0.00 0 7403 11 0 0
v_cancel_customer_prepayment 1414 1414 0 0.00 0 8233 13 0 0
v_cancel_customer_reserve 1478 1478 0 0.00 0 2533 10 0 0
v_cancel_payment_to_supplier 826 826 0 0.00 0 320 3 0 0
v_cancel_supplier_invoice 868 840 28 3.22 0 1516 17 0 28
v_cancel_supplier_order 808 808 0 0.00 0 2528 8 0 0
v_cancel_write_off 2086 2086 0 0.00 0 3026 15 0 0
v_max_id_clo_ord 5796 5796 0 0.00 0 1745 2 0 0
v_max_id_clo_res 1046 1046 0 0.00 0 2831 12 0 0
v_max_id_ord_sup 5756 5756 0 0.00 0 6882 4 0 0
v_max_non_paid_invoice 1711 1711 0 0.00 0 127 2 0 0
v_max_non_paid_realizn 4222 4222 0 0.00 0 7439 5 0 0
v_min_id_clo_ord 5796 5796 0 0.00 0 4434 7 0 0
v_min_id_clo_res 1046 1046 0 0.00 0 9738 14 0 0
v_min_id_ord_sup 5756 5756 0 0.00 0 5618 6 0 0
v_min_non_paid_invoice 6180 6180 0 0.00 0 9472 18 0 0
v_min_non_paid_realizn 7511 7511 0 0.00 0 8540 16 0 0
v_our_firm 10664 10664 0 0.00 0 16 1 0 0
v_reserve_write_off 21172 21172 0 0.00 0 7088 7 0 0
x_lock_depdocs_on_canc_invoice 11961 11961 0 0.00 0 20062 111 0 0
x_lock_depdocs_on_canc_sup_ord 404 404 0 0.00 0 10424 379 0 0
x_make_qstorno_1000_1200 5796 5796 0 0.00 1 111881 1097 0 0
x_make_qstorno_1200_2000 5756 5756 0 0.00 3 88684 3128 0 0
x_make_qstorno_2100_3300 476209 476209 0 0.00 0 106407 160 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 07-06-2022 01:39:27 to 07-06-2022 01:39:31.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
23:08:24 15 8192000000 8803236656 8816136192 7670864 0.093 3 1 2 0 7771824 58176 42784
23:09:25 21176 8192000000 9898656992 10209660928 979398656 10.678 100 90 178 1 1009261328 5702592 24160080
23:10:51 19898 8192000000 9952049056 10275708928 1035064576 11.217 100 91 182 0 1067006640 8425968 23516096
23:12:22 16858 8192000000 9979575136 10314534912 1065929792 11.513 100 84 168 0 1096701696 9450192 21321712
23:13:47 13725 8192000000 10036549824 10390335488 1126139904 12.085 100 74 148 0 1154185072 10638624 17406544
23:15:04 9950 8192000000 10112004592 10473709568 1192114480 12.703 100 81 162 0 1222221408 11805248 18301680
23:16:15 5653 8192000000 10146975856 10518335488 1232171936 13.074 100 83 166 0 1265107008 14631296 18303776
23:17:24 1963 8192000000 10171775312 10550697984 1258687936 13.318 100 86 174 0 1290743680 12824368 19231376
23:18:28 3187 8192000000 10201164192 10582827008 1286288048 13.570 100 91 181 0 1319162672 13054768 19819856
23:19:33 29983 8192000000 10203405664 10599841792 1301172816 13.706 100 56 107 1 1320965184 7086080 12706288
23:21:04 6980 8192000000 10201192800 10611122176 1310274416 13.789 100 25 49 2 1322747792 6592160 5881216
23:22:15 14351 8192000000 10198196176 10617057280 1318419824 13.862 100 15 30 1 1327250464 5395728 3434912
23:23:30 17824 8192000000 10210203360 10627608576 1324115712 13.914 100 16 32 2 1333908752 6126528 3666512
23:24:52 22716 8192000000 10225460544 10646757376 1332167440 13.987 100 26 52 0 1348062096 10368864 5525792
23:26:16 7247 8192000000 10224878160 10650648576 1337709984 14.037 100 25 50 1 1349549984 5731856 6108144
23:27:24 6107 8192000000 10198706912 10622951424 1301156384 13.706 98 18 36 0 1310853024 5731104 3965536
23:28:30 16207 8192000000 10164705728 10570366976 1205004560 12.823 93 24 48 1 1215771840 4692688 6074592
23:29:49 26759 8192000000 10142238352 10544066560 1157596064 12.381 90 13 26 0 1164992592 5021872 2374656
23:31:16 5766 8192000000 10140638816 10536435712 1201268992 12.788 95 22 45 1 1211966112 4896096 5801024
23:32:23 7747 8192000000 10154213152 10555944960 1230674288 13.060 97 13 26 0 1238848976 5384416 2790272
23:33:33 60266 8192000000 10165885808 10581159936 1272406928 13.444 99 23 46 1 1283036880 4992944 5637008
23:35:33 7473 8192000000 10201150224 10611589120 1307600384 13.764 100 22 44 0 1318131056 5795392 4735280
23:36:43 9174 8192000000 10198317920 10618712064 1312183776 13.806 100 21 42 0 1323126432 5892768 5049888
23:37:57 8635 8192000000 10217575616 10633019392 1321254560 13.888 100 17 32 2 1329627584 4592416 3780608
23:39:12 10711 8192000000 10259003456 10667376640 1346928288 14.120 100 45 90 3 1366081632 8283536 10869808
23:40:25 44457 8192000000 10258207904 10684874752 1362655904 14.261 100 39 78 0 1382141408 10006384 9479120
23:42:15 16468 8192000000 10276852480 10693173248 1355745712 14.199 99 44 88 2 1379096144 13419664 9930768
23:43:35 36137 8192000000 10283501840 10703654912 1351534512 14.161 98 49 98 2 1381674816 18378608 11761696
23:45:16 22094 8192000000 10300724864 10730573824 1381930032 14.434 100 39 78 0 1411996288 20318832 9747424
23:46:46 6401 8192000000 10325066032 10743386112 1395297728 14.553 100 68 134 2 1435918272 24921008 15699536
23:47:56 14784 8192000000 10306383264 10722430976 1354962576 14.192 98 61 119 0 1397723536 27732800 15028160
23:49:12 2964 8192000000 10307445808 10724507648 1373092944 14.355 100 62 123 0 1418045072 29661120 15291008
23:50:24 5207 8192000000 10321277088 10733162496 1372652720 14.351 99 81 159 2 1419132304 27967456 18512128
23:51:34 2165 8192000000 10314331264 10723237888 1368232064 14.311 99 75 152 0 1413830368 27743920 17854384
23:52:40 2985 8192000000 10306741904 10717765632 1370996496 14.336 100 61 120 1 1415145136 29367456 14781184
23:53:53 8377 8192000000 10337736224 10748297216 1390227008 14.508 100 70 141 2 1442676512 35357776 17091728
23:55:07 6159 8192000000 10332138688 10743246848 1384577584 14.457 100 66 131 1 1438730176 37857968 16294624
23:56:18 3942 8192000000 10338704816 10758365184 1393112528 14.534 100 51 100 1 1448575216 41828720 13633968
23:57:33 4362 8192000000 10339368464 10746560512 1360558016 14.242 99 78 156 2 1429279632 47721008 21000608
23:58:46 7369 8192000000 10334753792 10746650624 1359269376 14.231 99 52 102 2 1424967664 51731920 13966368
00:00:01 12201 8192000000 10309194400 10739838976 1365213776 14.284 100 27 54 0 1427510688 54542864 7754048
00:01:41 6801 8192000000 10330850160 10757357568 1378010896 14.399 100 42 84 0 1434829936 45647792 11171248
00:02:49 14767 8192000000 10336490688 10779451392 1388906288 14.496 100 27 54 0 1440464320 44233088 7324944
00:04:09 28381 8192000000 10324496592 10771574784 1383949424 14.452 100 23 46 1 1435056160 44516368 6590368
00:06:00 26306 8192000000 10327294976 10779561984 1369474032 14.322 99 30 58 2 1429023696 50920368 8629296
00:07:38 45269 8192000000 10312588080 10774478848 1359502704 14.233 99 11 22 0 1402775248 40181680 3090864
00:09:24 30472 8192000000 10301486816 10750091264 1385278592 14.464 100 16 32 0 1400411360 11903360 3229408
00:11:33 39229 8192000000 10317924336 10757189632 1384854976 14.460 99 32 64 1 1406476096 14056240 7564880
00:13:13 49660 8192000000 10296558064 10740396032 1381687664 14.432 100 12 23 1 1392157552 8597888 1872000
00:15:06 124392 8192000000 10314220112 10759147520 1368611968 14.315 98 10 19 1 1382056560 11573856 1870736
00:18:17 157949 8192000000 10303525664 10748694528 1365201600 14.284 97 27 54 0 1380794352 9575072 6017680
00:22:59 69046 8192000000 10296750832 10752262144 1383628944 14.449 99 22 44 0 1400221840 11369696 5223200
00:25:10 34333 8192000000 10311897296 10765340672 1402969616 14.621 100 23 46 0 1421807824 13176384 5661824
00:27:04 41752 8192000000 10299732672 10752872448 1380032352 14.417 99 42 84 1 1404142272 14250464 9859456
00:29:07 24518 8192000000 10277719552 10751406080 1373191744 14.356 100 6 12 0 1390832320 16463440 1177136
00:30:32 36108 8192000000 10287141808 10748030976 1375340144 14.375 99 26 50 0 1392496224 11825184 5330896
00:32:18 23618 8192000000 10282914080 10746351616 1376173824 14.382 99 6 12 0 1388559616 10994640 1391152
00:33:52 80585 8192000000 10281923824 10747367424 1351451040 14.161 97 15 30 0 1368339120 13619536 3268544
00:36:28 24280 8192000000 10284941488 10735124480 1358124336 14.221 98 23 46 0 1371900896 8858800 4917760
00:37:57 55866 8192000000 10262695520 10726047744 1351309984 14.159 99 6 12 0 1365329696 12903600 1116112
00:40:18 33807 8192000000 10248010256 10704326656 1317845872 13.857 96 14 28 0 1330728400 10061536 2820992
00:41:55 57214 8192000000 10281959232 10730262528 1361502992 14.251 99 18 35 1 1374841952 9631296 3707664
00:43:55 11088 8192000000 10280548432 10717499392 1352815168 14.173 99 42 84 1 1369811616 7830848 9165600
00:45:08 8287 8192000000 10267069312 10701803520 1337928304 14.039 99 43 86 2 1357477808 10538336 9011168
00:46:21 32223 8192000000 10271666048 10718072832 1341005872 14.066 99 28 56 0 1366201376 18579264 6616240
00:47:54 44081 8192000000 10252977168 10684878848 1284081920 13.550 95 29 58 0 1298409840 7794704 6533216
00:49:41 115732 8192000000 10268671824 10723663872 1334328464 14.006 98 13 26 0 1350516816 13813104 2375248
00:52:52 64144 8192000000 10291675136 10742628352 1380959040 14.425 100 27 54 0 1404756064 17599328 6197696
00:54:57 113735 8192000000 10272315088 10726207488 1346383840 14.115 98 24 48 0 1362173632 10011184 5778608
00:57:53 49149 8192000000 10270752288 10733363200 1374486816 14.367 100 13 26 0 1384211824 6902576 2822432
00:59:47 70008 8192000000 10277344016 10737733632 1382054592 14.435 100 11 22 0 1393537264 9172944 2309728
01:03:58 46305 8192000000 10279795184 10739589120 1376260272 14.383 100 14 28 2 1395318864 15553008 3505584
01:05:49 65868 8192000000 10291354704 10760642560 1342008176 14.076 96 28 56 0 1373075376 24002592 7064608
01:07:56 83592 8192000000 10268790000 10731298816 1315780512 13.838 95 18 36 0 1336920352 17321488 3818352
01:10:20 40966 8192000000 10313837424 10768650240 1397561360 14.573 100 28 56 2 1421811840 18118992 6131488
01:12:10 73570 8192000000 10299086944 10751377408 1335855760 14.020 96 14 28 0 1355121584 16372288 2893536
01:14:26 36724 8192000000 10310227824 10762174464 1392651280 14.530 100 41 82 1 1415282032 13807376 8823376
01:16:08 91298 8192000000 10283029872 10735685632 1302232608 13.716 93 8 16 0 1323734704 19638240 1863856
01:18:41 169257 8192000000 10277640944 10741575680 1322871936 13.903 96 19 38 1 1345015232 17494832 4648464
01:22:31 58723 8192000000 10273443328 10744614912 1367184048 14.302 99 12 23 1 1384451200 14099984 3167168
01:24:30 52904 8192000000 10260410112 10723557376 1338061136 14.040 97 11 22 2 1350458320 9557712 2839472
01:26:23 71638 8192000000 10258654048 10724335616 1323419104 13.908 96 9 18 0 1336516208 10689936 2407168
01:28:39 56442 8192000000 10260913264 10714791936 1354939232 14.192 99 10 19 0 1363338144 6188032 2210880
01:30:36 62302 8192000000 10252200880 10710941696 1331925888 13.985 97 15 30 0 1342621632 7542128 3153616
01:32:39 73482 8192000000 10244897088 10708828160 1292402272 13.626 95 6 12 0 1304840016 11218992 1218752
01:34:53 27151 8192000000 10279696400 10719387648 1325966960 13.931 97 28 56 1 1339399696 7494720 5938016
01:36:23 19460 8192000000 10275351216 10723667968 1352620784 14.171 99 33 66 0 1374788128 14706416 7460928
01:37:50 38440 8192000000 9346912208 9647931392 501914160 5.773 37 3 6 0 507966976 5784208 268608

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 07-06-2022 01:39:33 to 07-06-2022 01:39:47.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 104 v_add_invoice_to_stock 335544517
except 14 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 07-06-2022 01:39:48 to 07-06-2022 01:39:50.

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          253775
MON$OLDEST_ACTIVE               253776
MON$OLDEST_SNAPSHOT             253776
MON$NEXT_TRANSACTION            253776
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                       2056800
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33602 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33602 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33602 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33602 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'

07.06.22 01:39:51. There are 3 logs in /var/tmp/logs-oltp30 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2022-06-07 01:39:54 No severe PSQL-related problems occured
07.06.22 01:39:55. End of report.