Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 24.04.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 16603488 kB
MemAvailable 31515364 kB
Buffers 0 kB
Cached 15095396 kB
SwapCached 2540 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-04-24 22:38:17
DTS_END                         2022-04-24 22:38:17.9360
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/40092
TRN_ID                          236465
ATT_ID                          690
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: 9509.69 25952 1141163
customer order: creation 166.57 333 19988
customer order: refuse 14.78 377 1773
order to supplier: creation 45.18 1181 5421
order to supplier: removal 3.68 92051 441
invoice (draft): creation 44.73 3466 5368
invoice (draft): removal 54.84 719 6581
invoice accept: apply 42.15 18194 5058
invoice accept: cancel 48.78 772 5854
customer reserve: creation 3973.28 177 476794
customer reserve: removal 4822.33 55 578679
realization accept: apply 82.52 201 9902
realization accept: cancel 83.75 113 10050
payment from customer: creation 58.41 105 7009
payment from customer: removal 5.83 108 699
payment to supplier: creation 47.42 155 5690
payment to supplier: removal 3.64 49 437
service: total inventory turnovers 4.70 1153 564
service: total monetary turnovers 6.26 31 751
service: aggregate perf. data 0.83 18925 100
service: refresh index statistics 0.03 380877 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 24-04-2022 22:39:39 to 24-04-2022 22:39:41.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3798
TEST_TIME 2 3830
TEST_TIME 3 4669
TEST_TIME 4 4942
TEST_TIME 5 4478
TEST_TIME 6 4616
TEST_TIME 7 4625
TEST_TIME 8 4719
TEST_TIME 9 5162
TEST_TIME 10 5489
TEST_TIME 11 5566
TEST_TIME 12 5895
TEST_TIME 13 6540
TEST_TIME 14 7286
TEST_TIME 15 7251
TEST_TIME 16 7906
TEST_TIME 17 7946
TEST_TIME 18 8197
TEST_TIME 19 8440
TEST_TIME 20 8473
TEST_TIME 21 8743
TEST_TIME 22 8930
TEST_TIME 23 8997
TEST_TIME 24 9705
TEST_TIME 25 9827
TEST_TIME 26 10535
TEST_TIME 27 11040
TEST_TIME 28 10892
TEST_TIME 29 10648
TEST_TIME 30 10701
TEST_TIME 31 10991
TEST_TIME 32 10807
TEST_TIME 33 10791
TEST_TIME 34 10492
TEST_TIME 35 10255
TEST_TIME 36 10303
TEST_TIME 37 10264
TEST_TIME 38 10211
TEST_TIME 39 10277
TEST_TIME 40 10229
TEST_TIME 41 10101
TEST_TIME 42 9984
TEST_TIME 43 10004
TEST_TIME 44 9955
TEST_TIME 45 9810
TEST_TIME 46 9792
TEST_TIME 47 9812
TEST_TIME 48 9785
TEST_TIME 49 9860
TEST_TIME 50 9888
TEST_TIME 51 9889
TEST_TIME 52 9836
TEST_TIME 53 9798
TEST_TIME 54 9906
TEST_TIME 55 9938
TEST_TIME 56 9967
TEST_TIME 57 9952
TEST_TIME 58 9926
TEST_TIME 59 10082
TEST_TIME 60 10166
TEST_TIME 61 10153
TEST_TIME 62 10104
TEST_TIME 63 9985
TEST_TIME 64 9962
TEST_TIME 65 9872
TEST_TIME 66 9894
TEST_TIME 67 9857
TEST_TIME 68 9862
TEST_TIME 69 9857
TEST_TIME 70 9853
TEST_TIME 71 9787
TEST_TIME 72 9786
TEST_TIME 73 9907
TEST_TIME 74 9840
TEST_TIME 75 9926
TEST_TIME 76 9918
TEST_TIME 77 9965
TEST_TIME 78 9930
TEST_TIME 79 9973
TEST_TIME 80 9987
TEST_TIME 81 9939
TEST_TIME 82 9871
TEST_TIME 83 9889
TEST_TIME 84 9800
TEST_TIME 85 9765
TEST_TIME 86 9728
TEST_TIME 87 9725
TEST_TIME 88 9725
TEST_TIME 89 9721
TEST_TIME 90 9661
TEST_TIME 91 9641
TEST_TIME 92 9695
TEST_TIME 93 9694
TEST_TIME 94 9688
TEST_TIME 95 9699
TEST_TIME 96 9687
TEST_TIME 97 9739
TEST_TIME 98 9827
TEST_TIME 99 9784
TEST_TIME 100 9791
TEST_TIME 101 9837
TEST_TIME 102 9810
TEST_TIME 103 9783
TEST_TIME 104 9835
TEST_TIME 105 9860
TEST_TIME 106 9819
TEST_TIME 107 9819
TEST_TIME 108 9788
TEST_TIME 109 9734
TEST_TIME 110 9699
TEST_TIME 111 9689
TEST_TIME 112 9670
TEST_TIME 113 9630
TEST_TIME 114 9612
TEST_TIME 115 9550
TEST_TIME 116 9577
TEST_TIME 117 9581
TEST_TIME 118 9568
TEST_TIME 119 9579
TEST_TIME 120 9512

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 24-04-2022 22:39:43 to 24-04-2022 22:39:46.

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 1580168 1580168 0 0.00 0 36063 6 0 0
doc_list_biud 1580168 1580168 0 0.00 0 54096 26 0 0
sp_add_invoice_to_stock 5164 5058 106 2.05 31 179156 18194 0 106
sp_cancel_adding_invoice 5854 5854 0 0.00 4 54141 772 0 0
sp_cancel_client_order 1773 1773 0 0.00 4 19231 378 0 0
sp_cancel_customer_reserve 578679 578679 0 0.00 1 21398 55 0 0
sp_cancel_pay_from_customer 699 699 0 0.00 2 11730 109 0 0
sp_cancel_pay_to_supplier 437 437 0 0.00 3 1384 50 0 0
sp_cancel_supplier_invoice 6590 6581 9 0.13 2 53503 720 0 9
sp_cancel_supplier_order 441 441 0 0.00 5 1191387 92052 0 0
sp_cancel_write_off 10050 10050 0 0.00 1 26932 113 0 0
sp_client_order 19988 19988 0 0.00 3 19009 333 0 0
sp_customer_reserve 476794 476794 0 0.00 0 53404 177 0 0
sp_fill_shopping_cart 30774 30774 0 0.00 1 9722 39 0 0
sp_fill_shopping_cart_clo_res 476786 476786 0 0.00 0 15334 14 0 0
sp_get_clo_for_invoice 5050 5050 0 0.00 0 33582 308 0 0
sp_kill_cost_storno 17040 17040 0 0.00 0 18946 56 0 0
sp_kill_qstorno_ret_qs2qd 1174430 1174430 0 0.00 0 53492 30 0 0
sp_kill_qty_storno 618330 618330 0 0.00 0 54094 65 0 0
sp_lock_selected_doc 20781 20781 0 0.00 0 8007 4 0 0
sp_make_cost_storno 27651 27651 0 0.00 0 29163 62 0 0
sp_multiply_rows_for_pdistr 27651 27651 0 0.00 0 21501 32 0 0
sp_multiply_rows_for_qdistr 19988 19988 0 0.00 0 17389 160 0 0
sp_pay_from_customer 7009 7009 0 0.00 5 21237 106 0 0
sp_pay_to_supplier 5690 5690 0 0.00 5 27292 155 0 0
sp_payment_common 12699 12699 0 0.00 1 24122 42 0 0
sp_qd_handle_on_cancel_clo 1773 1773 0 0.00 0 19225 354 0 0
sp_qd_handle_on_invoice_upd_sts 10904 10904 0 0.00 0 54091 332 0 0
sp_qd_handle_on_reserve_upd_sts 9902 9902 0 0.00 0 27039 62 0 0
sp_reserve_write_off 9902 9902 0 0.00 6 29172 202 0 0
sp_supplier_invoice 5368 5368 0 0.00 6 154819 3466 0 0
sp_supplier_order 5421 5421 0 0.00 5 53048 1181 0 0
srv_aggregate_perf_data 100 100 0 0.00 3025 114876 18926 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 2323 150871 43416 0 0
srv_find_qd_qs_mism 1092333 1092333 0 0.00 0 36062 5 0 0
srv_make_invnt_saldo 564 564 0 0.00 37 60305 1154 0 0
srv_make_money_saldo 751 751 0 0.00 0 4088 32 0 0
srv_recalc_idx_stat 4 4 0 0.00 282475 477036 380878 0 0
t$perf-abend:sp_add_invoice_to_stock 106 106 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5058 5058 0 0.00 0 13780 157 0 0
t$perf-norm:sp_cancel_adding_invoice 398 398 0 0.00 0 6401 104 0 0
t$perf-norm:sp_cancel_client_order 1773 1773 0 0.00 0 5580 16 0 0
t$perf-norm:sp_cancel_customer_reserve 696 696 0 0.00 0 2812 16 0 0
t$perf-norm:sp_cancel_pay_from_customer 699 699 0 0.00 0 2750 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 437 437 0 0.00 0 1302 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 393 393 0 0.00 0 2655 28 0 0
t$perf-norm:sp_cancel_supplier_order 441 441 0 0.00 0 17354 1375 0 0
t$perf-norm:sp_cancel_write_off 992 992 0 0.00 0 783 4 0 0
t$perf-norm:sp_client_order 19988 19988 0 0.00 0 13042 19 0 0
t$perf-norm:sp_customer_reserve 936 936 0 0.00 0 958 5 0 0
t$perf-norm:sp_pay_from_customer 7009 7009 0 0.00 0 8737 12 0 0
t$perf-norm:sp_pay_to_supplier 5690 5690 0 0.00 0 2723 9 0 0
t$perf-norm:sp_reserve_write_off 9902 9902 0 0.00 0 8410 9 0 0
t$perf-norm:sp_supplier_invoice 5368 5368 0 0.00 0 6738 20 0 0
t$perf-norm:sp_supplier_order 5421 5421 0 0.00 0 13885 20 0 0
t$perf-norm:srv_aggregate_perf_data 100 100 0 0.00 0 191 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 12 0 0 0
t$perf-norm:srv_make_invnt_saldo 564 564 0 0.00 0 824 19 0 0
t$perf-norm:srv_make_money_saldo 751 751 0 0.00 0 3763 16 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 9 4 0 0
v_add_invoice_to_stock 10312 10100 212 2.05 0 10061 16 0 212
v_all_customers 36464 36464 0 0.00 0 94 1 0 0
v_all_suppliers 29682 29682 0 0.00 0 162 1 0 0
v_all_wares 39974 39974 0 0.00 0 29 1 0 0
v_cancel_adding_invoice 796 796 0 0.00 0 1803 8 0 0
v_cancel_client_order 3546 3546 0 0.00 0 8659 7 0 0
v_cancel_customer_prepayment 1398 1398 0 0.00 0 11721 13 0 0
v_cancel_customer_reserve 1392 1392 0 0.00 0 1130 5 0 0
v_cancel_payment_to_supplier 874 874 0 0.00 0 1379 4 0 0
v_cancel_supplier_invoice 804 786 18 2.23 0 3074 18 0 18
v_cancel_supplier_order 882 882 0 0.00 0 1200 5 0 0
v_cancel_write_off 1984 1984 0 0.00 0 1629 12 0 0
v_max_id_clo_ord 5421 5421 0 0.00 0 2903 2 0 0
v_max_id_clo_res 936 936 0 0.00 0 1488 9 0 0
v_max_id_ord_sup 5366 5366 0 0.00 0 1624 2 0 0
v_max_non_paid_invoice 1636 1636 0 0.00 0 2553 4 0 0
v_max_non_paid_realizn 3781 3781 0 0.00 0 1325 3 0 0
v_min_id_clo_ord 5421 5421 0 0.00 0 2231 5 0 0
v_min_id_clo_res 936 936 0 0.00 0 5804 18 0 0
v_min_id_ord_sup 5366 5366 0 0.00 0 5967 11 0 0
v_min_non_paid_invoice 5690 5690 0 0.00 0 18461 34 0 0
v_min_non_paid_realizn 7009 7009 0 0.00 0 18570 29 0 0
v_our_firm 9966 9966 0 0.00 0 17 1 0 0
v_reserve_write_off 19804 19804 0 0.00 0 18303 7 0 0
x_lock_depdocs_on_canc_invoice 12042 12042 0 0.00 0 21294 107 0 0
x_lock_depdocs_on_canc_sup_ord 441 441 0 0.00 0 15884 349 0 0
x_make_qstorno_1000_1200 5421 5421 0 0.00 1 53034 1094 0 0
x_make_qstorno_1200_2000 5368 5368 0 0.00 3 154474 3336 0 0
x_make_qstorno_2100_3300 476794 476794 0 0.00 0 50043 161 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 24-04-2022 22:39:47 to 24-04-2022 22:39:51.

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:21 25 8192000000 8817882016 8835600384 18571168 0.226 5 5 7 0 18855104 101264 182672
20:09:22 30725 8192000000 9916830464 10230943744 995687040 10.837 100 94 188 2 1026783232 6794880 24301312
20:10:58 27382 8192000000 9954171632 10281988096 1039299616 11.258 100 87 174 2 1070457744 8918896 22239232
20:12:33 22039 8192000000 10003187488 10343657472 1083963888 11.685 100 84 168 0 1114426464 9660176 20802400
20:14:00 19099 8192000000 10046062736 10397364224 1131643328 12.137 100 83 166 0 1162786272 11438576 19704368
20:15:23 8460 8192000000 10097084928 10459222016 1182520480 12.614 100 75 150 1 1212122080 12469072 17132528
20:16:35 4677 8192000000 10142038928 10511044608 1228050192 13.036 100 80 161 1 1259044144 13311696 17682256
20:17:41 2180 8192000000 10161095584 10536161280 1249757664 13.236 100 82 163 0 1281521296 13744720 18018912
20:18:46 1845 8192000000 10199962928 10581200896 1283621776 13.546 100 89 170 2 1316038752 13222672 19194304
20:19:50 1914 8192000000 10217060384 10601082880 1299658336 13.692 100 90 179 0 1334522240 13746192 21117712
20:20:53 43315 8192000000 10219842384 10610499584 1309006704 13.777 100 58 118 0 1329557312 6380912 14169696
20:22:37 6803 8192000000 10200075664 10617733120 1315648304 13.837 100 29 57 2 1328874320 6686432 6539584
20:23:46 9143 8192000000 10211925488 10630578176 1323402016 13.907 100 30 60 0 1336703696 6318528 6983152
20:24:59 8210 8192000000 10211730752 10636902400 1330942464 13.976 100 17 34 1 1340906128 6150896 3812768
20:26:08 45138 8192000000 10248587072 10651037696 1336361760 14.025 100 53 105 1 1354922848 5724496 12836592
20:27:54 10935 8192000000 10223597984 10648207360 1314424608 13.826 98 20 39 0 1328731312 9466464 4840240
20:29:07 9628 8192000000 10183256400 10594177024 1273127872 13.450 96 22 44 1 1283151216 5308224 4715120
20:30:21 23731 8192000000 10157266944 10573131776 1248602976 13.225 97 43 83 0 1264259456 5479712 10176768
20:31:46 5861 8192000000 10145767872 10538655744 1203601008 12.810 95 41 83 0 1218722416 4390784 10730624
20:32:54 7791 8192000000 10170272432 10568568832 1259419856 13.325 99 34 68 0 1273290720 5677728 8193136
20:34:03 8227 8192000000 10156561232 10563760128 1250875328 13.246 98 11 22 0 1258935616 5377248 2683040
20:35:37 10562 8192000000 10179460448 10588884992 1287774608 13.584 100 22 43 0 1298910880 5655456 5480816
20:36:48 8770 8192000000 10194732464 10609471488 1304892432 13.740 100 20 40 1 1315380240 5588640 4899168
20:37:58 5615 8192000000 10199825968 10616942592 1314584912 13.828 100 24 47 1 1325297536 5100368 5612256
20:39:07 33144 8192000000 10254542352 10664640512 1344623824 14.099 100 50 100 1 1364814576 8670640 11520112
20:40:44 72462 8192000000 10259146624 10688135168 1360104848 14.238 100 39 78 0 1382412832 12805712 9502272
20:43:12 4929 8192000000 10284913552 10706247680 1374192912 14.365 100 47 94 1 1398953392 13789888 10970592
20:44:19 15645 8192000000 10312437312 10729967616 1388350368 14.491 100 55 108 1 1419196112 18624896 12220848
20:45:45 13003 8192000000 10325206576 10746777600 1398743104 14.584 100 56 112 0 1434814848 23061344 13010400
20:47:02 9126 8192000000 10333257904 10753904640 1406388320 14.652 100 56 112 0 1442231744 22140272 13703152
20:48:22 11547 8192000000 10349762928 10782420992 1420502848 14.777 100 52 103 2 1457251472 24150544 12598080
20:49:36 5454 8192000000 10359881536 10788663296 1428089376 14.844 100 61 122 3 1470153616 26720464 15343776
20:50:58 10716 8192000000 10345618512 10772881408 1389743936 14.504 98 56 113 0 1434344944 30848880 13752128
20:52:14 2363 8192000000 10380285680 10793635840 1421456304 14.786 100 90 180 1 1476050256 32189888 22404064
20:53:23 4948 8192000000 10355847360 10779189248 1412332000 14.705 100 66 131 0 1456176816 27257328 16587488
20:54:33 3611 8192000000 10341802720 10748669952 1366734976 14.298 97 82 164 0 1415719696 28202608 20782112
20:55:42 5585 8192000000 10312773680 10727563264 1371378320 14.339 100 55 108 5 1414828240 29364320 14085600
20:56:51 2323 8192000000 10336423648 10744717312 1375771056 14.379 99 81 161 0 1427984448 32665088 19548304
20:57:57 5451 8192000000 10326112864 10747314176 1388569664 14.493 100 57 114 0 1433334016 29642144 15122208
20:59:07 2447 8192000000 10330631392 10746257408 1381480400 14.430 100 72 140 2 1436955248 36894912 18579936
21:00:18 5133 8192000000 10319482320 10747277312 1376565600 14.386 100 43 87 1 1427727280 39115696 12045984
21:01:26 6677 8192000000 10335439200 10754940928 1381448272 14.429 100 58 114 1 1437800688 40893824 15458592
21:02:59 15007 8192000000 10312036112 10748854272 1371798912 14.343 100 27 52 1 1414952192 36029776 7123504
21:04:26 42807 8192000000 10281881568 10735022080 1346270032 14.114 99 17 34 0 1402010784 50918032 4822720
21:06:10 83986 8192000000 10286993392 10722435072 1356667152 14.207 100 15 28 2 1371423632 11161040 3595440
21:09:10 53655 8192000000 10265806992 10725339136 1342493056 14.080 99 9 18 0 1363783200 19615776 1674368
21:11:09 42891 8192000000 10258351168 10699665408 1334587616 14.009 99 36 72 3 1354537696 11799200 8150880
21:14:07 13058 8192000000 10260914912 10684792832 1316367440 13.844 97 43 86 0 1332629904 7454624 8807840
21:15:21 40341 8192000000 10282486432 10730528768 1371779232 14.343 100 37 74 0 1390871248 10670144 8421872
21:17:10 20729 8192000000 10265651104 10719182848 1361893968 14.254 100 20 40 0 1379819184 12672288 5252928
21:18:32 57797 8192000000 10286603072 10740150272 1377536544 14.395 100 12 24 0 1388734496 8424144 2773808
21:20:33 47996 8192000000 10284139632 10741313536 1383593632 14.449 100 10 20 0 1397827040 11558256 2675152
21:22:22 45150 8192000000 10286816272 10745982976 1391168800 14.516 100 16 32 1 1403263664 8544560 3550304
21:24:32 34643 8192000000 10285963264 10760282112 1387875664 14.487 100 12 24 1 1402838016 12196112 2766240
21:26:10 54776 8192000000 10301140192 10765217792 1397857904 14.576 100 15 30 0 1413411664 12103536 3450224
21:28:07 50425 8192000000 10291043888 10757992448 1391984480 14.524 100 12 24 0 1405050320 10272800 2793040
21:30:02 69460 8192000000 10319476816 10774564864 1386168944 14.472 99 25 49 2 1401076608 9334096 5573568
21:32:13 66576 8192000000 10298048352 10763919360 1375171248 14.373 98 16 32 0 1393437328 14349872 3916208
21:34:22 51684 8192000000 10322137568 10766508032 1386496944 14.475 99 22 41 1 1401659712 10497600 4665168
21:36:15 86936 8192000000 10320150336 10795548672 1388835472 14.495 99 10 20 0 1409647168 18544432 2267264
21:38:42 95183 8192000000 10305230544 10760237056 1323481904 13.908 94 13 26 0 1339674976 13060176 3132896
21:41:21 64242 8192000000 10280722672 10732085248 1353484928 14.179 97 26 52 0 1366462800 6806944 6170928
21:43:31 67389 8192000000 10280146752 10739970048 1332954960 13.994 96 7 14 1 1344621872 10435424 1231488
21:45:50 48794 8192000000 10249931808 10701336576 1317166128 13.851 96 35 70 1 1336194928 11080736 7948064
21:47:49 42514 8192000000 10263451616 10712457216 1337185632 14.032 98 32 64 1 1353486816 9167792 7133392
21:49:39 59543 8192000000 10275907744 10727116800 1337648800 14.036 98 28 56 0 1359811072 15796736 6365536
21:51:39 45276 8192000000 10271060496 10714484736 1307254768 13.761 96 15 30 0 1332980592 22713520 3012304
21:53:26 44479 8192000000 10293589552 10727342080 1343587456 14.090 98 32 64 1 1362955968 11921008 7447504
21:55:12 16002 8192000000 10290263392 10734399488 1370673648 14.333 100 40 80 1 1391245664 11911840 8660176
21:56:30 16005 8192000000 10279807536 10715848704 1341095104 14.067 98 52 104 0 1362544240 9759584 11689552
21:57:50 150871 8192000000 10263558064 10721603584 1315783040 13.839 96 19 38 1 1331099120 11086176 4229904
22:01:34 65457 8192000000 10265364384 10721636352 1341782096 14.073 98 16 32 0 1357037632 11910128 3345408
22:03:43 50634 8192000000 10279675200 10735415296 1375462192 14.376 100 19 38 2 1395906304 15703360 4740752
22:05:35 49380 8192000000 10276828160 10743025664 1345557440 14.107 98 10 20 0 1363645952 15764640 2323872
22:07:24 70410 8192000000 10275808528 10730311680 1339438528 14.052 97 13 26 1 1352140464 9648384 3053552
22:09:35 78127 8192000000 10282716896 10751643648 1366227584 14.293 99 14 27 0 1383989456 14908768 2853104
22:12:05 75616 8192000000 10304937456 10766905344 1377973264 14.398 99 19 38 1 1392815936 11100928 3741744
22:14:25 65627 8192000000 10308914320 10773430272 1392901968 14.532 99 26 52 0 1414365744 14544512 6919264
22:16:34 60761 8192000000 10313312880 10785619968 1399496800 14.591 100 9 19 0 1417385472 16067104 1821568
22:18:41 30108 8192000000 10319402528 10767425536 1394482512 14.546 99 35 70 1 1412029056 9787616 7758928
22:20:16 44397 8192000000 10319681728 10772627456 1391092448 14.516 99 15 30 0 1403105936 8935168 3078320
22:22:06 61283 8192000000 10314274480 10779840512 1396426880 14.563 99 28 56 0 1416131200 13628032 6076288
22:24:08 111762 8192000000 10306250480 10768994304 1370928928 14.335 98 24 48 0 1393278256 17041888 5307440
22:27:33 22527 8192000000 10307806720 10753024000 1366407040 14.295 98 42 84 1 1384364016 8685008 9271968
22:28:56 12803 8192000000 10286035584 10740760576 1358261872 14.222 98 32 63 0 1375510800 10642592 6606336
22:30:21 71969 8192000000 10259181312 10716909568 1335182704 14.014 97 13 26 0 1356337232 18067312 3087216
22:32:34 90497 8192000000 10268350752 10730074112 1356914288 14.210 99 10 20 2 1376379280 16743760 2721232
22:35:20 74283 8192000000 10259723264 10712911872 1303732176 13.729 95 15 30 1 1319158960 12001648 3425136
22:37:40 43474 8192000000 9169365520 9427025920 320755408 3.767 25 6 12 0 324887792 3077632 1054752

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 24-04-2022 22:39:53 to 24-04-2022 22:40:08.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 106 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 24-04-2022 22:40:09 to 24-04-2022 22:40:11.

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          235905
MON$OLDEST_ACTIVE               236776
MON$OLDEST_SNAPSHOT             236776
MON$NEXT_TRANSACTION            236776
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                       2029472
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.33577 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33577 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33577 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33577 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 ---
:1594: 
:1595: mscs-test-161.budzdorov.ru    Sun Apr 24 22:38:26 2022
:1596:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1597: 
:1598: 
:1599: mscs-test-161.budzdorov.ru    Sun Apr 24 22:38:28 2022
:1600:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1601: 
--- end of diff output ---

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

24.04.22 22:40:12. 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-04-24 22:40:14 No severe PSQL-related problems occured
24.04.22 22:40:15. End of report.