Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 31.05.2022 16:38
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16039696 kB
MemAvailable 31332160 kB
Buffers 0 kB
Cached 15636508 kB
SwapCached 2440 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-31 16:38:21
DTS_END                         2022-05-31 16:38:21.9570
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/34416
TRN_ID                          247226
ATT_ID                          679
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: 9629.94 22882 1155593
customer order: creation 179.43 313 21532
customer order: refuse 15.43 313 1851
order to supplier: creation 47.98 1284 5757
order to supplier: removal 3.41 100863 409
invoice (draft): creation 48.72 3504 5846
invoice (draft): removal 54.30 766 6516
invoice accept: apply 43.63 17965 5236
invoice accept: cancel 48.86 874 5863
customer reserve: creation 4067.50 176 488100
customer reserve: removal 4811.60 56 577392
realization accept: apply 87.89 168 10547
realization accept: cancel 84.59 104 10151
payment from customer: creation 62.35 95 7482
payment from customer: removal 6.27 94 752
payment to supplier: creation 51.59 112 6191
payment to supplier: removal 3.38 94 405
service: total inventory turnovers 4.83 1279 579
service: total monetary turnovers 7.24 32 869
service: aggregate perf. data 0.93 16971 111
service: refresh index statistics 0.03 312578 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 31-05-2022 16:39:15 to 31-05-2022 16:39:17.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5736
TEST_TIME 2 6019
TEST_TIME 3 6413
TEST_TIME 4 6402
TEST_TIME 5 6699
TEST_TIME 6 6352
TEST_TIME 7 6327
TEST_TIME 8 6306
TEST_TIME 9 6616
TEST_TIME 10 6746
TEST_TIME 11 6965
TEST_TIME 12 7053
TEST_TIME 13 7446
TEST_TIME 14 7561
TEST_TIME 15 7936
TEST_TIME 16 8208
TEST_TIME 17 8541
TEST_TIME 18 8777
TEST_TIME 19 8814
TEST_TIME 20 9212
TEST_TIME 21 9027
TEST_TIME 22 9106
TEST_TIME 23 9288
TEST_TIME 24 9277
TEST_TIME 25 9224
TEST_TIME 26 9256
TEST_TIME 27 10166
TEST_TIME 28 10155
TEST_TIME 29 9997
TEST_TIME 30 10185
TEST_TIME 31 10164
TEST_TIME 32 10411
TEST_TIME 33 10576
TEST_TIME 34 10702
TEST_TIME 35 11050
TEST_TIME 36 10926
TEST_TIME 37 10828
TEST_TIME 38 10828
TEST_TIME 39 10683
TEST_TIME 40 10597
TEST_TIME 41 10574
TEST_TIME 42 10420
TEST_TIME 43 10455
TEST_TIME 44 10502
TEST_TIME 45 10499
TEST_TIME 46 10356
TEST_TIME 47 10229
TEST_TIME 48 10055
TEST_TIME 49 10145
TEST_TIME 50 10052
TEST_TIME 51 10034
TEST_TIME 52 9941
TEST_TIME 53 9971
TEST_TIME 54 9996
TEST_TIME 55 9944
TEST_TIME 56 9857
TEST_TIME 57 9828
TEST_TIME 58 9795
TEST_TIME 59 9708
TEST_TIME 60 9599
TEST_TIME 61 9576
TEST_TIME 62 9558
TEST_TIME 63 9679
TEST_TIME 64 9682
TEST_TIME 65 9722
TEST_TIME 66 9727
TEST_TIME 67 9747
TEST_TIME 68 9758
TEST_TIME 69 9755
TEST_TIME 70 9703
TEST_TIME 71 9837
TEST_TIME 72 9873
TEST_TIME 73 9831
TEST_TIME 74 9871
TEST_TIME 75 9832
TEST_TIME 76 9868
TEST_TIME 77 9837
TEST_TIME 78 9757
TEST_TIME 79 9751
TEST_TIME 80 9697
TEST_TIME 81 9637
TEST_TIME 82 9672
TEST_TIME 83 9645
TEST_TIME 84 9669
TEST_TIME 85 9589
TEST_TIME 86 9534
TEST_TIME 87 9601
TEST_TIME 88 9649
TEST_TIME 89 9602
TEST_TIME 90 9697
TEST_TIME 91 9712
TEST_TIME 92 9728
TEST_TIME 93 9698
TEST_TIME 94 9745
TEST_TIME 95 9818
TEST_TIME 96 9870
TEST_TIME 97 9901
TEST_TIME 98 9851
TEST_TIME 99 9883
TEST_TIME 100 9837
TEST_TIME 101 9805
TEST_TIME 102 9747
TEST_TIME 103 9746
TEST_TIME 104 9742
TEST_TIME 105 9712
TEST_TIME 106 9669
TEST_TIME 107 9666
TEST_TIME 108 9598
TEST_TIME 109 9584
TEST_TIME 110 9583
TEST_TIME 111 9591
TEST_TIME 112 9566
TEST_TIME 113 9620
TEST_TIME 114 9586
TEST_TIME 115 9672
TEST_TIME 116 9679
TEST_TIME 117 9682
TEST_TIME 118 9687
TEST_TIME 119 9678
TEST_TIME 120 9636

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 31-05-2022 16:39:18 to 31-05-2022 16:39:22.

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1605348 1605348 0 0.00 0 25612 6 0 0
doc_list_biud 1605348 1605348 0 0.00 0 35131 26 0 0
sp_add_invoice_to_stock 5379 5236 143 2.65 17 194380 17965 0 143
sp_cancel_adding_invoice 5863 5863 0 0.00 4 69773 875 0 0
sp_cancel_client_order 1851 1851 0 0.00 4 7021 314 0 0
sp_cancel_customer_reserve 577392 577392 0 0.00 1 22716 56 0 0
sp_cancel_pay_from_customer 752 752 0 0.00 3 6556 95 0 0
sp_cancel_pay_to_supplier 405 405 0 0.00 2 8143 95 0 0
sp_cancel_supplier_invoice 6530 6516 14 0.21 2 24701 767 0 14
sp_cancel_supplier_order 409 409 0 0.00 5 1047469 100864 0 0
sp_cancel_write_off 10151 10151 0 0.00 1 17921 104 0 0
sp_client_order 21532 21532 0 0.00 3 17062 313 0 0
sp_customer_reserve 488100 488100 0 0.00 0 99596 176 0 0
sp_fill_shopping_cart 33129 33129 0 0.00 1 16583 41 0 0
sp_fill_shopping_cart_clo_res 488090 488090 0 0.00 0 18134 12 0 0
sp_get_clo_for_invoice 5226 5226 0 0.00 0 26807 255 0 0
sp_kill_cost_storno 17171 17171 0 0.00 0 11978 59 0 0
sp_kill_qstorno_ret_qs2qd 1171860 1171860 0 0.00 0 24689 30 0 0
sp_kill_qty_storno 617955 617955 0 0.00 0 35128 67 0 0
sp_lock_selected_doc 21851 21851 0 0.00 0 8046 5 0 0
sp_make_cost_storno 29446 29446 0 0.00 0 25530 53 0 0
sp_multiply_rows_for_pdistr 29446 29446 0 0.00 0 7872 28 0 0
sp_multiply_rows_for_qdistr 21532 21532 0 0.00 0 10743 140 0 0
sp_pay_from_customer 7482 7482 0 0.00 4 10567 96 0 0
sp_pay_to_supplier 6191 6191 0 0.00 5 25722 112 0 0
sp_payment_common 13673 13673 0 0.00 1 9980 22 0 0
sp_qd_handle_on_cancel_clo 1851 1851 0 0.00 0 7001 284 0 0
sp_qd_handle_on_invoice_upd_sts 11089 11089 0 0.00 0 35119 369 0 0
sp_qd_handle_on_reserve_upd_sts 10547 10547 0 0.00 0 23530 51 0 0
sp_reserve_write_off 10547 10547 0 0.00 6 23605 169 0 0
sp_supplier_invoice 5846 5846 0 0.00 6 94530 3504 0 0
sp_supplier_order 5757 5757 0 0.00 6 26526 1285 0 0
srv_aggregate_perf_data 111 111 0 0.00 3056 136778 16972 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 2939 124644 40992 0 0
srv_find_qd_qs_mism 1104239 1104239 0 0.00 0 8659 6 0 0
srv_make_invnt_saldo 579 579 0 0.00 36 138760 1279 0 0
srv_make_money_saldo 869 869 0 0.00 1 5682 33 0 0
srv_recalc_idx_stat 4 4 0 0.00 104876 532431 312579 0 0
t$perf-abend:sp_add_invoice_to_stock 143 143 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 5237 5237 0 0.00 0 8394 159 0 0
t$perf-norm:sp_cancel_adding_invoice 438 438 0 0.00 1 7613 127 0 0
t$perf-norm:sp_cancel_client_order 1851 1851 0 0.00 0 4114 15 0 0
t$perf-norm:sp_cancel_customer_reserve 732 732 0 0.00 0 783 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 752 752 0 0.00 0 659 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 405 405 0 0.00 0 1734 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 445 445 0 0.00 0 719 13 0 0
t$perf-norm:sp_cancel_supplier_order 409 409 0 0.00 0 19393 1653 0 0
t$perf-norm:sp_cancel_write_off 1046 1046 0 0.00 0 1242 8 0 0
t$perf-norm:sp_client_order 21532 21532 0 0.00 0 33490 19 0 0
t$perf-norm:sp_customer_reserve 1114 1114 0 0.00 0 1182 5 0 0
t$perf-norm:sp_pay_from_customer 7482 7482 0 0.00 0 3317 8 0 0
t$perf-norm:sp_pay_to_supplier 6191 6191 0 0.00 0 4246 8 0 0
t$perf-norm:sp_reserve_write_off 10547 10547 0 0.00 0 15265 9 0 0
t$perf-norm:sp_supplier_invoice 5846 5846 0 0.00 0 6243 16 0 0
t$perf-norm:sp_supplier_order 5757 5757 0 0.00 0 3662 15 0 0
t$perf-norm:srv_aggregate_perf_data 111 111 0 0.00 0 2403 37 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 9249 141 0 0
t$perf-norm:srv_make_invnt_saldo 579 579 0 0.00 0 963 21 0 0
t$perf-norm:srv_make_money_saldo 869 869 0 0.00 0 2231 20 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 4 3 0 0
v_add_invoice_to_stock 10738 10452 286 2.66 0 5805 17 0 286
v_all_customers 39188 39188 0 0.00 0 32 1 0 0
v_all_suppliers 32112 32112 0 0.00 0 27 1 0 0
v_all_wares 43064 43064 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 876 876 0 0.00 0 617 7 0 0
v_cancel_client_order 3702 3702 0 0.00 0 2702 5 0 0
v_cancel_customer_prepayment 1504 1504 0 0.00 0 3035 5 0 0
v_cancel_customer_reserve 1464 1464 0 0.00 0 2497 8 0 0
v_cancel_payment_to_supplier 810 810 0 0.00 0 333 3 0 0
v_cancel_supplier_invoice 918 890 28 3.05 0 483 14 0 28
v_cancel_supplier_order 818 818 0 0.00 0 1833 6 0 0
v_cancel_write_off 2092 2092 0 0.00 0 6544 15 0 0
v_max_id_clo_ord 5757 5757 0 0.00 0 3294 2 0 0
v_max_id_clo_res 1114 1114 0 0.00 0 2072 12 0 0
v_max_id_ord_sup 5840 5840 0 0.00 0 4644 4 0 0
v_max_non_paid_invoice 1732 1732 0 0.00 0 416 3 0 0
v_max_non_paid_realizn 4018 4018 0 0.00 0 4356 5 0 0
v_min_id_clo_ord 5757 5757 0 0.00 0 5608 8 0 0
v_min_id_clo_res 1114 1114 0 0.00 0 4916 14 0 0
v_min_id_ord_sup 5840 5840 0 0.00 0 5225 7 0 0
v_min_non_paid_invoice 6191 6191 0 0.00 0 5831 12 0 0
v_min_non_paid_realizn 7482 7482 0 0.00 0 9976 18 0 0
v_our_firm 10804 10804 0 0.00 0 19 1 0 0
v_reserve_write_off 21094 21094 0 0.00 0 6094 6 0 0
x_lock_depdocs_on_canc_invoice 11934 11934 0 0.00 0 25201 104 0 0
x_lock_depdocs_on_canc_sup_ord 409 409 0 0.00 0 3094 336 0 0
x_make_qstorno_1000_1200 5757 5757 0 0.00 1 26518 1193 0 0
x_make_qstorno_1200_2000 5846 5846 0 0.00 2 94433 3364 0 0
x_make_qstorno_2100_3300 488100 488100 0 0.00 0 99595 162 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 31-05-2022 16:39:23 to 31-05-2022 16:39:27.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
14:08:25 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:09:26 24628 8192000000 9906946512 10218385408 987279904 10.755 100 89 178 0 1016792960 6504544 23008512
14:10:57 15597 8192000000 9957872368 10285473792 1037419856 11.240 100 81 162 0 1066837824 8802928 20615040
14:12:22 10186 8192000000 9998904752 10335125504 1082185984 11.668 100 84 167 0 1113371856 10842576 20343296
14:13:36 12453 8192000000 10029285664 10375340032 1113415520 11.965 100 87 174 1 1147878992 12970432 21493040
14:14:51 10220 8192000000 10071670208 10431393792 1156231360 12.368 100 76 152 1 1186013936 11979648 17802928
14:16:03 6028 8192000000 10117512032 10484899840 1205584048 12.828 100 84 166 1 1237647232 13937872 18125312
14:17:13 3699 8192000000 10149208400 10522537984 1240539264 13.151 100 81 162 0 1271775952 12883536 18353152
14:18:19 1530 8192000000 10190628176 10572574720 1274970800 13.467 100 90 180 0 1308735472 13781136 19983536
14:19:21 27793 8192000000 10192095568 10590097408 1290036368 13.605 100 61 122 0 1315901600 12515024 13350208
14:20:49 10984 8192000000 10187637984 10598830080 1299595312 13.692 100 24 48 0 1311762352 6925024 5242016
14:22:01 13410 8192000000 10190275296 10610528256 1306359008 13.753 100 13 25 1 1314512192 5650256 2502928
14:23:15 52798 8192000000 10202243104 10621779968 1311837136 13.803 100 30 61 1 1324731024 5279104 7614784
14:25:09 2491 8192000000 10232146816 10643447808 1322911232 13.903 100 42 82 3 1343954320 11989760 9053328
14:26:13 8476 8192000000 10218129872 10634870784 1311760416 13.802 99 24 48 1 1327024976 10115296 5149264
14:27:22 17051 8192000000 10199328832 10612838400 1285584400 13.564 97 15 30 1 1294275232 5923920 2766912
14:29:03 7328 8192000000 10199594272 10620616704 1310113360 13.787 100 30 60 0 1324039312 7182368 6743584
14:30:13 4234 8192000000 10167042576 10574954496 1254319360 13.278 98 29 58 0 1267178608 5993472 6865776
14:31:20 8939 8192000000 10135734416 10536079360 1200303264 12.779 96 14 27 1 1212864704 9612592 2948848
14:32:33 46736 8192000000 10117699152 10515374080 1208445984 12.855 98 36 70 2 1222090544 5080480 8564080
14:34:25 10141 8192000000 10165826368 10571669504 1263198512 13.359 99 29 58 0 1275605424 5710496 6696416
14:35:35 8072 8192000000 10182338688 10597363712 1292637072 13.628 100 21 42 0 1303734368 6373600 4723696
14:36:48 54446 8192000000 10202261152 10614259712 1303649936 13.728 100 25 50 1 1314890496 5489136 5751424
14:38:50 35374 8192000000 10250454800 10661462016 1341691680 14.073 100 54 106 2 1362929280 8697424 12540176
14:40:28 26773 8192000000 10267201904 10682265600 1357800768 14.218 100 54 108 1 1381229616 11469856 11958992
14:41:58 12690 8192000000 10283600048 10703171584 1373227904 14.356 100 48 94 1 1395028800 10768336 11032560
14:43:14 7730 8192000000 10294161664 10711764992 1379333040 14.411 100 49 97 1 1402231632 11742976 11155616
14:44:24 8568 8192000000 10301110832 10721345536 1386214448 14.472 100 64 128 2 1412779360 10739744 15825168
14:45:38 5224 8192000000 10313793872 10735480832 1400770096 14.602 100 62 124 1 1428617392 13580432 14266864
14:46:47 7097 8192000000 10332380704 10756624384 1408609536 14.672 100 59 114 2 1438068384 15352272 14106576
14:47:58 15199 8192000000 10337959664 10767368192 1411287008 14.695 100 62 123 0 1442328800 16336768 14705024
14:49:17 5148 8192000000 10348837696 10775638016 1420820624 14.780 100 60 117 0 1454884976 19239920 14824432
14:50:27 12307 8192000000 10360904832 10784325632 1424265856 14.811 100 74 148 2 1466179536 24212800 17700880
14:51:46 5094 8192000000 10356857600 10775027712 1421868976 14.789 100 79 157 2 1465532528 24829888 18833664
14:52:58 3174 8192000000 10345130480 10761109504 1399479328 14.590 100 81 159 1 1448224256 29522352 19222576
14:54:08 3029 8192000000 10337274496 10751774720 1373762640 14.361 98 83 166 2 1423477232 29159792 20554800
14:55:16 2938 8192000000 10324933488 10733957120 1359851472 14.236 98 73 143 1 1407939936 29841456 18247008
14:56:24 7919 8192000000 10291224032 10713866240 1352665008 14.171 98 33 66 1 1385715136 24677056 8373072
14:57:38 3784 8192000000 10317806288 10731945984 1372767856 14.352 100 67 134 1 1423471696 33784640 16919200
14:58:52 10739 8192000000 10294806112 10711638016 1340487088 14.062 98 35 70 2 1379737568 29720288 9530192
15:00:15 8713 8192000000 10307557888 10721890304 1364644880 14.279 100 52 102 0 1412836112 35157648 13033584
15:01:46 22069 8192000000 10311700560 10737553408 1370676752 14.333 100 27 54 1 1418687968 40355440 7655776
15:03:16 10478 8192000000 10317108816 10742681600 1365474656 14.286 100 27 54 0 1415370032 42311552 7583824
15:04:31 7673 8192000000 10343587136 10766020608 1374410080 14.367 99 43 85 1 1441833792 55865232 11558480
15:05:52 24442 8192000000 10312489120 10744147968 1376407632 14.384 100 25 50 2 1427032864 43549088 7076144
15:07:25 25905 8192000000 10331716384 10763825152 1380470800 14.421 100 29 59 2 1433833200 45806240 7556160
15:08:57 27778 8192000000 10318950256 10749542400 1377135152 14.391 99 38 75 1 1422690320 36495328 9059840
15:10:27 27653 8192000000 10316216224 10769399808 1384965248 14.461 100 20 40 0 1429324784 39118640 5240896
15:11:58 19000 8192000000 10309206288 10760245248 1377551536 14.395 100 12 24 1 1410525968 29989040 2985392
15:13:17 52407 8192000000 10286638464 10730332160 1374734800 14.369 100 13 26 0 1400018784 22221904 3062080
15:15:10 56114 8192000000 10294488448 10735710208 1364390192 14.277 98 11 22 0 1377570288 10810736 2369360
15:17:07 102750 8192000000 10300588128 10751803392 1354417296 14.187 97 16 32 1 1369583696 11868240 3298160
15:20:19 23019 8192000000 10336649776 10762334208 1398347664 14.580 100 59 118 2 1421791968 9424176 14020128
15:21:46 52812 8192000000 10317146624 10767953920 1388469872 14.492 99 10 20 0 1401860400 11360416 2030112
15:23:39 118491 8192000000 10313875024 10763214848 1375207728 14.374 98 16 32 0 1399680944 20498784 3974432
15:27:21 13091 8192000000 10341140048 10774097920 1405400704 14.643 100 70 139 1 1432379472 11873024 15105744
15:28:37 70366 8192000000 10320068016 10782568448 1407091744 14.658 100 12 24 1 1421160912 11227600 2841568
15:30:50 61230 8192000000 10310441552 10767818752 1381049136 14.426 98 37 74 0 1402551840 12526976 8975728
15:32:55 124644 8192000000 10276448192 10734325760 1316444000 13.844 94 10 20 0 1325778656 7198640 2136016
15:36:08 40557 8192000000 10270030464 10740027392 1341612784 14.072 97 7 14 1 1356681312 13235360 1833168
15:37:48 68983 8192000000 10279260784 10723033088 1337109568 14.031 97 34 67 0 1350869680 7056112 6704000
15:39:58 58835 8192000000 10276245712 10738614272 1356422912 14.205 98 10 20 0 1372047472 13247056 2377504
15:41:58 48949 8192000000 10283699872 10729811968 1294159392 13.642 94 20 40 1 1311582480 13598464 3824624
15:43:51 44197 8192000000 10266058032 10708484096 1326016080 13.931 98 24 48 1 1343509584 12128528 5364976
15:45:39 46959 8192000000 10229834816 10672934912 1261786944 13.346 93 16 32 1 1277779888 11934128 4058816
15:47:29 39845 8192000000 10253735680 10705326080 1335986656 14.021 99 19 38 1 1353724352 13483232 4254464
15:49:36 71981 8192000000 10259978704 10708541440 1329412240 13.962 99 16 32 1 1354504896 21795360 3297296
15:51:48 72997 8192000000 10262363472 10705514496 1315329392 13.834 97 16 32 0 1330411840 11032864 4049584
15:54:01 98807 8192000000 10269949936 10723950592 1360459040 14.241 100 24 48 0 1381756176 16167792 5129344
15:57:16 48942 8192000000 10270525008 10723934208 1362803216 14.263 100 19 38 0 1384441504 17214064 4424224
15:59:07 61474 8192000000 10265793072 10717696000 1351543536 14.161 99 14 28 0 1370956800 16325120 3088144
16:01:09 60495 8192000000 10249241152 10700951552 1331670176 13.982 98 17 34 1 1357282160 21417888 4194096
16:03:26 74798 8192000000 10261568672 10719866880 1335535936 14.017 98 12 24 1 1349196176 11124864 2535376
16:06:47 48433 8192000000 10293955888 10734374912 1369790912 14.325 99 19 38 1 1384903920 10649264 4463744
16:08:35 67970 8192000000 10289367392 10751291392 1372370032 14.348 99 12 24 0 1388441456 13694320 2377104
16:11:09 33795 8192000000 10301131216 10752466944 1383752560 14.450 99 22 44 1 1395995200 7778528 4464112
16:12:59 96995 8192000000 10309475712 10767261696 1371821520 14.343 98 9 16 2 1392048624 17981488 2245616
16:15:38 60600 8192000000 10310515520 10749431808 1319397568 13.871 94 35 70 3 1339322800 11511296 8413936
16:17:41 36150 8192000000 10297704704 10762919936 1393816144 14.540 100 33 66 3 1413274144 12300816 7157184
16:19:18 92624 8192000000 10293542656 10753720320 1359021120 14.229 97 15 30 2 1374268000 11675728 3571152
16:21:52 44962 8192000000 10274571072 10742923264 1351155696 14.158 97 10 20 2 1363070512 9652128 2262688
16:23:43 58164 8192000000 10298634432 10751909888 1375811936 14.379 99 15 30 0 1386748448 7508768 3427744
16:25:44 47513 8192000000 10306702560 10775273472 1400510160 14.600 100 15 30 0 1420950480 17221216 3219104
16:27:58 74758 8192000000 10287223136 10748665856 1353741360 14.181 97 6 12 0 1363434720 8533328 1160032
16:30:13 41177 8192000000 10275724848 10728554496 1340423680 14.061 97 22 43 1 1356786288 11439856 4922752
16:31:56 44912 8192000000 10292417984 10747265024 1358045456 14.220 98 21 42 0 1377924896 15211904 4667536
16:33:41 26932 8192000000 10299992784 10745479168 1358723488 14.226 98 30 60 2 1376534992 10547184 7264320
16:35:09 45654 8192000000 10275402000 10725715968 1296905680 13.667 94 23 46 0 1316094800 14204560 4984560
16:36:56 25493 8192000000 10268560576 10711076864 1341860736 14.074 99 34 69 2 1360656560 11404032 7391792

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 31-05-2022 16:39:28 to 31-05-2022 16:39:43.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 143 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 31-05-2022 16:39:44 to 31-05-2022 16:39:46.

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          242372
MON$OLDEST_ACTIVE               247343
MON$OLDEST_SNAPSHOT             247343
MON$NEXT_TRANSACTION            247343
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                       2068960
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.33597 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33597 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33597 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33597 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 ---
:242: 
:243: mscs-test-161.budzdorov.ru    Tue May 31 16:38:23 2022
:244:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:245: 
:246: 
:247: mscs-test-161.budzdorov.ru    Tue May 31 16:38:23 2022
:248:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:249: 
:250: 
:251: mscs-test-161.budzdorov.ru    Tue May 31 16:38:25 2022
:252:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:253: 
:254: 
:255: mscs-test-161.budzdorov.ru    Tue May 31 16:38:29 2022
:256:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:257: 
:258: 
:259: mscs-test-161.budzdorov.ru    Tue May 31 16:38:31 2022
:260:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:261: 
--- end of diff output ---

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

31.05.22 16:39:47. 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-31 16:39:50 No severe PSQL-related problems occured
31.05.22 16:39:51. End of report.