Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 16.08.2022 22: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 15982080 kB
MemAvailable 30926228 kB
Buffers 0 kB
Cached 15759096 kB
SwapCached 2044 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 2022-08-16 22:38:28
DTS_END                         2022-08-16 22:38:28.3820
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/43070
TRN_ID                          235643
ATT_ID                          648
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: 9458.40 22148 1135008
customer order: creation 165.31 380 19837
customer order: refuse 14.51 356 1741
order to supplier: creation 43.13 1346 5176
order to supplier: removal 3.76 105056 451
invoice (draft): creation 44.27 3267 5312
invoice (draft): removal 56.38 838 6765
invoice accept: apply 40.19 17982 4823
invoice accept: cancel 50.59 734 6071
customer reserve: creation 3782.48 175 453898
customer reserve: removal 4960.55 62 595266
realization accept: apply 81.64 166 9797
realization accept: cancel 87.99 106 10559
payment from customer: creation 57.95 110 6954
payment from customer: removal 5.25 82 630
payment to supplier: creation 48.83 118 5859
payment to supplier: removal 3.45 76 414
service: total inventory turnovers 4.97 1144 596
service: total monetary turnovers 6.28 35 754
service: aggregate perf. data 0.84 20923 101
service: refresh index statistics 0.03 290013 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 16-08-2022 22:39:53 to 16-08-2022 22:39:55.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4229
TEST_TIME 2 3094
TEST_TIME 3 4429
TEST_TIME 4 4008
TEST_TIME 5 4610
TEST_TIME 6 4781
TEST_TIME 7 4764
TEST_TIME 8 5061
TEST_TIME 9 5144
TEST_TIME 10 5321
TEST_TIME 11 5903
TEST_TIME 12 6079
TEST_TIME 13 6263
TEST_TIME 14 6361
TEST_TIME 15 6352
TEST_TIME 16 6442
TEST_TIME 17 6377
TEST_TIME 18 6564
TEST_TIME 19 6574
TEST_TIME 20 6998
TEST_TIME 21 7697
TEST_TIME 22 7844
TEST_TIME 23 8079
TEST_TIME 24 8537
TEST_TIME 25 9631
TEST_TIME 26 10999
TEST_TIME 27 10915
TEST_TIME 28 10951
TEST_TIME 29 10759
TEST_TIME 30 10499
TEST_TIME 31 10385
TEST_TIME 32 10472
TEST_TIME 33 10402
TEST_TIME 34 10336
TEST_TIME 35 10183
TEST_TIME 36 10122
TEST_TIME 37 10039
TEST_TIME 38 10174
TEST_TIME 39 10172
TEST_TIME 40 10102
TEST_TIME 41 10037
TEST_TIME 42 9884
TEST_TIME 43 9815
TEST_TIME 44 9777
TEST_TIME 45 10014
TEST_TIME 46 9881
TEST_TIME 47 9753
TEST_TIME 48 9751
TEST_TIME 49 9592
TEST_TIME 50 9544
TEST_TIME 51 9488
TEST_TIME 52 9529
TEST_TIME 53 9670
TEST_TIME 54 9712
TEST_TIME 55 9719
TEST_TIME 56 9696
TEST_TIME 57 9781
TEST_TIME 58 9763
TEST_TIME 59 9703
TEST_TIME 60 9686
TEST_TIME 61 9825
TEST_TIME 62 9829
TEST_TIME 63 9778
TEST_TIME 64 9985
TEST_TIME 65 9919
TEST_TIME 66 9795
TEST_TIME 67 9773
TEST_TIME 68 9664
TEST_TIME 69 9673
TEST_TIME 70 9688
TEST_TIME 71 9606
TEST_TIME 72 9563
TEST_TIME 73 9594
TEST_TIME 74 9534
TEST_TIME 75 9478
TEST_TIME 76 9483
TEST_TIME 77 9503
TEST_TIME 78 9627
TEST_TIME 79 9652
TEST_TIME 80 9670
TEST_TIME 81 9693
TEST_TIME 82 9698
TEST_TIME 83 9716
TEST_TIME 84 9758
TEST_TIME 85 9746
TEST_TIME 86 9803
TEST_TIME 87 9789
TEST_TIME 88 9688
TEST_TIME 89 9669
TEST_TIME 90 9609
TEST_TIME 91 9646
TEST_TIME 92 9632
TEST_TIME 93 9580
TEST_TIME 94 9572
TEST_TIME 95 9534
TEST_TIME 96 9600
TEST_TIME 97 9575
TEST_TIME 98 9587
TEST_TIME 99 9602
TEST_TIME 100 9604
TEST_TIME 101 9566
TEST_TIME 102 9595
TEST_TIME 103 9610
TEST_TIME 104 9639
TEST_TIME 105 9667
TEST_TIME 106 9718
TEST_TIME 107 9695
TEST_TIME 108 9664
TEST_TIME 109 9637
TEST_TIME 110 9580
TEST_TIME 111 9563
TEST_TIME 112 9566
TEST_TIME 113 9496
TEST_TIME 114 9506
TEST_TIME 115 9469
TEST_TIME 116 9533
TEST_TIME 117 9472
TEST_TIME 118 9458
TEST_TIME 119 9495
TEST_TIME 120 9459

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 16-08-2022 22:39:57 to 16-08-2022 22:40:00.

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 1554579 1554579 0 0.00 0 21825 6 0 0
doc_list_biud 1554577 1554577 0 0.00 0 40081 30 0 0
sp_add_invoice_to_stock 4895 4823 72 1.47 31 249711 17982 0 72
sp_cancel_adding_invoice 6071 6071 0 0.00 5 73610 734 0 0
sp_cancel_client_order 1741 1741 0 0.00 4 15921 357 0 0
sp_cancel_customer_reserve 595266 595266 0 0.00 0 40083 62 0 0
sp_cancel_pay_from_customer 630 630 0 0.00 2 5146 82 0 0
sp_cancel_pay_to_supplier 414 414 0 0.00 3 2498 77 0 0
sp_cancel_supplier_invoice 6772 6765 7 0.10 1 14839 839 0 7
sp_cancel_supplier_order 451 451 0 0.00 4 1157425 105056 0 0
sp_cancel_write_off 10559 10559 0 0.00 1 23862 106 0 0
sp_client_order 19837 19837 0 0.00 3 31824 380 0 0
sp_customer_reserve 453898 453898 0 0.00 0 163994 175 0 0
sp_fill_shopping_cart 30316 30316 0 0.00 1 17051 32 0 0
sp_fill_shopping_cart_clo_res 453896 453896 0 0.00 0 37369 16 0 0
sp_get_clo_for_invoice 4821 4821 0 0.00 0 20003 313 0 0
sp_kill_cost_storno 17674 17674 0 0.00 0 20393 54 0 0
sp_kill_qstorno_ret_qs2qd 1208307 1208307 0 0.00 0 40080 34 0 0
sp_kill_qty_storno 635471 635471 0 0.00 0 40081 72 0 0
sp_lock_selected_doc 20293 20293 0 0.00 0 6408 4 0 0
sp_make_cost_storno 27431 27431 0 0.00 0 16542 50 0 0
sp_multiply_rows_for_pdistr 27431 27431 0 0.00 0 7970 29 0 0
sp_multiply_rows_for_qdistr 19837 19837 0 0.00 0 20010 169 0 0
sp_pay_from_customer 6954 6954 0 0.00 4 12468 111 0 0
sp_pay_to_supplier 5859 5859 0 0.00 5 16605 118 0 0
sp_payment_common 12813 12813 0 0.00 1 12464 27 0 0
sp_qd_handle_on_cancel_clo 1741 1741 0 0.00 0 15916 332 0 0
sp_qd_handle_on_invoice_upd_sts 10892 10892 0 0.00 0 31690 338 0 0
sp_qd_handle_on_reserve_upd_sts 9797 9797 0 0.00 0 15066 59 0 0
sp_reserve_write_off 9797 9797 0 0.00 6 27148 167 0 0
sp_supplier_invoice 5312 5312 0 0.00 6 85256 3268 0 0
sp_supplier_order 5176 5176 0 0.00 4 49150 1347 0 0
srv_aggregate_perf_data 101 101 0 0.00 2452 238154 20924 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 1046 158906 50361 0 0
srv_find_qd_qs_mism 1088101 1088101 0 0.00 0 21825 5 0 0
srv_make_invnt_saldo 596 596 0 0.00 37 97639 1145 0 0
srv_make_money_saldo 754 754 0 0.00 1 8067 36 0 0
srv_recalc_idx_stat 4 4 0 0.00 171773 375648 290014 0 0
t$perf-abend:sp_add_invoice_to_stock 72 72 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4823 4823 0 0.00 0 21699 177 0 0
t$perf-norm:sp_cancel_adding_invoice 385 385 0 0.00 0 24512 158 0 0
t$perf-norm:sp_cancel_client_order 1741 1741 0 0.00 0 10421 27 0 0
t$perf-norm:sp_cancel_customer_reserve 679 679 0 0.00 0 2694 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 630 630 0 0.00 0 2401 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 414 414 0 0.00 0 313 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 391 391 0 0.00 0 3157 36 0 0
t$perf-norm:sp_cancel_supplier_order 451 451 0 0.00 0 18568 1266 0 0
t$perf-norm:sp_cancel_write_off 984 984 0 0.00 0 2044 10 0 0
t$perf-norm:sp_client_order 19837 19837 0 0.00 0 8497 18 0 0
t$perf-norm:sp_customer_reserve 1012 1012 0 0.00 0 4371 9 0 0
t$perf-norm:sp_pay_from_customer 6954 6954 0 0.00 0 7116 12 0 0
t$perf-norm:sp_pay_to_supplier 5859 5859 0 0.00 0 3264 8 0 0
t$perf-norm:sp_reserve_write_off 9797 9797 0 0.00 0 7354 10 0 0
t$perf-norm:sp_supplier_invoice 5312 5312 0 0.00 0 7047 19 0 0
t$perf-norm:sp_supplier_order 5176 5176 0 0.00 0 16015 21 0 0
t$perf-norm:srv_aggregate_perf_data 101 101 0 0.00 0 88 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 192 9 0 0
t$perf-norm:srv_make_invnt_saldo 596 596 0 0.00 0 2023 18 0 0
t$perf-norm:srv_make_money_saldo 754 754 0 0.00 0 3160 23 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 2 0 0
v_add_invoice_to_stock 9786 9642 144 1.47 0 8208 20 0 144
v_all_customers 36910 36910 0 0.00 0 22 1 0 0
v_all_suppliers 29894 29894 0 0.00 0 25 1 0 0
v_all_wares 39674 39674 0 0.00 0 76 1 0 0
v_cancel_adding_invoice 770 770 0 0.00 0 1527 8 0 0
v_cancel_client_order 3482 3482 0 0.00 0 3303 4 0 0
v_cancel_customer_prepayment 1260 1260 0 0.00 0 1203 4 0 0
v_cancel_customer_reserve 1358 1358 0 0.00 0 515 4 0 0
v_cancel_payment_to_supplier 828 828 0 0.00 0 1114 10 0 0
v_cancel_supplier_invoice 796 782 14 1.75 0 2209 17 0 14
v_cancel_supplier_order 902 902 0 0.00 0 688 5 0 0
v_cancel_write_off 1968 1968 0 0.00 0 5273 19 0 0
v_max_id_clo_ord 5175 5175 0 0.00 0 285 1 0 0
v_max_id_clo_res 1012 1012 0 0.00 0 5364 22 0 0
v_max_id_ord_sup 5303 5303 0 0.00 0 2235 3 0 0
v_max_non_paid_invoice 1390 1390 0 0.00 0 4575 7 0 0
v_max_non_paid_realizn 3422 3422 0 0.00 0 1952 4 0 0
v_min_id_clo_ord 5175 5175 0 0.00 0 2045 5 0 0
v_min_id_clo_res 1012 1012 0 0.00 0 7155 13 0 0
v_min_id_ord_sup 5303 5303 0 0.00 0 6660 7 0 0
v_min_non_paid_invoice 5859 5859 0 0.00 0 10111 16 0 0
v_min_non_paid_realizn 6954 6954 0 0.00 0 12460 24 0 0
v_our_firm 9828 9828 0 0.00 0 19 1 0 0
v_reserve_write_off 19594 19594 0 0.00 0 5523 6 0 0
x_lock_depdocs_on_canc_invoice 12445 12445 0 0.00 0 24125 117 0 0
x_lock_depdocs_on_canc_sup_ord 451 451 0 0.00 0 13365 335 0 0
x_make_qstorno_1000_1200 5176 5176 0 0.00 2 49131 1280 0 0
x_make_qstorno_1200_2000 5312 5312 0 0.00 2 85233 3155 0 0
x_make_qstorno_2100_3300 453898 453898 0 0.00 0 163992 157 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 16-08-2022 22:40:01 to 16-08-2022 22:40:05.

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
20:08:32 4 8192000000 8800865984 8812728320 6928592 0.084 2 1 2 0 7032896 61520 42784
20:09:33 28693 8192000000 9885397824 10189352960 961536256 10.504 100 90 180 0 991599840 5828208 24235376
20:11:07 13029 8192000000 9926368320 10244247552 1014538448 11.019 100 89 178 0 1045557584 7444240 23574896
20:12:28 10353 8192000000 9969734880 10300252160 1052613616 11.386 100 87 175 1 1082668352 8494368 21560368
20:13:45 12157 8192000000 10029535600 10374057984 1116055616 11.990 100 76 152 0 1143531504 9186480 18289408
20:14:59 7942 8192000000 10075956928 10437218304 1169215312 12.489 100 77 154 0 1198540448 11395904 17929232
20:16:11 6508 8192000000 10130360448 10501730304 1219611312 12.958 100 74 146 0 1247393136 12048016 15733808
20:17:19 2841 8192000000 10165255760 10540474368 1250295472 13.241 100 82 162 0 1281032816 13263840 17473504
20:18:24 2652 8192000000 10184482672 10568089600 1274904528 13.466 100 80 158 0 1304718400 12011424 17802448
20:19:29 716 8192000000 10209922208 10601017344 1304297792 13.734 100 86 169 0 1335591568 12845392 18448384
20:20:32 933 8192000000 10225882672 10616856576 1316620160 13.846 100 84 167 2 1347391568 12679648 18091760
20:21:36 2170 8192000000 10221549408 10622529536 1323058912 13.904 100 53 105 1 1342463184 7608496 11795776
20:22:41 3984 8192000000 10221301536 10628943872 1323562128 13.909 100 52 104 2 1344413920 9413600 11438192
20:23:46 4604 8192000000 10216644832 10633203712 1328908912 13.957 100 32 64 0 1343242160 6682176 7651072
20:24:51 48647 8192000000 10233312192 10645954560 1339743264 14.055 100 14 29 1 1347644000 4806336 3094400
20:26:41 3206 8192000000 10244659152 10663366656 1345327712 14.105 100 41 82 0 1362307376 8062256 8917408
20:27:46 4374 8192000000 10235101888 10657533952 1343912416 14.093 100 28 56 1 1356350048 6134512 6303120
20:28:51 9912 8192000000 10194892352 10613555200 1269707120 13.419 96 20 41 0 1283653664 9197120 4749424
20:30:03 53316 8192000000 10170010352 10576076800 1224271376 13.001 94 30 59 0 1236239968 5863696 6104896
20:31:57 5856 8192000000 10152533488 10559750144 1252900400 13.265 99 31 62 0 1265958016 6206448 6851168
20:33:04 5624 8192000000 10138949440 10536153088 1230005648 13.054 98 19 38 0 1239890032 5931840 3952544
20:34:12 8916 8192000000 10157837488 10564558848 1257493632 13.307 99 18 37 0 1266808080 5377360 3937088
20:35:26 21847 8192000000 10180549984 10588618752 1284731968 13.556 100 26 52 0 1296044224 5974224 5338032
20:36:50 8905 8192000000 10193629936 10607861760 1303455408 13.727 100 13 26 0 1311345344 4939408 2950528
20:38:00 18335 8192000000 10214092560 10633531392 1319854368 13.875 100 16 32 0 1328134272 4574720 3705184
20:39:25 118146 8192000000 10256256688 10682380288 1356088192 14.202 100 42 84 0 1373817776 8443536 9286048
20:42:31 55822 8192000000 10280118976 10716241920 1379406640 14.411 100 34 64 0 1403472608 16360080 7705888
20:45:24 8214 8192000000 10325624960 10748493824 1404060784 14.631 100 51 101 0 1436232336 20751472 11420080
20:46:39 7379 8192000000 10335009024 10760568832 1410911552 14.692 100 47 94 0 1446532928 24501968 11119408
20:47:50 7507 8192000000 10366911408 10786328576 1425787104 14.824 100 52 103 1 1470366272 31882256 12696912
20:49:00 4627 8192000000 10372685824 10798379008 1430524032 14.866 100 56 109 0 1482492640 37751088 14217520
20:50:10 7491 8192000000 10362176688 10782793728 1411472944 14.697 99 59 118 1 1465904208 39330752 15100512
20:51:21 4903 8192000000 10388510032 10811916288 1434161376 14.898 100 63 123 0 1495608624 45450480 15996768
20:52:31 4123 8192000000 10408762432 10826436608 1442821280 14.975 100 81 162 1 1513006624 49761104 20424240
20:53:40 4553 8192000000 10405103056 10821758976 1440377744 14.953 100 71 140 1 1509192224 51006400 17808080
20:54:50 3451 8192000000 10405110656 10815840256 1429415184 14.856 100 80 153 2 1508670400 59106912 20148304
20:55:59 2328 8192000000 10388145328 10795479040 1407754240 14.664 100 85 169 2 1495614720 65580352 22280128
20:57:06 3110 8192000000 10394170304 10811248640 1417356064 14.749 100 60 120 0 1502664672 69574112 15734496
20:58:16 1045 8192000000 10371728688 10771095552 1374786176 14.370 99 71 139 2 1471694352 78472400 18435776
20:59:22 5533 8192000000 10364652448 10781110272 1381121536 14.427 100 54 108 0 1468576832 72073168 15382128
21:00:30 17443 8192000000 10346008384 10770202624 1371531008 14.341 100 36 72 0 1453425936 71175648 10719280
21:01:53 23449 8192000000 10355905328 10787180544 1362290320 14.258 99 41 80 1 1456085952 81754560 12041072
21:03:56 69277 8192000000 10258450944 10720129024 1311330832 13.798 97 14 28 1 1361662880 46470352 3861696
21:06:07 68881 8192000000 10279397312 10719604736 1344186016 14.095 99 17 34 0 1365590144 17701408 3702720
21:08:17 78989 8192000000 10268710848 10700963840 1325929104 13.930 97 27 54 0 1338701104 6573392 6198608
21:10:37 77435 8192000000 10283756016 10723774464 1345128928 14.104 98 17 30 0 1356359968 7962272 3268768
21:12:56 151098 8192000000 10318400368 10764054528 1371281888 14.339 99 26 50 1 1384332512 7815872 5234752
21:17:04 42371 8192000000 10290343760 10742218752 1371749312 14.343 99 18 36 0 1387675120 11717568 4208240
21:18:57 38985 8192000000 10300367504 10752466944 1363444128 14.268 98 11 22 0 1374482144 8226800 2811216
21:20:37 63530 8192000000 10314480640 10769412096 1406188432 14.650 100 21 42 0 1426699200 15705728 4805040
21:22:41 86447 8192000000 10306450800 10789756928 1402335824 14.616 100 21 42 0 1431973632 24300336 5337472
21:25:46 28006 8192000000 10301601424 10768932864 1407146816 14.659 100 47 94 0 1430095568 12339216 10609536
21:27:24 63220 8192000000 10314701824 10778132480 1398002128 14.577 99 25 50 1 1423384000 19369136 6012736
21:29:45 71093 8192000000 10315071888 10778525696 1394328352 14.544 99 7 14 0 1413192464 17319200 1544912
21:32:08 64333 8192000000 10319965216 10771173376 1379976480 14.416 98 26 50 0 1398248656 12713584 5558592
21:34:13 14191 8192000000 10319820592 10767642624 1403214400 14.624 100 46 93 0 1421492608 7222448 11055760
21:35:30 115455 8192000000 10324829744 10780180480 1371776672 14.343 97 15 30 0 1386302224 11267968 3257584
21:38:30 59009 8192000000 10305990560 10779721728 1386710640 14.477 99 13 25 0 1406574624 16507376 3356608
21:40:29 51283 8192000000 10290530608 10732347392 1307724368 13.765 94 22 44 0 1322228016 9501536 5002112
21:42:23 70898 8192000000 10258559088 10708451328 1259965184 13.330 92 18 36 0 1281183024 17447344 3770496
21:44:35 83662 8192000000 10271989328 10723090432 1344291920 14.096 98 36 72 0 1360288192 7975328 8020944
21:46:59 8917 8192000000 10258178896 10695774208 1336134352 14.023 98 39 78 0 1356220336 11080576 9005408
21:48:09 79408 8192000000 10234421392 10684137472 1287643744 13.583 94 17 34 0 1300784400 9248176 3892480
21:50:57 54503 8192000000 10231185712 10688548864 1301769040 13.711 96 18 36 0 1315497680 9120352 4608288
21:52:51 65703 8192000000 10273372224 10726707200 1347870320 14.128 99 16 32 1 1366501264 14799728 3831216
21:54:57 52896 8192000000 10274049664 10718023680 1315691920 13.838 96 19 38 0 1337824464 17711760 4420784
21:56:55 54961 8192000000 10261219728 10708602880 1324274960 13.915 97 27 54 0 1345016192 14593024 6148208
21:58:52 72636 8192000000 10269026416 10714222592 1337672288 14.036 98 16 32 0 1350597344 9571808 3353248
22:01:07 76316 8192000000 10279284544 10736427008 1342938496 14.084 98 17 34 0 1360137168 13555872 3642800
22:03:46 36808 8192000000 10289626608 10733666304 1356716976 14.208 99 29 56 0 1375809904 13099568 5993360
22:05:36 158906 8192000000 10281087792 10740334592 1373844368 14.361 100 19 38 0 1393723376 15732320 4146688
22:09:17 64637 8192000000 10273447840 10736902144 1366664448 14.297 99 16 33 0 1381266608 11017984 3584176
22:11:34 47146 8192000000 10265821840 10728325120 1337392352 14.034 97 6 10 1 1348235840 9983776 859712
22:13:21 64568 8192000000 10289692816 10743869440 1377780624 14.397 99 19 39 0 1393480144 11741040 3958480
22:15:44 34755 8192000000 10308114096 10761306112 1403057920 14.622 100 32 64 0 1424020544 14176128 6786496
22:17:22 47765 8192000000 10299404912 10761068544 1393327680 14.536 100 18 36 0 1406861760 9358096 4175984
22:19:12 23619 8192000000 10311008016 10758873088 1385857568 14.469 99 29 58 2 1400802272 8629216 6315488
22:20:39 54788 8192000000 10296645600 10757963776 1384987760 14.461 99 23 46 1 1403214528 12605696 5621072
22:22:36 60242 8192000000 10317928624 10774110208 1385244144 14.463 99 11 22 0 1408005776 19963456 2798176
22:24:36 85194 8192000000 10285213184 10730975232 1298722288 13.684 93 11 22 0 1315894832 14816688 2355856
22:27:05 104989 8192000000 10270002624 10731970560 1357860288 14.218 99 23 46 0 1374728464 11672528 5195648
22:29:52 60449 8192000000 10282628800 10752733184 1370208336 14.329 99 11 22 0 1385296736 12581968 2506432
22:31:53 58073 8192000000 10290326704 10752188416 1383029968 14.444 100 17 35 0 1406695744 19110272 4555504
22:34:03 46521 8192000000 10278174016 10749931520 1358794768 14.227 98 14 28 0 1375331456 12799632 3737056
22:35:58 53724 8192000000 10277219952 10740314112 1347576256 14.126 97 31 62 0 1369068192 14643312 6848624
22:38:06 26987 8192000000 9267432960 9550069760 415786384 4.830 31 4 8 0 419441184 3071696 583104

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-08-2022 22:40:07 to 16-08-2022 22:40:22.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 72 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 16-08-2022 22:40:23 to 16-08-2022 22:40:25.

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          235959
MON$OLDEST_ACTIVE               235960
MON$OLDEST_SNAPSHOT             235960
MON$NEXT_TRANSACTION            235960
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                       2019712
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.33617 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33617 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33617 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33617 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.08.22 22:40:26. 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-08-16 22:40:28 No severe PSQL-related problems occured
16.08.22 22:40:29. End of report.