Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 02.06.2022 19: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 16285380 kB
MemAvailable 31319752 kB
Buffers 0 kB
Cached 15401320 kB
SwapCached 2444 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-02 19:38:22
DTS_END                         2022-06-02 19:38:22.2660
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/38892
TRN_ID                          240147
ATT_ID                          669
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: 9638.65 19062 1156638
customer order: creation 177.84 344 21341
customer order: refuse 16.06 357 1927
order to supplier: creation 47.44 1280 5693
order to supplier: removal 3.64 91417 437
invoice (draft): creation 47.47 3495 5696
invoice (draft): removal 56.08 729 6729
invoice accept: apply 43.28 17372 5194
invoice accept: cancel 50.48 801 6058
customer reserve: creation 4015.83 171 481900
customer reserve: removal 4870.85 54 584502
realization accept: apply 85.83 169 10300
realization accept: cancel 90.03 92 10803
payment from customer: creation 61.19 113 7343
payment from customer: removal 5.99 96 719
payment to supplier: creation 51.10 118 6132
payment to supplier: removal 3.65 96 438
service: total inventory turnovers 4.78 1076 573
service: total monetary turnovers 6.39 25 767
service: aggregate perf. data 0.68 26619 82
service: refresh index statistics 0.03 236817 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 02-06-2022 19:39:15 to 02-06-2022 19:39:17.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5340
TEST_TIME 2 7104
TEST_TIME 3 5770
TEST_TIME 4 5624
TEST_TIME 5 5499
TEST_TIME 6 5545
TEST_TIME 7 5832
TEST_TIME 8 5755
TEST_TIME 9 6275
TEST_TIME 10 6553
TEST_TIME 11 7178
TEST_TIME 12 7211
TEST_TIME 13 7423
TEST_TIME 14 7784
TEST_TIME 15 7823
TEST_TIME 16 7965
TEST_TIME 17 8370
TEST_TIME 18 8251
TEST_TIME 19 8526
TEST_TIME 20 8786
TEST_TIME 21 9026
TEST_TIME 22 9127
TEST_TIME 23 9529
TEST_TIME 24 9772
TEST_TIME 25 9618
TEST_TIME 26 9556
TEST_TIME 27 9676
TEST_TIME 28 9989
TEST_TIME 29 10666
TEST_TIME 30 10726
TEST_TIME 31 10662
TEST_TIME 32 10775
TEST_TIME 33 10667
TEST_TIME 34 10658
TEST_TIME 35 10626
TEST_TIME 36 10598
TEST_TIME 37 10391
TEST_TIME 38 10282
TEST_TIME 39 10122
TEST_TIME 40 10091
TEST_TIME 41 10244
TEST_TIME 42 10098
TEST_TIME 43 10045
TEST_TIME 44 9992
TEST_TIME 45 10071
TEST_TIME 46 10089
TEST_TIME 47 10108
TEST_TIME 48 10041
TEST_TIME 49 9927
TEST_TIME 50 9880
TEST_TIME 51 9752
TEST_TIME 52 9808
TEST_TIME 53 9784
TEST_TIME 54 9803
TEST_TIME 55 9782
TEST_TIME 56 9873
TEST_TIME 57 9826
TEST_TIME 58 9782
TEST_TIME 59 9873
TEST_TIME 60 9817
TEST_TIME 61 9946
TEST_TIME 62 9985
TEST_TIME 63 9987
TEST_TIME 64 10026
TEST_TIME 65 10127
TEST_TIME 66 10123
TEST_TIME 67 10108
TEST_TIME 68 10330
TEST_TIME 69 10342
TEST_TIME 70 10212
TEST_TIME 71 10155
TEST_TIME 72 10070
TEST_TIME 73 10086
TEST_TIME 74 10076
TEST_TIME 75 10028
TEST_TIME 76 9988
TEST_TIME 77 9913
TEST_TIME 78 9840
TEST_TIME 79 9848
TEST_TIME 80 9819
TEST_TIME 81 9794
TEST_TIME 82 9772
TEST_TIME 83 9741
TEST_TIME 84 9666
TEST_TIME 85 9659
TEST_TIME 86 9634
TEST_TIME 87 9593
TEST_TIME 88 9584
TEST_TIME 89 9677
TEST_TIME 90 9658
TEST_TIME 91 9672
TEST_TIME 92 9693
TEST_TIME 93 9762
TEST_TIME 94 9794
TEST_TIME 95 9816
TEST_TIME 96 9813
TEST_TIME 97 9890
TEST_TIME 98 9832
TEST_TIME 99 9854
TEST_TIME 100 9954
TEST_TIME 101 9934
TEST_TIME 102 9898
TEST_TIME 103 9868
TEST_TIME 104 9801
TEST_TIME 105 9808
TEST_TIME 106 9781
TEST_TIME 107 9724
TEST_TIME 108 9706
TEST_TIME 109 9700
TEST_TIME 110 9652
TEST_TIME 111 9660
TEST_TIME 112 9654
TEST_TIME 113 9623
TEST_TIME 114 9619
TEST_TIME 115 9635
TEST_TIME 116 9619
TEST_TIME 117 9627
TEST_TIME 118 9667
TEST_TIME 119 9622
TEST_TIME 120 9644

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 02-06-2022 19:39:19 to 02-06-2022 19:39:22.

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 1601376 1601376 0 0.00 0 12494 6 0 0
doc_list_biud 1601375 1601375 0 0.00 0 25810 25 0 0
sp_add_invoice_to_stock 5330 5194 136 2.55 26 155767 17373 0 136
sp_cancel_adding_invoice 6058 6058 0 0.00 4 63421 802 0 0
sp_cancel_client_order 1927 1927 0 0.00 4 12712 358 0 0
sp_cancel_customer_reserve 584502 584502 0 0.00 1 25814 55 0 0
sp_cancel_pay_from_customer 719 719 0 0.00 2 9125 97 0 0
sp_cancel_pay_to_supplier 438 438 0 0.00 3 6642 96 0 0
sp_cancel_supplier_invoice 6740 6729 11 0.16 2 18040 729 0 11
sp_cancel_supplier_order 437 437 0 0.00 5 863636 91417 0 0
sp_cancel_write_off 10803 10803 0 0.00 1 7720 92 0 0
sp_client_order 21341 21341 0 0.00 3 14977 345 0 0
sp_customer_reserve 481900 481900 0 0.00 0 83915 172 0 0
sp_fill_shopping_cart 32719 32719 0 0.00 1 9420 32 0 0
sp_fill_shopping_cart_clo_res 481895 481895 0 0.00 0 11033 13 0 0
sp_get_clo_for_invoice 5186 5186 0 0.00 0 27168 294 0 0
sp_kill_cost_storno 18018 18018 0 0.00 0 7186 51 0 0
sp_kill_qstorno_ret_qs2qd 1186973 1186973 0 0.00 0 25805 30 0 0
sp_kill_qty_storno 625940 625940 0 0.00 0 25809 64 0 0
sp_lock_selected_doc 21609 21609 0 0.00 0 9420 6 0 0
sp_make_cost_storno 28960 28960 0 0.00 0 12480 48 0 0
sp_multiply_rows_for_pdistr 28960 28960 0 0.00 0 6116 27 0 0
sp_multiply_rows_for_qdistr 21341 21341 0 0.00 0 14663 163 0 0
sp_pay_from_customer 7343 7343 0 0.00 4 11138 113 0 0
sp_pay_to_supplier 6132 6132 0 0.00 5 11439 118 0 0
sp_payment_common 13475 13475 0 0.00 2 11134 24 0 0
sp_qd_handle_on_cancel_clo 1927 1927 0 0.00 0 12706 334 0 0
sp_qd_handle_on_invoice_upd_sts 11242 11242 0 0.00 0 15065 304 0 0
sp_qd_handle_on_reserve_upd_sts 10300 10300 0 0.00 0 14940 66 0 0
sp_reserve_write_off 10300 10300 0 0.00 6 15024 169 0 0
sp_supplier_invoice 5696 5696 0 0.00 8 94895 3495 0 0
sp_supplier_order 5693 5693 0 0.00 5 75135 1281 0 0
srv_aggregate_perf_data 82 82 0 0.00 2045 350091 26620 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 2170 112918 44691 0 0
srv_find_qd_qs_mism 1106350 1106350 0 0.00 0 10137 5 0 0
srv_make_invnt_saldo 573 573 0 0.00 28 57749 1076 0 0
srv_make_money_saldo 767 767 0 0.00 1 992 25 0 0
srv_recalc_idx_stat 4 4 0 0.00 110805 396371 236818 0 0
t$perf-abend:sp_add_invoice_to_stock 136 136 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5194 5194 0 0.00 0 15097 177 0 0
t$perf-norm:sp_cancel_adding_invoice 442 442 0 0.00 0 7227 130 0 0
t$perf-norm:sp_cancel_client_order 1927 1927 0 0.00 0 16924 21 0 0
t$perf-norm:sp_cancel_customer_reserve 693 693 0 0.00 0 1429 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 719 719 0 0.00 0 1455 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 438 438 0 0.00 0 3103 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 400 400 0 0.00 0 1916 21 0 0
t$perf-norm:sp_cancel_supplier_order 437 437 0 0.00 0 22103 1668 0 0
t$perf-norm:sp_cancel_write_off 1069 1069 0 0.00 0 2385 9 0 0
t$perf-norm:sp_client_order 21341 21341 0 0.00 0 10773 20 0 0
t$perf-norm:sp_customer_reserve 1071 1071 0 0.00 0 985 6 0 0
t$perf-norm:sp_pay_from_customer 7343 7343 0 0.00 0 6280 10 0 0
t$perf-norm:sp_pay_to_supplier 6132 6132 0 0.00 0 5695 10 0 0
t$perf-norm:sp_reserve_write_off 10300 10300 0 0.00 0 12833 14 0 0
t$perf-norm:sp_supplier_invoice 5696 5696 0 0.00 0 4426 19 0 0
t$perf-norm:sp_supplier_order 5693 5693 0 0.00 0 10674 22 0 0
t$perf-norm:srv_aggregate_perf_data 82 82 0 0.00 0 3433 59 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 623 14 0 0
t$perf-norm:srv_make_invnt_saldo 573 573 0 0.00 0 3365 30 0 0
t$perf-norm:srv_make_money_saldo 767 767 0 0.00 0 14622 40 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 66 18 0 0
v_add_invoice_to_stock 10640 10368 272 2.55 0 6290 17 0 272
v_all_customers 38794 38794 0 0.00 0 18 1 0 0
v_all_suppliers 31910 31910 0 0.00 0 13 1 0 0
v_all_wares 42682 42682 0 0.00 0 74 1 0 0
v_cancel_adding_invoice 884 884 0 0.00 0 3267 10 0 0
v_cancel_client_order 3854 3854 0 0.00 0 3258 4 0 0
v_cancel_customer_prepayment 1438 1438 0 0.00 0 3289 8 0 0
v_cancel_customer_reserve 1386 1386 0 0.00 0 2075 7 0 0
v_cancel_payment_to_supplier 876 876 0 0.00 0 2512 11 0 0
v_cancel_supplier_invoice 822 800 22 2.67 0 2911 17 0 22
v_cancel_supplier_order 874 874 0 0.00 0 685 4 0 0
v_cancel_write_off 2138 2138 0 0.00 0 3379 15 0 0
v_max_id_clo_ord 5690 5690 0 0.00 0 891 1 0 0
v_max_id_clo_res 1071 1071 0 0.00 0 3675 18 0 0
v_max_id_ord_sup 5687 5687 0 0.00 0 4233 4 0 0
v_max_non_paid_invoice 1554 1554 0 0.00 0 2405 5 0 0
v_max_non_paid_realizn 3949 3949 0 0.00 0 1752 3 0 0
v_min_id_clo_ord 5690 5690 0 0.00 0 2336 7 0 0
v_min_id_clo_res 1071 1071 0 0.00 0 6894 28 0 0
v_min_id_ord_sup 5687 5687 0 0.00 0 3738 5 0 0
v_min_non_paid_invoice 6132 6132 0 0.00 0 8855 16 0 0
v_min_non_paid_realizn 7343 7343 0 0.00 0 11129 20 0 0
v_our_firm 10676 10676 0 0.00 0 17 1 0 0
v_reserve_write_off 20600 20600 0 0.00 0 4218 6 0 0
x_lock_depdocs_on_canc_invoice 12387 12387 0 0.00 0 15313 102 0 0
x_lock_depdocs_on_canc_sup_ord 437 437 0 0.00 0 11630 323 0 0
x_make_qstorno_1000_1200 5693 5693 0 0.00 1 75105 1200 0 0
x_make_qstorno_1200_2000 5696 5696 0 0.00 3 94888 3391 0 0
x_make_qstorno_2100_3300 481900 481900 0 0.00 0 83913 157 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 02-06-2022 19:39:23 to 02-06-2022 19:39:27.

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
17:08:26 9 8192000000 8807888848 8822034432 12693024 0.154 4 3 3 0 12839088 71120 74944
17:09:27 20057 8192000000 9905285984 10214113280 986557584 10.748 100 88 176 0 1014596832 5395168 22644080
17:10:55 21120 8192000000 9943665792 10262261760 1026591568 11.136 100 90 181 1 1058237744 8203808 23442368
17:12:23 16325 8192000000 9974393840 10304839680 1058429792 11.441 100 77 154 0 1087504624 9700784 19374048
17:13:44 16925 8192000000 10028885584 10376560640 1115863728 11.988 100 79 158 0 1145898352 10573728 19460896
17:15:05 8157 8192000000 10071536336 10430279680 1159430336 12.398 100 78 156 1 1188950288 11066752 18453200
17:16:19 5474 8192000000 10132607264 10495807488 1210131136 12.870 100 80 160 0 1241711984 13747568 17833280
17:17:26 8422 8192000000 10156385136 10529239040 1243268688 13.176 100 80 156 1 1275390448 14606032 17515728
17:18:38 1877 8192000000 10191996128 10573910016 1281384000 13.526 100 86 170 0 1312736304 12231728 19120576
17:19:42 1468 8192000000 10212200976 10592841728 1293655168 13.638 100 95 188 1 1328882656 14056608 21170880
17:20:47 16950 8192000000 10215567952 10605502464 1304398224 13.735 100 80 162 0 1333231328 10599680 18233424
17:22:06 4765 8192000000 10202166944 10609946624 1309961264 13.786 100 46 90 0 1330166272 10635472 9569536
17:23:14 8262 8192000000 10205148784 10615754752 1317428432 13.853 100 23 46 1 1328186560 5069104 5689024
17:24:25 57348 8192000000 10243273568 10643451904 1326752064 13.938 100 65 130 2 1348531088 5646128 16132896
17:26:23 5931 8192000000 10223468304 10644119552 1332326832 13.988 100 32 64 1 1346749840 7268848 7154160
17:27:29 10822 8192000000 10234970720 10656112640 1339586128 14.054 100 26 50 0 1351461456 5900992 5974336
17:28:41 20335 8192000000 10243921968 10647642112 1329810016 13.965 100 75 152 2 1353042848 5400288 17832544
17:30:02 4875 8192000000 10200269520 10604527616 1280064256 13.514 98 31 62 0 1294848432 5773728 9010448
17:31:08 5512 8192000000 10164016272 10563850240 1235281712 13.103 97 24 48 1 1245525632 5042560 5201360
17:32:17 46166 8192000000 10152904560 10557943808 1222141856 12.981 96 24 48 0 1232919328 4876288 5901184
17:34:04 5081 8192000000 10149955904 10553102336 1261569296 13.344 100 25 50 0 1273117584 6296720 5251568
17:35:13 7660 8192000000 10175540704 10582331392 1273058464 13.450 99 21 42 2 1283938400 5157408 5722528
17:36:21 6733 8192000000 10182609184 10595545088 1294443728 13.645 100 20 41 1 1304584528 5637200 4503600
17:37:32 34990 8192000000 10197097456 10609233920 1305054912 13.741 100 37 73 2 1318871456 5365552 8450992
17:39:14 10388 8192000000 10246064912 10655617024 1336351776 14.025 100 41 85 2 1355071776 9907424 8812576
17:40:32 8754 8192000000 10271506992 10690252800 1359171648 14.230 100 53 106 0 1384991104 13818976 12000480
17:41:43 28787 8192000000 10280851744 10703319040 1373348752 14.357 100 39 78 0 1395290720 12970400 8971568
17:43:15 15546 8192000000 10298483744 10724364288 1390726768 14.512 100 45 89 1 1415917440 14363504 10827168
17:44:53 5628 8192000000 10311652112 10736951296 1396119584 14.560 100 42 84 2 1424066912 17672736 10274592
17:46:02 9533 8192000000 10327811264 10752819200 1406554208 14.653 100 49 99 2 1439958528 21029296 12375024
17:47:17 4299 8192000000 10344600112 10770046976 1416834720 14.745 100 57 114 1 1455381440 24552672 13994048
17:48:28 12334 8192000000 10361407216 10784980992 1426129744 14.827 100 75 150 0 1472826752 28692992 18004016
17:49:46 9652 8192000000 10357583072 10782687232 1415776928 14.735 100 60 118 0 1456597440 26653440 14167072
17:51:02 3323 8192000000 10353212416 10770092032 1397823408 14.576 99 74 149 2 1449630832 32999712 18807712
17:52:10 4067 8192000000 10364646912 10787270656 1418196336 14.757 100 61 122 1 1470966384 37428000 15342048
17:53:17 4358 8192000000 10340322656 10761957376 1397054272 14.569 100 66 128 1 1447580672 34724240 15802160
17:54:33 4580 8192000000 10327736960 10748354560 1384493952 14.457 100 54 106 0 1434653392 36583360 13576080
17:55:46 3651 8192000000 10346512704 10770100224 1394821392 14.549 99 57 114 2 1445633232 35833776 14978064
17:57:03 2165 8192000000 10342281584 10758533120 1385398496 14.465 100 74 150 3 1450934768 45654992 19881280
17:58:08 9857 8192000000 10316460144 10741456896 1371756672 14.343 100 48 96 1 1428168528 43505360 12906496
17:59:22 14920 8192000000 10332789376 10756022272 1381922272 14.434 100 37 73 3 1432938384 41151856 9864256
18:00:44 10944 8192000000 10306848144 10732511232 1354077856 14.184 98 34 68 2 1407094080 43515696 9500528
18:01:58 8525 8192000000 10321812272 10747002880 1375263248 14.374 100 35 70 1 1422613904 38413568 8937088
18:03:11 12902 8192000000 10298882704 10730635264 1355305728 14.195 99 30 60 1 1400333056 37261632 7765696
18:04:43 22235 8192000000 10305639696 10748719104 1360413504 14.241 99 23 46 3 1410920768 44322800 6184464
18:06:19 23774 8192000000 10298929104 10743218176 1356427152 14.205 100 15 30 1 1405032752 44169392 4436208
18:07:48 71685 8192000000 10267949232 10696364032 1301363984 13.708 96 18 37 1 1329851232 24006080 4481168
18:10:01 39105 8192000000 10268890320 10701512704 1337034496 14.031 99 38 72 3 1358935968 13442992 8458480
18:11:44 76365 8192000000 10259300064 10705993728 1326951184 13.940 98 13 26 0 1344944160 15122400 2870576
18:14:01 105264 8192000000 10274234512 10705444864 1331782848 13.983 97 23 47 0 1346471648 9196544 5492256
18:16:48 86662 8192000000 10303737952 10742390784 1378566592 14.404 100 41 82 1 1397148944 8668832 9913520
18:19:18 49558 8192000000 10305108224 10757935104 1381375312 14.429 99 47 94 2 1404964880 13819712 9769856
18:21:12 16973 8192000000 10299213584 10749386752 1393283968 14.535 100 21 42 2 1408883040 10445456 5153616
18:22:30 22492 8192000000 10305350656 10755678208 1385075536 14.462 99 18 36 0 1399525888 10091696 4358656
18:23:56 54989 8192000000 10305351232 10759127040 1394319664 14.544 100 32 64 1 1413118240 10822416 7976160
18:25:54 89641 8192000000 10300209216 10763776000 1375974352 14.381 98 13 26 0 1393186352 14455152 2756848
18:28:24 49286 8192000000 10311603616 10769776640 1401722816 14.610 100 17 35 2 1423011280 17583920 3704544
18:30:14 92162 8192000000 10290425328 10758803456 1368802432 14.316 98 19 38 0 1392307664 18432656 5072576
18:32:47 47215 8192000000 10294236016 10761412608 1383235152 14.445 99 22 44 1 1400239696 11556560 5447984
18:34:36 45899 8192000000 10303922416 10758574080 1372914608 14.353 98 31 62 2 1388292400 7628400 7749392
18:36:24 75695 8192000000 10296101136 10770698240 1364743328 14.280 98 11 22 1 1380627600 13253216 2631056
18:38:40 74839 8192000000 10276493376 10741305344 1253585152 13.271 90 13 24 1 1273182784 16619248 2978384
18:41:03 78056 8192000000 10300491760 10750410752 1363075776 14.265 98 18 36 0 1381867312 14910528 3881008
18:43:23 58097 8192000000 10270222128 10723520512 1319840400 13.875 96 14 28 0 1341831296 18366160 3624736
18:45:59 75580 8192000000 10288536784 10768740352 1327780960 13.947 97 14 28 1 1356416944 25504352 3131632
18:49:32 78795 8192000000 10264611824 10731171840 1322103296 13.896 97 13 26 1 1347732672 22925008 2704368
18:51:57 70141 8192000000 10235468592 10698153984 1262099568 13.349 93 7 14 1 1280051328 16237328 1714432
18:54:30 55172 8192000000 10233757024 10688684032 1290656528 13.610 96 7 14 1 1304358816 12341200 1361088
18:56:51 112918 8192000000 10250131936 10711195648 1329709520 13.965 98 20 40 1 1347698832 12365120 5624192
19:00:09 35592 8192000000 10242256832 10691182592 1322739808 13.902 98 15 30 1 1332711200 6609712 3361680
19:01:45 84741 8192000000 10233860176 10683486208 1300369216 13.699 97 17 35 1 1313642416 9068080 4205120
19:04:10 87792 8192000000 10248163600 10709991424 1325665744 13.928 99 8 16 0 1341453872 14153424 1634704
19:07:11 42451 8192000000 10260416464 10718879744 1357400064 14.214 100 14 28 0 1375135232 14233328 3501840
19:09:19 12386 8192000000 10298055008 10734964736 1360878592 14.245 99 52 104 1 1384108192 12210960 11018640
19:10:34 63479 8192000000 10300560048 10757451776 1388024752 14.488 100 15 30 0 1406914992 15642512 3247728
19:12:46 79569 8192000000 10284764496 10746691584 1357922608 14.219 98 17 34 0 1375953872 14213136 3818128
19:15:13 53296 8192000000 10272704448 10713427968 1351800560 14.164 98 26 49 2 1370950944 13245216 5905168
19:17:09 69629 8192000000 10298068688 10764550144 1383887648 14.451 100 26 52 0 1401843792 12269328 5686816
19:19:19 84234 8192000000 10284773152 10752393216 1364258080 14.276 99 15 30 0 1384657168 17098400 3300688
19:21:45 46283 8192000000 10304598752 10756517888 1382643488 14.440 100 38 75 2 1402445264 11200528 8601248
19:23:33 102856 8192000000 10281072640 10757066752 1363288304 14.267 98 9 18 0 1380113392 14632672 2192416
19:26:16 81760 8192000000 10285496032 10753433600 1377560416 14.395 99 15 30 0 1390256928 8045040 4651472
19:28:38 32627 8192000000 10299118896 10751602688 1390456528 14.510 100 27 54 0 1405803312 8436032 6910752
19:30:36 68005 8192000000 10256691920 10709921792 1293873600 13.640 94 18 37 0 1307250816 9020224 4356992
19:32:46 20630 8192000000 10270175712 10720079872 1351116960 14.158 99 38 76 0 1370922608 11045040 8760608
19:34:10 25206 8192000000 10277588864 10723131392 1337945456 14.039 98 30 60 0 1353702912 8737600 7019856
19:35:41 83483 8192000000 10266024016 10722324480 1334417888 14.007 98 18 36 0 1357695312 19310224 3967200
19:38:05 29349 8192000000 8942615072 9116008448 113665136 1.368 9 1 2 0 115344624 1637136 42352

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 02-06-2022 19:39:29 to 02-06-2022 19:39:44.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 136 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 02-06-2022 19:39:45 to 02-06-2022 19:39:47.

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          241047
MON$OLDEST_ACTIVE               241048
MON$OLDEST_SNAPSHOT             241048
MON$NEXT_TRANSACTION            241048
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                       2036512
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.33601 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33601 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33601 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33601 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'

02.06.22 19:39:48. 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-02 19:39:50 No severe PSQL-related problems occured
02.06.22 19:39:51. End of report.