Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 27.05.2022 01: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 16516120 kB
MemAvailable 31418772 kB
Buffers 0 kB
Cached 15233028 kB
SwapCached 2552 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-05-27 01:38:16
DTS_END                         2022-05-27 01:38:16.3050
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/48304
TRN_ID                          239914
ATT_ID                          643
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: 9256.67 20286 1110800
customer order: creation 171.73 355 20607
customer order: refuse 15.09 332 1811
order to supplier: creation 45.68 1299 5481
order to supplier: removal 3.47 93506 416
invoice (draft): creation 45.87 3591 5504
invoice (draft): removal 53.03 725 6363
invoice accept: apply 41.91 18179 5029
invoice accept: cancel 48.46 829 5815
customer reserve: creation 3791.31 184 454957
customer reserve: removal 4745.62 53 569474
realization accept: apply 84.03 165 10083
realization accept: cancel 81.96 96 9835
payment from customer: creation 58.23 110 6987
payment from customer: removal 5.47 78 656
payment to supplier: creation 49.50 118 5940
payment to supplier: removal 3.38 69 406
service: total inventory turnovers 4.62 959 554
service: total monetary turnovers 6.43 34 771
service: aggregate perf. data 0.89 18890 107
service: refresh index statistics 0.03 266142 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 27-05-2022 01:40:03 to 27-05-2022 01:40:05.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6841
TEST_TIME 2 6445
TEST_TIME 3 6125
TEST_TIME 4 6020
TEST_TIME 5 5957
TEST_TIME 6 5726
TEST_TIME 7 5443
TEST_TIME 8 5672
TEST_TIME 9 6004
TEST_TIME 10 6327
TEST_TIME 11 6597
TEST_TIME 12 6673
TEST_TIME 13 7098
TEST_TIME 14 7092
TEST_TIME 15 7020
TEST_TIME 16 6944
TEST_TIME 17 7292
TEST_TIME 18 7623
TEST_TIME 19 7825
TEST_TIME 20 8512
TEST_TIME 21 8942
TEST_TIME 22 8988
TEST_TIME 23 9290
TEST_TIME 24 9849
TEST_TIME 25 10082
TEST_TIME 26 10380
TEST_TIME 27 10329
TEST_TIME 28 10353
TEST_TIME 29 10699
TEST_TIME 30 10555
TEST_TIME 31 10467
TEST_TIME 32 10234
TEST_TIME 33 10185
TEST_TIME 34 10157
TEST_TIME 35 9991
TEST_TIME 36 10013
TEST_TIME 37 9961
TEST_TIME 38 9984
TEST_TIME 39 10015
TEST_TIME 40 9852
TEST_TIME 41 9813
TEST_TIME 42 9676
TEST_TIME 43 9619
TEST_TIME 44 9654
TEST_TIME 45 9769
TEST_TIME 46 9729
TEST_TIME 47 9740
TEST_TIME 48 9620
TEST_TIME 49 9572
TEST_TIME 50 9497
TEST_TIME 51 9445
TEST_TIME 52 9388
TEST_TIME 53 9385
TEST_TIME 54 9483
TEST_TIME 55 9445
TEST_TIME 56 9562
TEST_TIME 57 9559
TEST_TIME 58 9541
TEST_TIME 59 9551
TEST_TIME 60 9687
TEST_TIME 61 9747
TEST_TIME 62 9807
TEST_TIME 63 9834
TEST_TIME 64 9882
TEST_TIME 65 9894
TEST_TIME 66 9774
TEST_TIME 67 9657
TEST_TIME 68 9759
TEST_TIME 69 9726
TEST_TIME 70 9675
TEST_TIME 71 9644
TEST_TIME 72 9608
TEST_TIME 73 9600
TEST_TIME 74 9552
TEST_TIME 75 9460
TEST_TIME 76 9517
TEST_TIME 77 9517
TEST_TIME 78 9492
TEST_TIME 79 9497
TEST_TIME 80 9493
TEST_TIME 81 9472
TEST_TIME 82 9595
TEST_TIME 83 9618
TEST_TIME 84 9623
TEST_TIME 85 9670
TEST_TIME 86 9631
TEST_TIME 87 9640
TEST_TIME 88 9699
TEST_TIME 89 9742
TEST_TIME 90 9665
TEST_TIME 91 9582
TEST_TIME 92 9519
TEST_TIME 93 9524
TEST_TIME 94 9524
TEST_TIME 95 9455
TEST_TIME 96 9433
TEST_TIME 97 9406
TEST_TIME 98 9384
TEST_TIME 99 9401
TEST_TIME 100 9373
TEST_TIME 101 9432
TEST_TIME 102 9443
TEST_TIME 103 9438
TEST_TIME 104 9450
TEST_TIME 105 9486
TEST_TIME 106 9458
TEST_TIME 107 9466
TEST_TIME 108 9453
TEST_TIME 109 9533
TEST_TIME 110 9511
TEST_TIME 111 9473
TEST_TIME 112 9425
TEST_TIME 113 9390
TEST_TIME 114 9399
TEST_TIME 115 9413
TEST_TIME 116 9370
TEST_TIME 117 9336
TEST_TIME 118 9284
TEST_TIME 119 9264
TEST_TIME 120 9270

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 27-05-2022 01:40:07 to 27-05-2022 01:40:10.

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 1533845 1533845 0 0.00 0 21729 6 0 0
doc_list_biud 1533835 1533835 0 0.00 0 54945 26 0 0
sp_add_invoice_to_stock 5204 5029 175 3.36 33 169544 18180 0 175
sp_cancel_adding_invoice 5815 5815 0 0.00 5 89928 830 0 0
sp_cancel_client_order 1811 1811 0 0.00 4 9401 332 0 0
sp_cancel_customer_reserve 569474 569474 0 0.00 1 54947 54 0 0
sp_cancel_pay_from_customer 656 656 0 0.00 2 8331 78 0 0
sp_cancel_pay_to_supplier 406 406 0 0.00 3 5538 69 0 0
sp_cancel_supplier_invoice 6382 6363 19 0.29 2 26135 726 0 19
sp_cancel_supplier_order 416 416 0 0.00 5 907575 93506 0 0
sp_cancel_write_off 9835 9835 0 0.00 1 8331 96 0 0
sp_client_order 20607 20607 0 0.00 4 82004 356 0 0
sp_customer_reserve 454957 454957 0 0.00 0 59160 185 0 0
sp_fill_shopping_cart 31580 31580 0 0.00 1 9349 38 0 0
sp_fill_shopping_cart_clo_res 454934 454934 0 0.00 0 30420 13 0 0
sp_get_clo_for_invoice 5006 5006 0 0.00 0 28140 300 0 0
sp_kill_cost_storno 16712 16712 0 0.00 0 6274 50 0 0
sp_kill_qstorno_ret_qs2qd 1155562 1155562 0 0.00 0 54251 29 0 0
sp_kill_qty_storno 608802 608802 0 0.00 0 54944 64 0 0
sp_lock_selected_doc 20798 20798 0 0.00 0 10587 6 0 0
sp_make_cost_storno 28016 28016 0 0.00 0 21267 52 0 0
sp_multiply_rows_for_pdistr 28016 28016 0 0.00 0 7966 27 0 0
sp_multiply_rows_for_qdistr 20607 20607 0 0.00 0 81992 175 0 0
sp_pay_from_customer 6987 6987 0 0.00 4 15282 110 0 0
sp_pay_to_supplier 5940 5940 0 0.00 4 15279 119 0 0
sp_payment_common 12926 12926 0 0.00 1 10777 27 0 0
sp_qd_handle_on_cancel_clo 1811 1811 0 0.00 0 9391 304 0 0
sp_qd_handle_on_invoice_upd_sts 10821 10821 0 0.00 0 19169 321 0 0
sp_qd_handle_on_reserve_upd_sts 10082 10082 0 0.00 0 14403 56 0 0
sp_reserve_write_off 10083 10083 0 0.00 6 16825 166 0 0
sp_supplier_invoice 5504 5504 0 0.00 6 109878 3591 0 0
sp_supplier_order 5481 5481 0 0.00 5 41892 1299 0 0
srv_aggregate_perf_data 107 107 0 0.00 2679 481289 18890 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1295 165016 44228 0 0
srv_find_qd_qs_mism 1064339 1064339 0 0.00 0 21729 6 0 0
srv_make_invnt_saldo 554 554 0 0.00 63 20100 960 0 0
srv_make_money_saldo 771 771 0 0.00 1 9237 34 0 0
srv_recalc_idx_stat 4 4 0 0.00 146912 459958 266142 0 0
t$perf-abend:sp_add_invoice_to_stock 175 175 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 19 19 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5029 5029 0 0.00 0 12818 186 0 0
t$perf-norm:sp_cancel_adding_invoice 430 430 0 0.00 0 5123 134 0 0
t$perf-norm:sp_cancel_client_order 1811 1811 0 0.00 0 4298 13 0 0
t$perf-norm:sp_cancel_customer_reserve 644 644 0 0.00 0 5939 28 0 0
t$perf-norm:sp_cancel_pay_from_customer 656 656 0 0.00 0 4915 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 406 406 0 0.00 0 809 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 374 374 0 0.00 0 3181 22 0 0
t$perf-norm:sp_cancel_supplier_order 416 416 0 0.00 0 30124 1693 0 0
t$perf-norm:sp_cancel_write_off 974 974 0 0.00 0 2324 9 0 0
t$perf-norm:sp_client_order 20607 20607 0 0.00 0 8253 18 0 0
t$perf-norm:sp_customer_reserve 1034 1034 0 0.00 0 602 4 0 0
t$perf-norm:sp_pay_from_customer 6987 6987 0 0.00 0 7908 15 0 0
t$perf-norm:sp_pay_to_supplier 5940 5940 0 0.00 0 9182 15 0 0
t$perf-norm:sp_reserve_write_off 10083 10083 0 0.00 0 6072 12 0 0
t$perf-norm:sp_supplier_invoice 5504 5504 0 0.00 0 4668 16 0 0
t$perf-norm:sp_supplier_order 5481 5481 0 0.00 0 6943 15 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 431 15 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 1090 22 0 0
t$perf-norm:srv_make_invnt_saldo 554 554 0 0.00 0 1532 25 0 0
t$perf-norm:srv_make_money_saldo 771 771 0 0.00 0 1064 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 6 3 0 0
v_add_invoice_to_stock 10360 10010 350 3.37 0 7058 17 0 350
v_all_customers 37308 37308 0 0.00 0 16 1 0 0
v_all_suppliers 30570 30570 0 0.00 0 17 1 0 0
v_all_wares 41210 41210 0 0.00 0 92 1 0 0
v_cancel_adding_invoice 860 860 0 0.00 0 5202 18 0 0
v_cancel_client_order 3622 3622 0 0.00 0 1725 4 0 0
v_cancel_customer_prepayment 1312 1312 0 0.00 0 601 4 0 0
v_cancel_customer_reserve 1288 1288 0 0.00 0 1256 5 0 0
v_cancel_payment_to_supplier 812 812 0 0.00 0 3312 8 0 0
v_cancel_supplier_invoice 786 748 38 4.83 0 1514 16 0 38
v_cancel_supplier_order 832 832 0 0.00 0 1241 4 0 0
v_cancel_write_off 1948 1948 0 0.00 0 1769 12 0 0
v_max_id_clo_ord 5475 5475 0 0.00 0 1949 1 0 0
v_max_id_clo_res 1034 1034 0 0.00 0 2654 13 0 0
v_max_id_ord_sup 5499 5499 0 0.00 0 4574 4 0 0
v_max_non_paid_invoice 1628 1628 0 0.00 0 206 2 0 0
v_max_non_paid_realizn 3803 3803 0 0.00 0 2151 4 0 0
v_min_id_clo_ord 5475 5475 0 0.00 0 1829 5 0 0
v_min_id_clo_res 1034 1034 0 0.00 0 5951 26 0 0
v_min_id_ord_sup 5499 5499 0 0.00 0 6505 9 0 0
v_min_non_paid_invoice 5939 5939 0 0.00 0 8792 19 0 0
v_min_non_paid_realizn 6987 6987 0 0.00 0 10772 23 0 0
v_our_firm 10270 10270 0 0.00 0 19 1 0 0
v_reserve_write_off 20164 20164 0 0.00 0 4971 5 0 0
x_lock_depdocs_on_canc_invoice 11804 11804 0 0.00 0 17022 113 0 0
x_lock_depdocs_on_canc_sup_ord 416 416 0 0.00 0 5037 299 0 0
x_make_qstorno_1000_1200 5481 5481 0 0.00 2 41884 1224 0 0
x_make_qstorno_1200_2000 5504 5504 0 0.00 2 108580 3452 0 0
x_make_qstorno_2100_3300 454957 454957 0 0.00 0 59159 169 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 27-05-2022 01:40:11 to 27-05-2022 01:40:15.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
23:08:20 13 8192000000 8806746192 8820789248 11478848 0.139 4 2 3 0 11622624 68832 74944
23:09:21 32620 8192000000 9921279776 10236669952 1000743264 10.886 100 93 186 1 1031971120 6335856 24892000
23:11:02 17433 8192000000 9965079216 10292973568 1050774208 11.368 100 86 172 0 1081184176 8393488 22016480
23:12:23 17554 8192000000 10006844640 10345250816 1089321568 11.736 100 79 158 0 1117926672 9390544 19214560
23:13:42 11200 8192000000 10041368816 10392473600 1134758048 12.166 100 75 150 1 1162449648 9941712 17749888
23:14:59 8137 8192000000 10093479680 10453127168 1176681072 12.559 100 75 150 0 1205794720 12292336 16821312
23:16:11 6178 8192000000 10124259168 10497400832 1218141488 12.944 100 75 150 3 1247244432 12361184 16741760
23:17:19 2996 8192000000 10173048416 10547556352 1257954448 13.311 100 85 169 1 1290020144 13805776 18259920
23:18:23 1489 8192000000 10203812896 10584276992 1286102896 13.569 100 94 191 0 1321980512 14579536 21298080
23:19:25 3096 8192000000 10219479616 10605105152 1305181136 13.742 100 87 178 2 1337122576 12104656 19836784
23:20:29 5164 8192000000 10209013968 10613596160 1312672976 13.810 100 33 66 1 1328243728 8126016 7444736
23:21:37 60463 8192000000 10234297824 10631462912 1319451632 13.872 100 54 104 3 1337192528 6572208 11168688
23:23:39 7501 8192000000 10213371456 10636947456 1326776176 13.938 100 26 52 2 1340055808 6817360 6462272
23:24:50 5455 8192000000 10227027024 10645626880 1332491520 13.990 100 27 55 0 1344588080 5064992 7031568
23:25:58 19988 8192000000 10233031136 10654883840 1328739952 13.956 99 42 84 1 1345517280 5863648 10913680
23:27:46 9678 8192000000 10219096752 10643824640 1323590432 13.909 99 18 36 1 1333435392 5366400 4478560
23:28:57 8100 8192000000 10206030272 10622754816 1298377392 13.680 98 23 46 2 1309220288 5281552 5561344
23:30:05 11092 8192000000 10148359824 10556530688 1224615824 13.004 96 29 57 2 1236967952 5229376 7122752
23:31:17 66001 8192000000 10161148096 10562121728 1219564928 12.958 96 31 63 3 1231610624 4912656 7133040
23:33:28 6407 8192000000 10161668672 10560126976 1243737920 13.181 98 34 69 0 1257336448 6006880 7591648
23:34:36 8342 8192000000 10185685920 10598109184 1291852640 13.621 100 32 64 1 1305279712 5479248 7947824
23:35:49 6768 8192000000 10197327328 10612367360 1304344256 13.735 100 16 32 0 1313842592 4990192 4508144
23:36:59 24060 8192000000 10205170208 10621632512 1312354336 13.807 100 46 91 3 1328263280 5339472 10569472
23:38:28 4193 8192000000 10245858160 10652278784 1338521056 14.044 100 42 82 1 1353847136 6084928 9241152
23:39:42 7288 8192000000 10273841344 10685763584 1355559744 14.197 100 66 132 2 1385920288 15794592 14565952
23:40:58 10034 8192000000 10289253488 10705809408 1369111584 14.319 100 59 118 0 1398549456 16163216 13274656
23:42:19 38160 8192000000 10292031600 10719195136 1380946096 14.425 100 43 86 1 1407887728 17090480 9851152
23:44:03 34641 8192000000 10306706032 10736279552 1388871888 14.496 100 31 62 1 1418968432 22882288 7214256
23:45:41 4137 8192000000 10325417664 10750640128 1401239952 14.606 100 50 100 2 1436475056 23736128 11498976
23:46:53 3285 8192000000 10342421120 10762223616 1408511840 14.671 100 70 140 1 1451528240 26527360 16489040
23:48:01 2325 8192000000 10353622928 10771136512 1412641104 14.707 100 84 166 1 1461697856 29977744 19079008
23:49:09 8080 8192000000 10334030512 10759475200 1393477600 14.537 99 66 131 2 1440216272 29120176 17618496
23:50:19 3784 8192000000 10357140896 10773196800 1407583536 14.662 100 70 139 0 1458323216 33507824 17231856
23:51:30 3554 8192000000 10343215008 10757459968 1391048176 14.515 99 76 149 0 1439418384 30120816 18249392
23:52:41 3094 8192000000 10339022624 10750824448 1391814144 14.522 100 72 142 0 1444885104 35163888 17907072
23:53:50 5146 8192000000 10350041568 10760912896 1394571136 14.547 100 79 159 1 1453655392 39493296 19590960
23:55:03 1898 8192000000 10365245376 10775744512 1397845072 14.576 100 79 157 1 1464400880 45793232 20762576
23:56:09 7999 8192000000 10333513264 10744258560 1368895504 14.317 99 69 138 0 1431579936 44379488 18304944
23:57:25 1295 8192000000 10331538880 10740994048 1374346656 14.366 100 74 147 0 1435892464 42509216 19036592
23:58:34 4557 8192000000 10316996640 10736992256 1372930192 14.353 100 55 108 3 1423915040 36860576 14124272
23:59:45 21398 8192000000 10280630688 10712875008 1331651136 13.982 98 24 48 0 1371068256 32887424 6529696
00:01:44 27311 8192000000 10276263728 10718707712 1336408896 14.025 98 27 54 0 1381823056 38071056 7343104
00:03:19 28438 8192000000 10261208000 10694762496 1319660928 13.874 98 14 28 0 1346013264 22286560 4065776
00:04:49 29672 8192000000 10263121824 10689593344 1344473184 14.098 100 46 92 2 1367598464 10884672 12240608
00:06:21 42228 8192000000 10268299488 10711937024 1352013808 14.166 100 14 29 1 1373145040 17911952 3219280
00:08:04 60116 8192000000 10290552416 10721447936 1354373824 14.187 99 26 52 1 1376396224 16319536 5702864
00:10:06 109336 8192000000 10282165344 10735226880 1374858208 14.371 100 35 70 1 1402028016 19018400 8151408
00:13:15 35586 8192000000 10295426544 10734804992 1369753360 14.325 99 23 45 1 1381799568 7306368 4739840
00:14:56 20768 8192000000 10290994912 10734342144 1370481984 14.331 98 42 83 1 1388508480 8539536 9486960
00:16:21 32474 8192000000 10304781856 10754097152 1390726112 14.512 100 29 58 1 1412557344 15191536 6639696
00:18:11 31536 8192000000 10290753120 10742730752 1384716752 14.459 100 35 69 0 1405810048 13384992 7708304
00:19:58 56053 8192000000 10310982880 10767867904 1407342592 14.660 100 12 24 1 1427641888 17181984 3117312
00:21:54 56438 8192000000 10322048256 10795958272 1414549216 14.724 100 14 28 0 1437820208 19649136 3621856
00:23:53 39031 8192000000 10312512912 10793250816 1410095248 14.685 100 12 24 1 1432818080 19721664 3001168
00:25:38 59184 8192000000 10311778736 10778021888 1414297856 14.722 100 17 33 1 1426696128 8242304 4155968
00:27:42 61665 8192000000 10287151520 10762158080 1357994640 14.219 96 7 14 0 1369424784 9780640 1649504
00:29:44 57947 8192000000 10308886880 10766110720 1378090864 14.399 98 27 54 0 1395078400 10594640 6392896
00:31:42 47266 8192000000 10312335776 10778292224 1394569488 14.547 99 12 24 0 1411509552 14386176 2553888
00:33:30 19730 8192000000 10331097216 10783887360 1410728416 14.690 100 41 81 1 1432079696 12463584 8887696
00:34:50 65376 8192000000 10315923600 10783952896 1380700416 14.423 98 21 42 0 1407477776 21737168 5040192
00:36:57 65806 8192000000 10313608784 10763042816 1349034992 14.139 96 19 38 0 1366171712 13016720 4120000
00:39:03 73318 8192000000 10291490848 10744532992 1329456720 13.962 95 17 32 1 1341826480 8950800 3418960
00:41:18 42234 8192000000 10273416704 10718486528 1321574624 13.891 96 13 24 1 1331708992 7767312 2367056
00:43:03 165016 8192000000 10291546528 10738647040 1307339168 13.762 94 16 32 1 1325341232 14694576 3307488
00:46:48 48643 8192000000 10272463984 10737807360 1344437312 14.097 98 15 30 0 1374527312 26855984 3234016
00:48:38 62840 8192000000 10258352784 10707111936 1347723248 14.127 99 17 33 1 1360947488 9200688 4023552
00:51:16 72958 8192000000 10261824512 10707062784 1335058608 14.013 98 17 34 0 1348073824 8892048 4123168
00:53:29 53488 8192000000 10266146240 10717224960 1361934608 14.255 100 18 37 0 1378539776 12182464 4422704
00:55:24 71710 8192000000 10278825008 10729480192 1347365856 14.124 98 19 38 3 1358709504 7383616 3960032
00:57:38 49255 8192000000 10278915040 10732302336 1353669504 14.180 98 27 54 1 1373830944 14149008 6012432
00:59:28 76430 8192000000 10292206320 10761895936 1362515696 14.260 98 17 34 0 1382952896 16217904 4219296
01:02:30 29133 8192000000 10285466960 10724085760 1353701232 14.181 99 38 75 2 1375113232 12684080 8727920
01:04:33 29752 8192000000 10300135072 10741747712 1382049504 14.435 100 30 60 1 1400192592 11658512 6484576
01:06:04 14600 8192000000 10317592960 10756702208 1389348688 14.500 100 46 92 0 1414980096 15488320 10143088
01:07:21 92196 8192000000 10283434800 10744520704 1348950736 14.138 97 11 22 0 1371154032 19661664 2541632
01:10:22 12333 8192000000 10301782816 10746601472 1391864592 14.522 100 49 98 2 1411168432 7985280 11318560
01:11:46 77354 8192000000 10302838192 10777509888 1401200992 14.606 100 16 31 0 1422807632 18117808 3488832
01:14:04 68808 8192000000 10301633360 10763534336 1382291344 14.437 99 17 34 0 1401217840 14550192 4376304
01:17:01 143191 8192000000 10318951440 10776670208 1405409424 14.643 100 21 42 0 1429791744 19599792 4782528
01:20:55 33502 8192000000 10319559232 10768539648 1379568080 14.413 98 21 42 0 1392038576 7457264 5013232
01:22:32 94834 8192000000 10296711568 10762534912 1357361120 14.214 96 24 46 0 1373712048 10626160 5724768
01:25:12 55461 8192000000 10299043808 10754846720 1375414336 14.376 99 27 53 2 1395275952 13677440 6184176
01:27:31 84190 8192000000 10293644272 10734899200 1321856000 13.894 95 9 18 0 1333347760 9766944 1724816
01:29:59 77619 8192000000 10270120192 10713108480 1313339536 13.816 95 20 40 0 1323952688 6375792 4237360
01:32:18 36205 8192000000 10248965840 10702233600 1317984720 13.858 97 26 52 1 1337852928 13982768 5885440
01:33:55 65756 8192000000 10249355776 10711261184 1332846240 13.993 98 7 14 0 1345965904 11728288 1391376
01:36:01 88865 8192000000 10269055440 10727956480 1355481312 14.197 99 9 18 0 1371596336 13992128 2122896

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 27-05-2022 01:40:17 to 27-05-2022 01:40:32.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 175 v_add_invoice_to_stock 335544517
except 19 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 27-05-2022 01:40:33 to 27-05-2022 01:40:35.

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          238143
MON$OLDEST_ACTIVE               240052
MON$OLDEST_SNAPSHOT             240052
MON$NEXT_TRANSACTION            240052
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                       2022144
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.33596 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33596 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33596 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33596 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 ---
:137: 
:138: mscs-test-161.budzdorov.ru    Fri May 27 01:38:24 2022
:139:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:140: 
:141: 
:142: mscs-test-161.budzdorov.ru    Fri May 27 01:38:32 2022
:143:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:144: 
--- end of diff output ---

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

27.05.22 01:40:36. 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-05-27 01:40:38 No severe PSQL-related problems occured
27.05.22 01:40:39. End of report.