Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 22.07.2022 16:43
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 15945016 kB
MemAvailable 31065032 kB
Buffers 0 kB
Cached 15770064 kB
SwapCached 2500 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-07-22 16:42:28
DTS_END                         2022-07-22 16:42:28.4450
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/37560
TRN_ID                          251310
ATT_ID                          651
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: 9487.00 20283 1138440
customer order: creation 182.05 334 21846
customer order: refuse 15.79 350 1895
order to supplier: creation 49.63 1128 5955
order to supplier: removal 3.60 92779 432
invoice (draft): creation 48.79 2953 5855
invoice (draft): removal 51.33 845 6159
invoice accept: apply 44.13 17144 5295
invoice accept: cancel 46.04 725 5525
customer reserve: creation 4146.58 167 497589
customer reserve: removal 4587.93 56 550551
realization accept: apply 88.61 160 10633
realization accept: cancel 85.33 105 10239
payment from customer: creation 62.02 95 7442
payment from customer: removal 6.23 66 747
payment to supplier: creation 51.98 105 6238
payment to supplier: removal 4.01 66 481
service: total inventory turnovers 5.05 1063 606
service: total monetary turnovers 7.01 24 841
service: aggregate perf. data 0.89 21137 107
service: refresh index statistics 0.03 266359 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 22-07-2022 16:43:48 to 22-07-2022 16:43:50.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8266
TEST_TIME 2 7240
TEST_TIME 3 6152
TEST_TIME 4 6191
TEST_TIME 5 6085
TEST_TIME 6 6059
TEST_TIME 7 6483
TEST_TIME 8 6582
TEST_TIME 9 6883
TEST_TIME 10 7187
TEST_TIME 11 7076
TEST_TIME 12 7218
TEST_TIME 13 7336
TEST_TIME 14 7295
TEST_TIME 15 7678
TEST_TIME 16 7872
TEST_TIME 17 7976
TEST_TIME 18 8228
TEST_TIME 19 8609
TEST_TIME 20 8793
TEST_TIME 21 8737
TEST_TIME 22 8951
TEST_TIME 23 9092
TEST_TIME 24 9238
TEST_TIME 25 9563
TEST_TIME 26 9773
TEST_TIME 27 10374
TEST_TIME 28 11194
TEST_TIME 29 11188
TEST_TIME 30 10930
TEST_TIME 31 10874
TEST_TIME 32 11005
TEST_TIME 33 10900
TEST_TIME 34 10756
TEST_TIME 35 10500
TEST_TIME 36 10569
TEST_TIME 37 10452
TEST_TIME 38 10427
TEST_TIME 39 10313
TEST_TIME 40 10310
TEST_TIME 41 10155
TEST_TIME 42 10293
TEST_TIME 43 10249
TEST_TIME 44 10176
TEST_TIME 45 10150
TEST_TIME 46 10084
TEST_TIME 47 10010
TEST_TIME 48 9993
TEST_TIME 49 10014
TEST_TIME 50 10018
TEST_TIME 51 9986
TEST_TIME 52 9947
TEST_TIME 53 10063
TEST_TIME 54 10028
TEST_TIME 55 9996
TEST_TIME 56 10062
TEST_TIME 57 10092
TEST_TIME 58 10135
TEST_TIME 59 10128
TEST_TIME 60 10117
TEST_TIME 61 10134
TEST_TIME 62 10282
TEST_TIME 63 10300
TEST_TIME 64 10359
TEST_TIME 65 10219
TEST_TIME 66 10161
TEST_TIME 67 10097
TEST_TIME 68 10022
TEST_TIME 69 9997
TEST_TIME 70 9946
TEST_TIME 71 9876
TEST_TIME 72 9820
TEST_TIME 73 9805
TEST_TIME 74 9816
TEST_TIME 75 9797
TEST_TIME 76 9820
TEST_TIME 77 9795
TEST_TIME 78 9762
TEST_TIME 79 9759
TEST_TIME 80 9796
TEST_TIME 81 9760
TEST_TIME 82 9778
TEST_TIME 83 9784
TEST_TIME 84 9828
TEST_TIME 85 9822
TEST_TIME 86 9831
TEST_TIME 87 9849
TEST_TIME 88 9869
TEST_TIME 89 9869
TEST_TIME 90 9804
TEST_TIME 91 9760
TEST_TIME 92 9748
TEST_TIME 93 9709
TEST_TIME 94 9737
TEST_TIME 95 9692
TEST_TIME 96 9664
TEST_TIME 97 9655
TEST_TIME 98 9633
TEST_TIME 99 9633
TEST_TIME 100 9677
TEST_TIME 101 9714
TEST_TIME 102 9787
TEST_TIME 103 9742
TEST_TIME 104 9784
TEST_TIME 105 9770
TEST_TIME 106 9755
TEST_TIME 107 9840
TEST_TIME 108 9819
TEST_TIME 109 9823
TEST_TIME 110 9740
TEST_TIME 111 9712
TEST_TIME 112 9690
TEST_TIME 113 9630
TEST_TIME 114 9631
TEST_TIME 115 9575
TEST_TIME 116 9567
TEST_TIME 117 9549
TEST_TIME 118 9547
TEST_TIME 119 9533
TEST_TIME 120 9489

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 22-07-2022 16:43:52 to 22-07-2022 16:43:55.

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 1599102 1599102 0 0.00 0 14280 6 0 0
doc_list_biud 1599102 1599102 0 0.00 0 49388 25 0 0
sp_add_invoice_to_stock 5431 5295 136 2.50 29 186004 17144 0 136
sp_cancel_adding_invoice 5525 5525 0 0.00 4 59731 726 0 0
sp_cancel_client_order 1895 1895 0 0.00 4 19783 351 0 0
sp_cancel_customer_reserve 550551 550551 0 0.00 0 48152 57 0 0
sp_cancel_pay_from_customer 747 747 0 0.00 2 8937 67 0 0
sp_cancel_pay_to_supplier 481 481 0 0.00 2 4745 67 0 0
sp_cancel_supplier_invoice 6175 6159 16 0.25 1 49398 845 0 16
sp_cancel_supplier_order 432 432 0 0.00 6 992901 92779 0 0
sp_cancel_write_off 10239 10239 0 0.00 1 14905 105 0 0
sp_client_order 21846 21846 0 0.00 3 42367 335 0 0
sp_customer_reserve 497589 497589 0 0.00 0 57603 168 0 0
sp_fill_shopping_cart 33656 33656 0 0.00 1 23572 37 0 0
sp_fill_shopping_cart_clo_res 497577 497577 0 0.00 0 20525 12 0 0
sp_get_clo_for_invoice 5283 5283 0 0.00 0 20367 295 0 0
sp_kill_cost_storno 16992 16992 0 0.00 0 14274 51 0 0
sp_kill_qstorno_ret_qs2qd 1117932 1117932 0 0.00 0 49381 31 0 0
sp_kill_qty_storno 590717 590717 0 0.00 0 49386 67 0 0
sp_lock_selected_doc 22104 22104 0 0.00 0 8841 5 0 0
sp_make_cost_storno 29596 29596 0 0.00 0 8744 44 0 0
sp_multiply_rows_for_pdistr 29596 29596 0 0.00 0 5704 23 0 0
sp_multiply_rows_for_qdistr 21846 21846 0 0.00 0 42361 145 0 0
sp_pay_from_customer 7442 7442 0 0.00 4 8907 95 0 0
sp_pay_to_supplier 6238 6238 0 0.00 4 8921 106 0 0
sp_payment_common 13680 13680 0 0.00 2 5903 21 0 0
sp_qd_handle_on_cancel_clo 1895 1895 0 0.00 0 19770 308 0 0
sp_qd_handle_on_invoice_upd_sts 10808 10808 0 0.00 0 30187 287 0 0
sp_qd_handle_on_reserve_upd_sts 10633 10633 0 0.00 0 13383 56 0 0
sp_reserve_write_off 10633 10633 0 0.00 6 13430 161 0 0
sp_supplier_invoice 5855 5855 0 0.00 7 92444 2953 0 0
sp_supplier_order 5955 5955 0 0.00 4 20108 1129 0 0
srv_aggregate_perf_data 107 107 0 0.00 2738 325269 21138 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1284 139271 45857 0 0
srv_find_qd_qs_mism 1087456 1087456 0 0.00 0 13047 5 0 0
srv_make_invnt_saldo 606 606 0 0.00 31 82081 1064 0 0
srv_make_money_saldo 841 841 0 0.00 1 1122 24 0 0
srv_recalc_idx_stat 4 4 0 0.00 122409 377155 266360 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 16 16 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5295 5295 0 0.00 0 13946 145 0 0
t$perf-norm:sp_cancel_adding_invoice 431 431 0 0.00 0 6643 99 0 0
t$perf-norm:sp_cancel_client_order 1895 1895 0 0.00 0 2289 12 0 0
t$perf-norm:sp_cancel_customer_reserve 768 768 0 0.00 0 6648 23 0 0
t$perf-norm:sp_cancel_pay_from_customer 747 747 0 0.00 0 2758 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 481 481 0 0.00 0 878 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 443 443 0 0.00 0 585 11 0 0
t$perf-norm:sp_cancel_supplier_order 432 432 0 0.00 0 24891 1572 0 0
t$perf-norm:sp_cancel_write_off 991 991 0 0.00 0 1712 5 0 0
t$perf-norm:sp_client_order 21846 21846 0 0.00 0 7043 13 0 0
t$perf-norm:sp_customer_reserve 1019 1019 0 0.00 0 960 7 0 0
t$perf-norm:sp_pay_from_customer 7442 7442 0 0.00 0 10774 12 0 0
t$perf-norm:sp_pay_to_supplier 6238 6238 0 0.00 0 10703 14 0 0
t$perf-norm:sp_reserve_write_off 10633 10633 0 0.00 0 8199 12 0 0
t$perf-norm:sp_supplier_invoice 5855 5855 0 0.00 0 12899 17 0 0
t$perf-norm:sp_supplier_order 5955 5955 0 0.00 0 6163 13 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 6918 68 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 10 0 0 0
t$perf-norm:srv_make_invnt_saldo 606 606 0 0.00 0 526 15 0 0
t$perf-norm:srv_make_money_saldo 841 841 0 0.00 0 2027 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 9 5 0 0
v_add_invoice_to_stock 10838 10566 272 2.50 0 9556 19 0 272
v_all_customers 39492 39492 0 0.00 0 23 0 0 0
v_all_suppliers 32272 32272 0 0.00 0 16 1 0 0
v_all_wares 43692 43692 0 0.00 0 30 1 0 0
v_cancel_adding_invoice 862 862 0 0.00 0 4152 11 0 0
v_cancel_client_order 3790 3790 0 0.00 0 8891 10 0 0
v_cancel_customer_prepayment 1494 1494 0 0.00 0 8885 12 0 0
v_cancel_customer_reserve 1536 1536 0 0.00 0 943 4 0 0
v_cancel_payment_to_supplier 962 962 0 0.00 0 893 6 0 0
v_cancel_supplier_invoice 918 886 32 3.48 0 1226 14 0 32
v_cancel_supplier_order 864 864 0 0.00 0 1867 7 0 0
v_cancel_write_off 1982 1982 0 0.00 0 6674 20 0 0
v_max_id_clo_ord 5955 5955 0 0.00 0 824 2 0 0
v_max_id_clo_res 1019 1019 0 0.00 0 9336 23 0 0
v_max_id_ord_sup 5855 5855 0 0.00 0 5421 5 0 0
v_max_non_paid_invoice 1912 1912 0 0.00 0 1413 3 0 0
v_max_non_paid_realizn 4125 4125 0 0.00 0 4010 4 0 0
v_min_id_clo_ord 5955 5955 0 0.00 0 2165 5 0 0
v_min_id_clo_res 1019 1019 0 0.00 0 3310 12 0 0
v_min_id_ord_sup 5855 5855 0 0.00 0 3864 5 0 0
v_min_non_paid_invoice 6238 6238 0 0.00 0 5897 12 0 0
v_min_non_paid_realizn 7442 7442 0 0.00 0 5888 16 0 0
v_our_firm 10834 10834 0 0.00 0 25 1 0 0
v_reserve_write_off 21266 21266 0 0.00 0 9563 9 0 0
x_lock_depdocs_on_canc_invoice 11241 11241 0 0.00 0 11844 125 0 0
x_lock_depdocs_on_canc_sup_ord 432 432 0 0.00 0 6018 327 0 0
x_make_qstorno_1000_1200 5955 5955 0 0.00 1 20096 1058 0 0
x_make_qstorno_1200_2000 5855 5855 0 0.00 3 87654 2815 0 0
x_make_qstorno_2100_3300 497589 497589 0 0.00 0 57600 153 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 22-07-2022 16:43:56 to 22-07-2022 16:44:00.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
14:12:32 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:13:33 21755 8192000000 9883041024 10191491072 972339904 10.610 100 90 180 0 1003039536 6294592 24405040
14:15:03 11415 8192000000 9949036176 10267955200 1023620320 11.107 100 89 178 0 1054816592 8176544 23019728
14:16:20 16375 8192000000 9982353680 10311806976 1059424496 11.451 100 87 173 0 1091062560 10365360 21272704
14:17:45 8736 8192000000 10031158464 10375598080 1117979200 12.008 100 87 174 0 1150826800 12032976 20814624
14:18:57 5582 8192000000 10070572016 10429829120 1158351520 12.388 100 75 150 0 1187863408 12153520 17358368
14:20:06 2821 8192000000 10127585856 10494156800 1218064976 12.944 100 87 174 1 1251307808 12903744 20339088
14:21:11 2568 8192000000 10160399152 10534789120 1250602976 13.244 100 80 160 1 1281116480 13603792 16909712
14:22:16 4416 8192000000 10183777936 10571091968 1279980512 13.513 100 78 156 0 1310692512 13514048 17197952
14:23:21 45012 8192000000 10184311952 10600136704 1302757344 13.720 100 45 90 0 1323133984 9373904 11002736
14:25:10 6927 8192000000 10194654592 10612547584 1311893776 13.803 100 24 50 0 1323354400 6070912 5389712
14:26:20 12828 8192000000 10203640976 10621128704 1319651920 13.874 100 16 32 0 1329574944 6123616 3799408
14:27:35 63831 8192000000 10217786624 10640965632 1328616944 13.955 100 25 44 2 1338546672 4849488 5080240
14:29:42 4544 8192000000 10233145872 10652971008 1338883936 14.047 100 27 54 0 1351009312 5794272 6331104
14:30:52 12021 8192000000 10220341184 10638880768 1306881632 13.758 98 15 31 0 1315923584 5343216 3698736
14:32:08 56960 8192000000 10176918256 10588123136 1227362592 13.030 94 30 55 0 1237702832 4152368 6187872
14:34:10 6787 8192000000 10151083136 10551427072 1239605296 13.143 98 27 53 0 1251663920 5673456 6385168
14:35:18 4568 8192000000 10140087952 10539610112 1248656576 13.226 100 24 48 0 1260128176 5640128 5831472
14:36:27 17968 8192000000 10168134336 10572767232 1274882352 13.466 100 27 54 2 1285996608 5435248 5679008
14:38:21 4570 8192000000 10181301040 10590658560 1292418800 13.626 100 25 50 0 1303841696 5137968 6284928
14:39:27 9775 8192000000 10197793664 10613055488 1307687952 13.765 100 21 42 0 1317146656 5062160 4396544
14:40:37 9607 8192000000 10217182704 10634039296 1311814576 13.803 100 20 41 0 1321616416 5736640 4065200
14:41:48 76081 8192000000 10217374512 10624929792 1311641344 13.801 99 38 73 1 1325529216 5255408 8632464
14:44:15 17343 8192000000 10264433120 10674937856 1353860640 14.182 100 54 108 0 1378032640 11815040 12356960
14:45:35 20688 8192000000 10286040256 10706956288 1372432848 14.349 100 49 97 0 1398465376 14907616 11124912
14:46:59 14916 8192000000 10295550960 10719289344 1381231952 14.428 100 43 85 2 1410706912 19546416 9928544
14:48:18 23812 8192000000 10297904976 10717216768 1367150576 14.302 99 51 102 0 1398577232 19778912 11647744
14:49:47 3072 8192000000 10309066160 10726612992 1378432560 14.403 99 58 117 4 1410869936 18497520 13939856
14:50:53 4833 8192000000 10283724816 10698932224 1362785616 14.262 100 52 102 0 1398646048 23633184 12227248
14:52:07 9999 8192000000 10298504208 10717290496 1365377376 14.286 99 58 116 1 1403020256 23138320 14504560
14:53:26 3991 8192000000 10319146144 10729570304 1383694528 14.450 100 71 137 0 1423873760 24035120 16144112
14:54:34 6912 8192000000 10324734944 10738077696 1388230784 14.490 100 61 122 2 1433773792 30201280 15341728
14:55:48 6401 8192000000 10315206192 10729103360 1348198496 14.131 97 63 125 3 1400414960 36273664 15942800
14:56:59 9597 8192000000 10314780832 10732449792 1372171984 14.347 100 60 120 0 1425311712 37189056 15950672
14:58:15 1757 8192000000 10340038992 10748297216 1378615744 14.404 100 83 163 1 1440227168 41404912 20206512
14:59:22 2744 8192000000 10326506112 10731520000 1368912096 14.317 100 90 181 3 1433948688 42289168 22747424
15:00:29 1696 8192000000 10344604288 10750103552 1366722064 14.298 99 75 150 0 1433050400 47228448 19099888
15:01:38 1284 8192000000 10343057936 10751549440 1381916128 14.434 100 82 165 0 1444637296 41277296 21443872
15:02:41 13215 8192000000 10321721184 10752024576 1370126304 14.328 99 49 98 1 1428043888 44482016 13435568
15:03:59 16087 8192000000 10336046688 10768486400 1390150608 14.507 100 46 89 0 1448719312 46879312 11689392
15:05:36 19177 8192000000 10339341216 10771804160 1385303312 14.464 100 37 75 0 1447891632 52084624 10503696
15:06:58 27971 8192000000 10313171760 10747424768 1362450272 14.259 99 22 44 0 1412153072 43332576 6370224
15:08:27 31233 8192000000 10299370704 10743267328 1347398336 14.124 99 28 56 0 1404389376 48581088 8409952
15:10:03 91791 8192000000 10257910352 10715840512 1285034400 13.559 94 10 21 0 1309402096 22021888 2345808
15:12:35 26390 8192000000 10285889840 10725220352 1360758432 14.244 99 49 97 1 1378656848 7479280 10419136
15:14:03 86788 8192000000 10285217824 10726555648 1340555344 14.062 97 11 22 0 1360276528 17731712 1989472
15:16:32 108218 8192000000 10304861984 10753683456 1379235232 14.410 99 18 36 0 1391588784 7717024 4636528
15:19:37 75931 8192000000 10296913344 10753097728 1391086656 14.516 100 37 73 0 1410507440 11344864 8075920
15:22:09 33576 8192000000 10285496800 10741211136 1358467520 14.224 97 30 59 0 1378403216 11963104 7972592
15:23:54 38900 8192000000 10308118000 10766319616 1397274016 14.571 100 39 74 1 1416876544 11803488 7799040
15:25:39 48587 8192000000 10311746928 10777636864 1405576848 14.645 99 10 20 0 1419775104 11330464 2867792
15:27:40 62414 8192000000 10304894976 10769698816 1384716496 14.459 98 12 24 1 1398470096 11423248 2330352
15:29:46 78659 8192000000 10308918096 10779848704 1372268560 14.347 97 16 32 0 1390708176 14578480 3861136
15:33:17 32937 8192000000 10308294832 10772557824 1396396080 14.563 99 18 36 0 1407885920 7823120 3666720
15:34:51 37520 8192000000 10301080240 10766344192 1393662336 14.539 99 20 40 0 1408628816 9962144 5004336
15:36:30 28182 8192000000 10299069696 10752126976 1384205904 14.454 99 22 44 0 1397679664 8619200 4854560
15:38:02 22355 8192000000 10282552240 10732089344 1356599216 14.207 98 25 50 0 1373167904 10918064 5650624
15:39:35 50882 8192000000 10300817904 10753474560 1381554976 14.430 99 15 30 0 1397738144 12843264 3339904
15:41:30 57050 8192000000 10301309472 10750574592 1321204896 13.888 94 22 44 0 1335029520 9293200 4531424
15:43:27 72067 8192000000 10233861440 10683949056 1270513952 13.426 92 17 34 1 1295333984 20460464 4359568
15:45:43 56986 8192000000 10275519824 10724126720 1287423184 13.581 94 11 22 0 1306836128 17203744 2209200
15:47:46 74884 8192000000 10250106256 10690052096 1300110864 13.696 96 22 44 0 1312315216 6738048 5466304
15:50:03 40305 8192000000 10258210704 10700333056 1350873312 14.155 100 52 105 0 1373351712 11208096 11270304
15:51:44 46354 8192000000 10265901040 10726363136 1371853936 14.344 100 25 48 1 1387323024 10084128 5384960
15:53:30 65927 8192000000 10269874288 10708934656 1345020656 14.103 98 19 38 0 1362733840 13348496 4364688
15:55:37 53718 8192000000 10250329840 10696957952 1327587872 13.945 97 16 32 0 1344523840 12760784 4175184
15:57:40 83258 8192000000 10250982576 10714390528 1346704448 14.118 99 15 29 0 1360767088 10919456 3143184
16:00:08 41049 8192000000 10279578352 10725298176 1369665472 14.324 100 26 51 0 1383036160 7974048 5396640
16:01:51 71319 8192000000 10261291856 10712694784 1303164976 13.724 95 15 30 0 1314635520 7732016 3738528
16:04:07 65126 8192000000 10277918784 10731220992 1359674416 14.234 99 21 41 0 1377493344 13357520 4461408
16:06:14 90498 8192000000 10291536432 10742472704 1335755280 14.019 97 10 20 0 1350906816 13190496 1961040
16:08:45 124114 8192000000 10291857328 10755084288 1332883024 13.993 97 14 28 0 1347755040 12114528 2757488
16:11:50 114463 8192000000 10296482240 10752442368 1395366384 14.554 100 20 40 0 1408424992 8661712 4396896
16:14:44 48988 8192000000 10271832640 10729193472 1360780832 14.244 98 29 56 0 1380603664 13670576 6152256
16:16:34 59856 8192000000 10279730416 10745602048 1366390080 14.295 98 12 24 0 1376860144 6912848 3557216
16:18:34 38177 8192000000 10283565392 10743922688 1331359776 13.979 96 7 14 0 1342488144 9506192 1622176
16:20:12 53597 8192000000 10293751344 10749833216 1360103520 14.238 98 31 62 1 1380687184 13071168 7512496
16:22:07 128294 8192000000 10262248896 10717884416 1283257792 13.543 93 12 24 0 1306949248 20737264 2954192
16:25:15 16299 8192000000 10287657040 10725785600 1368799536 14.316 100 43 86 0 1386504288 8221888 9482864
16:26:35 45284 8192000000 10259874128 10706010112 1349386000 14.142 99 27 54 0 1369124144 13653856 6084288
16:28:23 23629 8192000000 10287056704 10727440384 1354762752 14.190 99 40 80 1 1379470784 15580704 9127328
16:29:47 139271 8192000000 10247377872 10712059904 1233811216 13.089 90 6 12 0 1255576512 20489664 1275632
16:33:06 42936 8192000000 10240134512 10699087872 1311519232 13.800 97 13 26 0 1324847200 10530288 2797680
16:36:08 73799 8192000000 10248059760 10720186368 1354087184 14.184 100 4 8 0 1367233088 12268576 877328
16:38:22 65120 8192000000 10257839184 10722115584 1359354480 14.232 100 13 25 0 1373373360 10682368 3336512
16:40:43 39035 8192000000 10257518368 10705948672 1331908400 13.984 98 18 36 0 1348273168 12414128 3950640
16:42:22 13346 8192000000 9126167376 9374556160 290877264 3.428 22 3 6 0 294848864 3411296 560304

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 22-07-2022 16:44:02 to 22-07-2022 16:44:17.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 22-07-2022 16:44:18 to 22-07-2022 16:44:20.

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          253200
MON$OLDEST_ACTIVE               253201
MON$OLDEST_SNAPSHOT             253201
MON$NEXT_TRANSACTION            253201
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                       2043296
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.33612 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33612 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33612 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33612 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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Fri Jul 22 16:42:39 2022
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Fri Jul 22 16:42:45 2022
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:13: 
:14: 
:15: mscs-test-161.budzdorov.ru    Fri Jul 22 16:42:46 2022
:16:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:17: 
:18: 
:19: mscs-test-161.budzdorov.ru    Fri Jul 22 16:42:47 2022
:20:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:21: 
--- end of diff output ---

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

22.07.22 16:44:21. 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-07-22 16:44:23 No severe PSQL-related problems occured
22.07.22 16:44:24. End of report.