Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 12.08.2022 01:40
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 15758352 kB
MemAvailable 30947644 kB
Buffers 0 kB
Cached 15979000 kB
SwapCached 2508 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 3.0.11
DB_NAME                         /home/bases/oltp30-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3333
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp30-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp30
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-08-12 01:38:22
DTS_END                         2022-08-12 01:38:22.9570
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/53270
TRN_ID                          239697
ATT_ID                          684
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: 9535.03 21473 1144203
customer order: creation 172.50 354 20700
customer order: refuse 14.69 334 1763
order to supplier: creation 45.26 1216 5431
order to supplier: removal 3.36 95349 403
invoice (draft): creation 46.51 3292 5581
invoice (draft): removal 53.73 761 6448
invoice accept: apply 43.05 16893 5166
invoice accept: cancel 48.83 782 5859
customer reserve: creation 3992.03 166 479044
customer reserve: removal 4810.31 52 577237
realization accept: apply 82.62 188 9914
realization accept: cancel 91.93 100 11032
payment from customer: creation 59.67 100 7160
payment from customer: removal 6.16 66 739
payment to supplier: creation 48.96 131 5875
payment to supplier: removal 3.43 63 411
service: total inventory turnovers 4.62 1149 554
service: total monetary turnovers 6.48 26 777
service: aggregate perf. data 0.88 21359 105
service: refresh index statistics 0.03 287076 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 12-08-2022 01:40:47 to 12-08-2022 01:40:49.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3707
TEST_TIME 2 3640
TEST_TIME 3 4346
TEST_TIME 4 4901
TEST_TIME 5 5186
TEST_TIME 6 5207
TEST_TIME 7 5274
TEST_TIME 8 5300
TEST_TIME 9 5768
TEST_TIME 10 6178
TEST_TIME 11 6467
TEST_TIME 12 6596
TEST_TIME 13 6462
TEST_TIME 14 6551
TEST_TIME 15 7242
TEST_TIME 16 7301
TEST_TIME 17 7493
TEST_TIME 18 8103
TEST_TIME 19 8007
TEST_TIME 20 8369
TEST_TIME 21 8812
TEST_TIME 22 9106
TEST_TIME 23 9383
TEST_TIME 24 9264
TEST_TIME 25 9351
TEST_TIME 26 9698
TEST_TIME 27 10098
TEST_TIME 28 10546
TEST_TIME 29 10795
TEST_TIME 30 10881
TEST_TIME 31 10624
TEST_TIME 32 10639
TEST_TIME 33 10650
TEST_TIME 34 10748
TEST_TIME 35 10659
TEST_TIME 36 10506
TEST_TIME 37 10427
TEST_TIME 38 10200
TEST_TIME 39 10139
TEST_TIME 40 10262
TEST_TIME 41 10073
TEST_TIME 42 10005
TEST_TIME 43 9925
TEST_TIME 44 9956
TEST_TIME 45 9994
TEST_TIME 46 10042
TEST_TIME 47 9947
TEST_TIME 48 9888
TEST_TIME 49 9894
TEST_TIME 50 9761
TEST_TIME 51 9717
TEST_TIME 52 9709
TEST_TIME 53 9739
TEST_TIME 54 9742
TEST_TIME 55 9855
TEST_TIME 56 9763
TEST_TIME 57 9767
TEST_TIME 58 9720
TEST_TIME 59 9804
TEST_TIME 60 9932
TEST_TIME 61 9947
TEST_TIME 62 10026
TEST_TIME 63 10150
TEST_TIME 64 10088
TEST_TIME 65 10082
TEST_TIME 66 10158
TEST_TIME 67 10053
TEST_TIME 68 9960
TEST_TIME 69 9983
TEST_TIME 70 9899
TEST_TIME 71 9851
TEST_TIME 72 9897
TEST_TIME 73 9858
TEST_TIME 74 9823
TEST_TIME 75 9766
TEST_TIME 76 9721
TEST_TIME 77 9677
TEST_TIME 78 9715
TEST_TIME 79 9662
TEST_TIME 80 9634
TEST_TIME 81 9581
TEST_TIME 82 9595
TEST_TIME 83 9671
TEST_TIME 84 9716
TEST_TIME 85 9727
TEST_TIME 86 9718
TEST_TIME 87 9841
TEST_TIME 88 9857
TEST_TIME 89 9814
TEST_TIME 90 9874
TEST_TIME 91 9947
TEST_TIME 92 9972
TEST_TIME 93 9871
TEST_TIME 94 9785
TEST_TIME 95 9748
TEST_TIME 96 9756
TEST_TIME 97 9743
TEST_TIME 98 9749
TEST_TIME 99 9734
TEST_TIME 100 9710
TEST_TIME 101 9718
TEST_TIME 102 9708
TEST_TIME 103 9750
TEST_TIME 104 9725
TEST_TIME 105 9769
TEST_TIME 106 9774
TEST_TIME 107 9788
TEST_TIME 108 9808
TEST_TIME 109 9785
TEST_TIME 110 9772
TEST_TIME 111 9769
TEST_TIME 112 9701
TEST_TIME 113 9720
TEST_TIME 114 9669
TEST_TIME 115 9681
TEST_TIME 116 9666
TEST_TIME 117 9635
TEST_TIME 118 9595
TEST_TIME 119 9613
TEST_TIME 120 9544

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 12-08-2022 01:40:51 to 12-08-2022 01:40:54.

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 1588164 1588164 0 0.00 0 51824 6 0 0
doc_list_biud 1588159 1588159 0 0.00 0 52890 25 0 0
sp_add_invoice_to_stock 5341 5166 175 3.27 52 203406 16893 0 175
sp_cancel_adding_invoice 5859 5859 0 0.00 4 93203 783 0 0
sp_cancel_client_order 1763 1763 0 0.00 4 15235 335 0 0
sp_cancel_customer_reserve 577237 577237 0 0.00 0 52893 52 0 0
sp_cancel_pay_from_customer 739 739 0 0.00 2 6428 67 0 0
sp_cancel_pay_to_supplier 411 411 0 0.00 2 4586 63 0 0
sp_cancel_supplier_invoice 6462 6448 14 0.21 2 17798 761 0 14
sp_cancel_supplier_order 403 403 0 0.00 6 1026234 95349 0 0
sp_cancel_write_off 11032 11032 0 0.00 1 10303 100 0 0
sp_client_order 20700 20700 0 0.00 3 12379 355 0 0
sp_customer_reserve 479044 479044 0 0.00 0 110935 167 0 0
sp_fill_shopping_cart 31705 31705 0 0.00 1 26838 35 0 0
sp_fill_shopping_cart_clo_res 479029 479029 0 0.00 0 16731 13 0 0
sp_get_clo_for_invoice 5149 5149 0 0.00 0 19745 270 0 0
sp_kill_cost_storno 18041 18041 0 0.00 0 9224 52 0 0
sp_kill_qstorno_ret_qs2qd 1172357 1172357 0 0.00 0 52875 29 0 0
sp_kill_qty_storno 617804 617804 0 0.00 0 52890 62 0 0
sp_lock_selected_doc 20864 20864 0 0.00 0 9304 7 0 0
sp_make_cost_storno 28097 28097 0 0.00 0 51806 55 0 0
sp_multiply_rows_for_pdistr 28096 28096 0 0.00 0 11252 28 0 0
sp_multiply_rows_for_qdistr 20700 20700 0 0.00 0 12371 163 0 0
sp_pay_from_customer 7160 7160 0 0.00 4 10846 100 0 0
sp_pay_to_supplier 5875 5875 0 0.00 4 16872 131 0 0
sp_payment_common 13034 13034 0 0.00 1 16828 27 0 0
sp_qd_handle_on_cancel_clo 1763 1763 0 0.00 0 15230 311 0 0
sp_qd_handle_on_invoice_upd_sts 11007 11007 0 0.00 0 27507 312 0 0
sp_qd_handle_on_reserve_upd_sts 9914 9914 0 0.00 0 23409 69 0 0
sp_reserve_write_off 9914 9914 0 0.00 6 23421 189 0 0
sp_supplier_invoice 5581 5581 0 0.00 6 100096 3292 0 0
sp_supplier_order 5431 5431 0 0.00 4 25016 1216 0 0
srv_aggregate_perf_data 105 105 0 0.00 2773 298352 21359 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1430 192851 48217 0 0
srv_find_qd_qs_mism 1095898 1095898 0 0.00 0 10919 5 0 0
srv_make_invnt_saldo 554 554 0 0.00 44 71771 1149 0 0
srv_make_money_saldo 777 777 0 0.00 1 2626 26 0 0
srv_recalc_idx_stat 4 4 0 0.00 152738 534243 287076 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 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5167 5167 0 0.00 0 11696 174 0 0
t$perf-norm:sp_cancel_adding_invoice 429 429 0 0.00 0 4443 103 0 0
t$perf-norm:sp_cancel_client_order 1763 1763 0 0.00 0 6721 19 0 0
t$perf-norm:sp_cancel_customer_reserve 679 679 0 0.00 0 3393 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 739 739 0 0.00 0 3454 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 411 411 0 0.00 0 2557 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 403 403 0 0.00 0 773 14 0 0
t$perf-norm:sp_cancel_supplier_order 403 403 0 0.00 0 16776 1584 0 0
t$perf-norm:sp_cancel_write_off 976 976 0 0.00 0 6718 11 0 0
t$perf-norm:sp_client_order 20700 20700 0 0.00 0 8289 17 0 0
t$perf-norm:sp_customer_reserve 958 958 0 0.00 0 1274 5 0 0
t$perf-norm:sp_pay_from_customer 7160 7160 0 0.00 0 5585 12 0 0
t$perf-norm:sp_pay_to_supplier 5875 5875 0 0.00 0 5745 14 0 0
t$perf-norm:sp_reserve_write_off 9914 9914 0 0.00 0 8127 11 0 0
t$perf-norm:sp_supplier_invoice 5581 5581 0 0.00 0 8533 17 0 0
t$perf-norm:sp_supplier_order 5431 5431 0 0.00 0 5857 16 0 0
t$perf-norm:srv_aggregate_perf_data 105 105 0 0.00 0 61 1 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 1145 28 0 0
t$perf-norm:srv_make_invnt_saldo 554 554 0 0.00 0 554 16 0 0
t$perf-norm:srv_make_money_saldo 777 777 0 0.00 0 3600 26 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 10644 10294 350 3.28 0 12446 18 0 350
v_all_customers 37940 37940 0 0.00 0 42 1 0 0
v_all_suppliers 30450 30450 0 0.00 0 24 1 0 0
v_all_wares 41400 41400 0 0.00 0 23 1 0 0
v_cancel_adding_invoice 858 858 0 0.00 0 83 5 0 0
v_cancel_client_order 3526 3526 0 0.00 0 3152 6 0 0
v_cancel_customer_prepayment 1478 1478 0 0.00 0 1757 5 0 0
v_cancel_customer_reserve 1358 1358 0 0.00 0 8586 17 0 0
v_cancel_payment_to_supplier 822 822 0 0.00 0 1033 6 0 0
v_cancel_supplier_invoice 834 806 28 3.35 0 6267 30 0 28
v_cancel_supplier_order 806 806 0 0.00 0 3644 9 0 0
v_cancel_write_off 1952 1952 0 0.00 0 4440 19 0 0
v_max_id_clo_ord 5426 5426 0 0.00 0 769 1 0 0
v_max_id_clo_res 958 958 0 0.00 0 3892 19 0 0
v_max_id_ord_sup 5579 5579 0 0.00 0 4435 6 0 0
v_max_non_paid_invoice 1654 1654 0 0.00 0 5293 6 0 0
v_max_non_paid_realizn 3706 3706 0 0.00 0 355 3 0 0
v_min_id_clo_ord 5426 5426 0 0.00 0 7142 10 0 0
v_min_id_clo_res 958 958 0 0.00 0 4548 16 0 0
v_min_id_ord_sup 5579 5579 0 0.00 0 6267 6 0 0
v_min_non_paid_invoice 5874 5874 0 0.00 0 16825 22 0 0
v_min_non_paid_realizn 7160 7160 0 0.00 0 10787 18 0 0
v_our_firm 10368 10368 0 0.00 0 24 1 0 0
v_reserve_write_off 19828 19828 0 0.00 0 3602 5 0 0
x_lock_depdocs_on_canc_invoice 11904 11904 0 0.00 0 28355 106 0 0
x_lock_depdocs_on_canc_sup_ord 403 403 0 0.00 0 5130 321 0 0
x_make_qstorno_1000_1200 5431 5431 0 0.00 1 25009 1142 0 0
x_make_qstorno_1200_2000 5581 5581 0 0.00 2 84283 3167 0 0
x_make_qstorno_2100_3300 479044 479044 0 0.00 0 110931 151 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 12-08-2022 01:40:55 to 12-08-2022 01:40:59.

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:26 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:27 28925 8192000000 9909903088 10219720704 984730640 10.730 100 84 167 0 1011978544 5697680 21550224
23:11:02 22673 8192000000 9953689504 10276196352 1034983408 11.216 100 90 180 0 1066029200 8201312 22844480
23:12:28 11048 8192000000 9979796720 10310995968 1063802640 11.493 100 86 172 0 1095381040 9668592 21909808
23:13:55 12538 8192000000 10045667152 10396270592 1130554064 12.127 100 79 158 0 1160104576 10768704 18781808
23:15:12 4406 8192000000 10099171184 10456547328 1184312416 12.630 100 85 166 0 1216030784 12722960 18995408
23:16:20 4442 8192000000 10148985392 10519515136 1229965136 13.054 100 85 168 1 1263007360 14066176 18976048
23:17:26 1354 8192000000 10170499264 10544607232 1257037136 13.303 100 89 177 0 1289863472 13218656 19607680
23:18:29 961 8192000000 10202077488 10586890240 1290317536 13.607 100 89 175 2 1323138864 12949936 19871392
23:19:31 4438 8192000000 10202623232 10598633472 1299391504 13.690 100 65 132 2 1324743984 10743616 14608864
23:20:36 7838 8192000000 10199243856 10607632384 1308588416 13.773 100 32 64 0 1323318224 7377808 7352000
23:21:47 11136 8192000000 10205256208 10616274944 1314735568 13.829 100 22 44 0 1325367296 5666160 4965568
23:22:58 26869 8192000000 10222133008 10630111232 1319595264 13.873 100 41 83 1 1334754272 5136976 10022032
23:24:27 6627 8192000000 10218365008 10642395136 1332937248 13.994 100 20 40 1 1343592752 6500736 4154768
23:25:35 10015 8192000000 10235677696 10653839360 1343737552 14.091 100 23 46 0 1354034944 5296944 5000448
23:26:47 53714 8192000000 10208774160 10629955584 1282561056 13.536 96 34 68 0 1296974000 6609104 7803840
23:28:41 7047 8192000000 10202407104 10616958976 1299196080 13.688 99 27 54 0 1311385920 6314432 5875408
23:29:49 8492 8192000000 10148718272 10556141568 1215213472 12.917 95 12 24 0 1222976384 4777104 2985808
23:30:59 14057 8192000000 10135774368 10532290560 1182177056 12.610 94 18 35 0 1189926576 4750064 2999456
23:32:13 27497 8192000000 10149399472 10547023872 1177395856 12.566 93 23 44 1 1191799616 9492624 4911136
23:33:42 4602 8192000000 10173079744 10576052224 1275994720 13.476 100 36 70 1 1291238272 7329600 7913952
23:34:49 5915 8192000000 10189416384 10594725888 1291500064 13.618 100 34 68 0 1304886320 5031328 8354928
23:35:56 71140 8192000000 10186119760 10601447424 1297969520 13.677 100 32 64 0 1310519264 5258224 7291520
23:38:08 5702 8192000000 10214021440 10626478080 1317801360 13.857 100 34 68 0 1332040896 7013520 7226016
23:39:22 12621 8192000000 10241350736 10651369472 1336937312 14.030 100 59 118 0 1359266880 9236336 13093232
23:40:41 26053 8192000000 10259661600 10674163712 1351895392 14.165 100 47 95 0 1373090048 10550768 10643888
23:42:16 16517 8192000000 10260040064 10682871808 1359437712 14.232 100 45 88 0 1381401184 11047968 10915504
23:43:46 24674 8192000000 10281705616 10705432576 1379242880 14.410 100 37 75 1 1400493392 12657568 8592944
23:45:16 22237 8192000000 10298632704 10723356672 1367634592 14.306 98 42 83 1 1392284352 14960192 9689568
23:46:48 8878 8192000000 10317360064 10735853568 1393621440 14.538 100 53 104 0 1426493536 20425568 12446528
23:47:59 5800 8192000000 10324161296 10744336384 1400803712 14.602 100 69 138 0 1438676784 21658560 16214512
23:49:15 16582 8192000000 10328330256 10753413120 1405513696 14.644 100 46 90 0 1444812496 28749936 10548864
23:50:37 5423 8192000000 10321180416 10736525312 1378656224 14.405 98 61 122 1 1411303616 18848960 13798432
23:51:47 8128 8192000000 10326857248 10749550592 1399876944 14.594 100 50 101 0 1439069072 26827104 12365024
23:53:02 4643 8192000000 10333601952 10748887040 1391908720 14.523 100 70 140 0 1440437216 31893568 16634928
23:54:09 2581 8192000000 10346494944 10760572928 1398898048 14.585 100 78 152 0 1449675600 32579616 18197936
23:55:16 3783 8192000000 10325436976 10735640576 1371761280 14.343 100 80 160 1 1432268848 40916112 19591456
23:56:38 7707 8192000000 10312955600 10740826112 1372253904 14.347 99 46 90 0 1424805024 41286768 11264352
23:57:53 5357 8192000000 10322140944 10734399488 1366161904 14.293 100 56 110 0 1421565152 40734784 14668464
23:59:02 1430 8192000000 10327621328 10743169024 1367896144 14.308 99 75 148 4 1425510864 38795680 18819040
00:00:07 13964 8192000000 10300929920 10724974592 1352426816 14.169 99 33 65 0 1398490944 37113888 8950240
00:01:25 14497 8192000000 10273575648 10706194432 1328377408 13.952 98 33 66 1 1371475600 33937152 9161040
00:02:45 34769 8192000000 10291400512 10717491200 1355141120 14.194 100 29 58 0 1397634928 34125856 8367952
00:04:22 8293 8192000000 10274444416 10701012992 1337893840 14.038 100 41 82 0 1384762720 35653616 11215264
00:05:34 45706 8192000000 10265572112 10710331392 1335956064 14.021 99 26 52 0 1374960048 32114800 6889184
00:07:20 54836 8192000000 10266284720 10716180480 1331206336 13.978 99 14 28 1 1363902944 28667376 4029232
00:09:40 94798 8192000000 10264025424 10706075648 1337253936 14.033 98 15 31 0 1353368880 12584736 3530208
00:12:16 88033 8192000000 10284436432 10729529344 1375139952 14.373 100 10 20 0 1389722704 12251632 2331120
00:14:45 36161 8192000000 10308206912 10743144448 1387856112 14.487 100 55 109 0 1413834384 13791488 12186784
00:16:26 78802 8192000000 10315366480 10753368064 1394454992 14.546 100 47 94 0 1418478432 12771104 11252336
00:18:46 99033 8192000000 10312500800 10756435968 1365214384 14.284 97 27 54 0 1387141040 15379456 6547200
00:21:27 26996 8192000000 10334785680 10775404544 1406112512 14.649 100 48 96 1 1435961264 17310720 12538032
00:22:56 26153 8192000000 10323640816 10786144256 1413736800 14.717 100 17 34 0 1435230832 17250896 4243136
00:24:29 45123 8192000000 10317561840 10785013760 1412716768 14.708 100 11 22 0 1434435616 19028080 2690768
00:26:15 26528 8192000000 10317788400 10770477056 1382762448 14.441 98 15 31 0 1403594992 17336528 3496016
00:27:51 42520 8192000000 10311981680 10777157632 1408918176 14.674 100 14 28 0 1424835168 12854960 3062032
00:29:34 63462 8192000000 10321463440 10789793792 1411896960 14.701 100 18 34 0 1431689664 15134544 4658160
00:31:43 41102 8192000000 10332369952 10795044864 1420207312 14.775 100 27 52 0 1437837152 11616752 6013088
00:33:28 64697 8192000000 10311739920 10771263488 1351040160 14.157 95 9 18 0 1365986272 12633648 2312464
00:35:50 77414 8192000000 10325757968 10784014336 1367277888 14.303 96 21 42 0 1392260976 20040736 4942352
00:38:08 81766 8192000000 10275533728 10721636352 1288505968 13.591 92 10 20 1 1301465872 11216592 1743312
00:40:30 42274 8192000000 10256891888 10717159424 1328667072 13.955 98 9 18 0 1351076272 20343008 2066192
00:42:14 67180 8192000000 10301610640 10732527616 1329983312 13.967 96 42 84 0 1349295824 10110544 9201968
00:44:23 89958 8192000000 10252645968 10702520320 1315345440 13.835 96 15 30 0 1327709280 8903792 3460048
00:46:53 63297 8192000000 10242406624 10681995264 1256530048 13.298 92 23 46 0 1268618544 7665040 4423456
00:49:01 25085 8192000000 10245032864 10676887552 1315684832 13.838 98 39 77 0 1336930608 12862704 8383072
00:50:27 62003 8192000000 10245457872 10704625664 1334880032 14.011 99 9 18 1 1347842448 11116032 1846384
00:52:29 54917 8192000000 10224051040 10670964736 1310224976 13.788 98 22 43 0 1325129472 10197888 4706608
00:54:42 63763 8192000000 10245342656 10698907648 1346441120 14.115 100 21 40 0 1365103232 13873712 4788400
00:56:55 55769 8192000000 10252238560 10724724736 1354071120 14.184 100 6 12 0 1366342960 10894496 1377344
00:58:53 33492 8192000000 10259998096 10713690112 1356430928 14.205 100 17 34 0 1368275840 7876592 3968320
01:00:32 41610 8192000000 10265549696 10716184576 1348287632 14.132 99 14 28 0 1365996640 14026112 3682896
01:02:36 104463 8192000000 10280506240 10736734208 1369325760 14.321 100 9 18 0 1382622800 11355936 1941104
01:06:01 94819 8192000000 10289373808 10742112256 1372612768 14.350 100 13 26 0 1388100944 12832512 2655664
01:08:36 93545 8192000000 10302287328 10769629184 1355007360 14.193 98 7 12 1 1371580000 15432848 1139792
01:11:12 94707 8192000000 10293234768 10744209408 1382454160 14.438 99 22 42 1 1406953040 19474496 5024384
01:13:49 57558 8192000000 10309832096 10785243136 1401408032 14.608 100 12 25 0 1423434240 19249072 2777136
01:16:20 86445 8192000000 10302153168 10769801216 1381777616 14.432 98 12 24 0 1397991856 13450784 2763456
01:18:47 45745 8192000000 10312389472 10772500480 1393616288 14.538 99 29 56 3 1407810448 7817504 6376656
01:20:38 28631 8192000000 10293147696 10755235840 1380552944 14.421 99 21 43 0 1395498624 9917104 5028576
01:22:08 81711 8192000000 10303255040 10768654336 1369872864 14.326 98 12 24 0 1383250752 11071312 2306576
01:24:37 192851 8192000000 10276528352 10722988032 1220113536 12.963 88 10 20 1 1232645296 10913456 1618304
01:28:53 110854 8192000000 10265242816 10710491136 1307167264 13.760 95 23 46 0 1324876448 12530368 5178816
01:31:54 49156 8192000000 10267479408 10720399360 1347397168 14.124 99 20 40 0 1370479456 18582848 4499440
01:33:43 55458 8192000000 10264497376 10720743424 1335995856 14.021 98 20 41 0 1351421152 10561680 4863616
01:35:39 39509 8192000000 10245077840 10693521408 1281188992 13.524 94 18 37 0 1298089232 12941280 3958960
01:37:20 65702 8192000000 10120723104 10561974272 1220748256 12.969 91 12 24 0 1238965072 14944240 3272576

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 12-08-2022 01:41:01 to 12-08-2022 01:41:16.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 12-08-2022 01:41:17 to 12-08-2022 01:41:19.

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          239696
MON$OLDEST_ACTIVE               243075
MON$OLDEST_SNAPSHOT             243075
MON$NEXT_TRANSACTION            243075
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                       2050848
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.33616 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33616 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33616 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33616 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 ---
:117: 
:118: mscs-test-161.budzdorov.ru    Fri Aug 12 01:38:33 2022
:119:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:120: 
:121: 
:122: mscs-test-161.budzdorov.ru    Fri Aug 12 01:38:38 2022
:123:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:124: 
:125: 
:126: mscs-test-161.budzdorov.ru    Fri Aug 12 01:38:49 2022
:127:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:128: 
:129: 
:130: mscs-test-161.budzdorov.ru    Fri Aug 12 01:38:53 2022
:131:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:132: 
:133: 
:134: mscs-test-161.budzdorov.ru    Fri Aug 12 01:38:59 2022
:135:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:136: 
--- end of diff output ---

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

12.08.22 01:41:20. There are 3 logs in /var/tmp/logs-oltp30 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2022-08-12 01:41:22 No severe PSQL-related problems occured
12.08.22 01:41:23. End of report.