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

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-06-27 16:38:21
DTS_END                         2022-06-27 16:38:21.7230
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/53916
TRN_ID                          245842
ATT_ID                          649
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: 9402.33 19195 1128279
customer order: creation 177.78 309 21334
customer order: refuse 14.73 346 1768
order to supplier: creation 47.71 1216 5725
order to supplier: removal 3.53 97896 423
invoice (draft): creation 47.97 3318 5756
invoice (draft): removal 54.75 811 6570
invoice accept: apply 43.58 16766 5229
invoice accept: cancel 49.48 760 5937
customer reserve: creation 3913.83 171 469659
customer reserve: removal 4741.48 56 568978
realization accept: apply 85.16 192 10219
realization accept: cancel 86.80 103 10416
payment from customer: creation 61.38 124 7365
payment from customer: removal 6.02 93 722
payment to supplier: creation 51.70 130 6204
payment to supplier: removal 3.62 51 434
service: total inventory turnovers 4.88 1157 586
service: total monetary turnovers 6.92 22 830
service: aggregate perf. data 1.00 15331 120
service: refresh index statistics 0.03 245057 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 27-06-2022 16:40:16 to 27-06-2022 16:40:18.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4724
TEST_TIME 2 4092
TEST_TIME 3 4736
TEST_TIME 4 4731
TEST_TIME 5 4819
TEST_TIME 6 4781
TEST_TIME 7 5451
TEST_TIME 8 5347
TEST_TIME 9 5682
TEST_TIME 10 5801
TEST_TIME 11 6057
TEST_TIME 12 6103
TEST_TIME 13 6083
TEST_TIME 14 6248
TEST_TIME 15 6243
TEST_TIME 16 6707
TEST_TIME 17 7063
TEST_TIME 18 7237
TEST_TIME 19 7436
TEST_TIME 20 7757
TEST_TIME 21 8331
TEST_TIME 22 8625
TEST_TIME 23 9291
TEST_TIME 24 9539
TEST_TIME 25 9875
TEST_TIME 26 10120
TEST_TIME 27 10444
TEST_TIME 28 10843
TEST_TIME 29 10699
TEST_TIME 30 10594
TEST_TIME 31 10543
TEST_TIME 32 10560
TEST_TIME 33 10461
TEST_TIME 34 10506
TEST_TIME 35 10529
TEST_TIME 36 10425
TEST_TIME 37 10214
TEST_TIME 38 10165
TEST_TIME 39 10145
TEST_TIME 40 10320
TEST_TIME 41 10142
TEST_TIME 42 10062
TEST_TIME 43 9982
TEST_TIME 44 9895
TEST_TIME 45 9865
TEST_TIME 46 9968
TEST_TIME 47 10046
TEST_TIME 48 10084
TEST_TIME 49 9972
TEST_TIME 50 9879
TEST_TIME 51 9924
TEST_TIME 52 9924
TEST_TIME 53 9923
TEST_TIME 54 9944
TEST_TIME 55 9940
TEST_TIME 56 9932
TEST_TIME 57 10007
TEST_TIME 58 9969
TEST_TIME 59 9908
TEST_TIME 60 10026
TEST_TIME 61 10027
TEST_TIME 62 10019
TEST_TIME 63 10039
TEST_TIME 64 10120
TEST_TIME 65 10131
TEST_TIME 66 10054
TEST_TIME 67 10009
TEST_TIME 68 9932
TEST_TIME 69 9908
TEST_TIME 70 9914
TEST_TIME 71 9851
TEST_TIME 72 9867
TEST_TIME 73 9802
TEST_TIME 74 9835
TEST_TIME 75 9780
TEST_TIME 76 9744
TEST_TIME 77 9683
TEST_TIME 78 9640
TEST_TIME 79 9596
TEST_TIME 80 9591
TEST_TIME 81 9601
TEST_TIME 82 9551
TEST_TIME 83 9546
TEST_TIME 84 9564
TEST_TIME 85 9635
TEST_TIME 86 9649
TEST_TIME 87 9633
TEST_TIME 88 9608
TEST_TIME 89 9647
TEST_TIME 90 9686
TEST_TIME 91 9690
TEST_TIME 92 9666
TEST_TIME 93 9764
TEST_TIME 94 9698
TEST_TIME 95 9623
TEST_TIME 96 9625
TEST_TIME 97 9644
TEST_TIME 98 9616
TEST_TIME 99 9598
TEST_TIME 100 9547
TEST_TIME 101 9524
TEST_TIME 102 9514
TEST_TIME 103 9472
TEST_TIME 104 9518
TEST_TIME 105 9491
TEST_TIME 106 9519
TEST_TIME 107 9487
TEST_TIME 108 9509
TEST_TIME 109 9550
TEST_TIME 110 9540
TEST_TIME 111 9502
TEST_TIME 112 9596
TEST_TIME 113 9585
TEST_TIME 114 9560
TEST_TIME 115 9581
TEST_TIME 116 9566
TEST_TIME 117 9567
TEST_TIME 118 9497
TEST_TIME 119 9463
TEST_TIME 120 9408

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 27-06-2022 16:40:19 to 27-06-2022 16:40:23.

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 1564785 1564785 0 0.00 0 19188 6 0 0
doc_list_biud 1564783 1564783 0 0.00 0 72045 26 0 0
sp_add_invoice_to_stock 5379 5229 150 2.78 50 159930 16766 0 150
sp_cancel_adding_invoice 5937 5937 0 0.00 5 80764 761 0 0
sp_cancel_client_order 1768 1768 0 0.00 4 7679 346 0 0
sp_cancel_customer_reserve 568978 568978 0 0.00 1 26326 56 0 0
sp_cancel_pay_from_customer 722 722 0 0.00 3 7836 94 0 0
sp_cancel_pay_to_supplier 434 434 0 0.00 3 2175 51 0 0
sp_cancel_supplier_invoice 6578 6570 8 0.12 2 72140 811 0 8
sp_cancel_supplier_order 423 423 0 0.00 6 1032054 97897 0 0
sp_cancel_write_off 10416 10416 0 0.00 1 8490 103 0 0
sp_client_order 21334 21334 0 0.00 3 34555 309 0 0
sp_customer_reserve 469659 469659 0 0.00 0 37151 171 0 0
sp_fill_shopping_cart 32799 32799 0 0.00 1 20398 36 0 0
sp_fill_shopping_cart_clo_res 469655 469655 0 0.00 0 14028 12 0 0
sp_get_clo_for_invoice 5223 5223 0 0.00 0 16712 262 0 0
sp_kill_cost_storno 17509 17509 0 0.00 0 7821 50 0 0
sp_kill_qstorno_ret_qs2qd 1155365 1155365 0 0.00 0 72043 31 0 0
sp_kill_qty_storno 609533 609533 0 0.00 0 72045 67 0 0
sp_lock_selected_doc 21295 21295 0 0.00 0 8935 6 0 0
sp_make_cost_storno 29010 29010 0 0.00 0 19184 57 0 0
sp_multiply_rows_for_pdistr 29010 29010 0 0.00 0 7022 29 0 0
sp_multiply_rows_for_qdistr 21334 21334 0 0.00 0 16762 138 0 0
sp_pay_from_customer 7365 7365 0 0.00 4 14395 125 0 0
sp_pay_to_supplier 6204 6204 0 0.00 5 19194 130 0 0
sp_payment_common 13569 13569 0 0.00 1 8395 32 0 0
sp_qd_handle_on_cancel_clo 1768 1768 0 0.00 0 7669 316 0 0
sp_qd_handle_on_invoice_upd_sts 11159 11159 0 0.00 0 25727 317 0 0
sp_qd_handle_on_reserve_upd_sts 10219 10219 0 0.00 0 26867 63 0 0
sp_reserve_write_off 10219 10219 0 0.00 6 26888 192 0 0
sp_supplier_invoice 5756 5756 0 0.00 7 90161 3318 0 0
sp_supplier_order 5725 5725 0 0.00 5 21894 1217 0 0
srv_aggregate_perf_data 120 120 0 0.00 1948 149346 15331 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1631 127710 45389 0 0
srv_find_qd_qs_mism 1079804 1079804 0 0.00 0 12664 5 0 0
srv_make_invnt_saldo 586 586 0 0.00 42 89934 1157 0 0
srv_make_money_saldo 830 830 0 0.00 0 1406 22 0 0
srv_recalc_idx_stat 4 4 0 0.00 123788 333854 245057 0 0
t$perf-abend:sp_add_invoice_to_stock 150 150 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5230 5230 0 0.00 0 8787 154 0 0
t$perf-norm:sp_cancel_adding_invoice 433 433 0 0.00 0 5606 101 0 0
t$perf-norm:sp_cancel_client_order 1768 1768 0 0.00 0 1905 12 0 0
t$perf-norm:sp_cancel_customer_reserve 657 657 0 0.00 0 1533 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 722 722 0 0.00 0 1815 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 434 434 0 0.00 0 538 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 437 437 0 0.00 0 1061 14 0 0
t$perf-norm:sp_cancel_supplier_order 423 423 0 0.00 0 30419 1674 0 0
t$perf-norm:sp_cancel_write_off 980 980 0 0.00 0 4084 10 0 0
t$perf-norm:sp_client_order 21334 21334 0 0.00 0 6132 16 0 0
t$perf-norm:sp_customer_reserve 1071 1071 0 0.00 0 2338 9 0 0
t$perf-norm:sp_pay_from_customer 7365 7365 0 0.00 0 3997 9 0 0
t$perf-norm:sp_pay_to_supplier 6204 6204 0 0.00 0 3676 8 0 0
t$perf-norm:sp_reserve_write_off 10219 10219 0 0.00 0 6100 9 0 0
t$perf-norm:sp_supplier_invoice 5756 5756 0 0.00 0 4391 17 0 0
t$perf-norm:sp_supplier_order 5725 5725 0 0.00 0 6764 18 0 0
t$perf-norm:srv_aggregate_perf_data 120 120 0 0.00 0 417 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 515 11 0 0
t$perf-norm:srv_make_invnt_saldo 586 586 0 0.00 0 1086 21 0 0
t$perf-norm:srv_make_money_saldo 830 830 0 0.00 0 2907 22 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 10744 10444 300 2.79 0 4904 16 0 300
v_all_customers 38580 38580 0 0.00 0 20 1 0 0
v_all_suppliers 31628 31628 0 0.00 0 18 1 0 0
v_all_wares 42668 42668 0 0.00 0 96 1 0 0
v_cancel_adding_invoice 866 866 0 0.00 0 2061 10 0 0
v_cancel_client_order 3536 3536 0 0.00 0 1469 4 0 0
v_cancel_customer_prepayment 1444 1444 0 0.00 0 6390 7 0 0
v_cancel_customer_reserve 1314 1314 0 0.00 0 694 4 0 0
v_cancel_payment_to_supplier 868 868 0 0.00 0 2163 5 0 0
v_cancel_supplier_invoice 890 874 16 1.79 0 574 14 0 16
v_cancel_supplier_order 846 846 0 0.00 0 1570 5 0 0
v_cancel_write_off 1960 1960 0 0.00 0 6510 18 0 0
v_max_id_clo_ord 5715 5715 0 0.00 0 5085 2 0 0
v_max_id_clo_res 1071 1071 0 0.00 0 3028 14 0 0
v_max_id_ord_sup 5750 5750 0 0.00 0 6918 4 0 0
v_max_non_paid_invoice 1855 1855 0 0.00 0 6444 6 0 0
v_max_non_paid_realizn 4015 4015 0 0.00 0 2192 4 0 0
v_min_id_clo_ord 5715 5715 0 0.00 0 5046 10 0 0
v_min_id_clo_res 1071 1071 0 0.00 0 2345 5 0 0
v_min_id_ord_sup 5750 5750 0 0.00 0 11400 7 0 0
v_min_non_paid_invoice 6204 6204 0 0.00 0 6384 20 0 0
v_min_non_paid_realizn 7365 7365 0 0.00 0 6889 27 0 0
v_our_firm 10788 10788 0 0.00 0 18 1 0 0
v_reserve_write_off 20438 20438 0 0.00 0 4589 5 0 0
x_lock_depdocs_on_canc_invoice 12070 12070 0 0.00 0 20150 123 0 0
x_lock_depdocs_on_canc_sup_ord 423 423 0 0.00 0 9962 396 0 0
x_make_qstorno_1000_1200 5725 5725 0 0.00 2 21845 1134 0 0
x_make_qstorno_1200_2000 5756 5756 0 0.00 3 88405 3194 0 0
x_make_qstorno_2100_3300 469659 469659 0 0.00 0 37147 156 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 27-06-2022 16:40:24 to 27-06-2022 16:40:28.

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 15005 8192000000 9900970912 10211549184 987438736 10.757 100 87 174 0 1015709808 5576304 22694768
14:10:46 17246 8192000000 9956714368 10280439808 1036837152 11.234 100 84 168 0 1066386096 8291168 21257776
14:12:16 10158 8192000000 9983406192 10319175680 1074425072 11.594 100 76 152 0 1102374528 9384992 18564464
14:13:34 17019 8192000000 10039964192 10385092608 1126254624 12.086 100 92 184 0 1159115072 10805472 22054976
14:14:54 8870 8192000000 10079774064 10436751360 1166972160 12.469 100 85 170 1 1199484176 13107968 19404048
14:16:07 6251 8192000000 10124709680 10489298944 1210147392 12.870 100 78 152 1 1240556304 12957264 17451648
14:17:16 3536 8192000000 10164866880 10535239680 1245120256 13.193 100 91 182 1 1279776656 14194656 20461744
14:18:21 1429 8192000000 10204241328 10584555520 1280680192 13.519 100 94 189 1 1315765856 13915776 21169888
14:19:24 40321 8192000000 10209139472 10598625280 1296009632 13.659 100 58 116 0 1318702960 8852832 13840496
14:21:05 6052 8192000000 10197381040 10604740608 1307661472 13.765 100 32 64 1 1321935424 6961616 7312336
14:22:12 6903 8192000000 10201705872 10616410112 1315400160 13.835 100 23 46 0 1327757392 6729936 5627296
14:23:20 8528 8192000000 10207562144 10624131072 1317671056 13.856 100 26 52 1 1330066656 6362944 6032656
14:24:29 60364 8192000000 10242157568 10644369408 1327067648 13.941 100 50 101 0 1344245472 5000944 12176880
14:26:30 4254 8192000000 10232902544 10648612864 1326336000 13.934 99 44 88 1 1344461712 7894288 10231424
14:27:34 47090 8192000000 10213568448 10635747328 1301677520 13.710 97 35 70 0 1316009344 6222976 8108848
14:29:23 4792 8192000000 10187897408 10592423936 1278877248 13.503 99 43 86 4 1296463216 7888864 9697104
14:30:31 11040 8192000000 10139609920 10543996928 1176882304 12.561 92 16 32 0 1189820400 9131824 3806272
14:31:42 49633 8192000000 10147629792 10536882176 1205511360 12.827 96 44 89 2 1222782544 6700000 10571184
14:33:35 4939 8192000000 10166018096 10570612736 1269723776 13.419 100 38 76 0 1285638320 7085648 8828896
14:34:40 8447 8192000000 10171457584 10577428480 1275312768 13.470 100 30 60 3 1292094608 9967872 6813968
14:35:49 12944 8192000000 10198316656 10603712512 1297982416 13.677 100 29 58 3 1310598896 5301328 7315152
14:37:03 6102 8192000000 10183437056 10602020864 1297217840 13.670 100 23 46 0 1308308672 5999104 5091728
14:38:12 9449 8192000000 10212403216 10626740224 1318115184 13.860 100 37 72 1 1332864064 6217408 8531472
14:39:28 11873 8192000000 10246794496 10659106816 1341938992 14.075 100 49 98 0 1360730048 7131600 11659456
14:40:45 28744 8192000000 10266762032 10682355712 1359585168 14.234 100 49 98 1 1382126656 11033152 11508336
14:42:35 18960 8192000000 10295037136 10715353088 1377935184 14.398 100 47 94 0 1404822448 15295280 11591984
14:44:27 9477 8192000000 10307805184 10732453888 1394064672 14.542 100 49 96 0 1423473040 17590768 11817600
14:45:43 9310 8192000000 10315523680 10746413056 1398753744 14.584 100 41 81 1 1432348112 23950544 9643824
14:46:57 5677 8192000000 10346673984 10775482368 1410138000 14.685 100 43 86 0 1457471792 36567584 10766208
14:48:05 7997 8192000000 10347534384 10773155840 1400437072 14.599 99 45 90 3 1443790672 32796320 10557280
14:49:17 6692 8192000000 10354873376 10778963968 1413616400 14.716 100 54 108 0 1463843840 36670016 13557424
14:50:32 12509 8192000000 10340081024 10764361728 1401601216 14.609 100 55 110 0 1452262560 36724560 13936784
14:51:55 4146 8192000000 10383293504 10799853568 1423655856 14.805 100 73 144 1 1488018208 45990160 18372192
14:53:03 2978 8192000000 10350934000 10759069696 1383618720 14.449 100 83 167 2 1455967536 51557472 20791344
14:54:11 5474 8192000000 10347798352 10761945088 1377627488 14.395 99 83 165 3 1452871152 53700528 21543136
14:55:21 1629 8192000000 10368979776 10771001344 1393318672 14.535 100 88 174 0 1467470272 52657312 21494288
14:56:29 1908 8192000000 10366061328 10776899584 1398464432 14.581 100 82 164 1 1469774480 50324208 20985840
14:57:37 16342 8192000000 10370121648 10797445120 1404721040 14.637 100 39 78 1 1474753008 58568064 11463904
14:58:57 8304 8192000000 10361972480 10785415168 1378848544 14.406 99 55 110 2 1456051648 61967456 15235648
15:00:13 6524 8192000000 10353861344 10786086912 1380776208 14.423 99 43 86 2 1450505264 57789872 11939184
15:01:22 15135 8192000000 10336962256 10779430912 1391294400 14.517 100 40 78 4 1456109552 54037568 10777584
15:02:53 13984 8192000000 10327609536 10772557824 1391098448 14.516 100 32 64 1 1440646624 41000080 8548096
15:04:22 41653 8192000000 10301503200 10749976576 1345952416 14.111 98 18 36 0 1378112864 27068864 5091584
15:06:40 78266 8192000000 10279497904 10723397632 1341155440 14.068 98 19 36 2 1357585072 12002400 4427232
15:09:10 87614 8192000000 10299458848 10731495424 1361931168 14.255 98 22 44 0 1377841792 11112000 4798624
15:11:45 51422 8192000000 10306432528 10762641408 1389097712 14.498 99 10 20 0 1403336864 12357648 1881504
15:13:39 56142 8192000000 10310920016 10752253952 1387471760 14.483 99 34 68 4 1405629216 10197792 7959664
15:15:36 96634 8192000000 10324077392 10778050560 1403249552 14.624 100 43 85 1 1423540208 10689776 9600880
15:18:19 15548 8192000000 10329964000 10778595328 1407338016 14.660 100 44 88 0 1432106992 14835664 9933312
15:19:40 13588 8192000000 10292244896 10750140416 1362751792 14.262 97 7 14 0 1372967568 8705424 1510352
15:20:54 29106 8192000000 10311435264 10760159232 1384513408 14.457 99 23 47 0 1400963552 10841616 5608528
15:22:29 48426 8192000000 10327704720 10772525056 1384614752 14.458 99 28 56 1 1408880592 17046176 7219664
15:24:20 47946 8192000000 10310106544 10777501696 1397218176 14.570 100 9 18 1 1418745120 19164576 2362368
15:26:13 23623 8192000000 10299667520 10756857856 1380153616 14.418 99 17 34 1 1407448112 23646880 3647616
15:27:38 65017 8192000000 10303432528 10764574720 1372501472 14.349 98 11 22 0 1394835568 19518352 2815744
15:30:11 59706 8192000000 10304045776 10767360000 1390434208 14.510 100 20 41 0 1408141968 13246960 4460800
15:32:18 50502 8192000000 10290855120 10737250304 1336517552 14.026 96 15 30 1 1348014944 8624176 2873216
15:34:13 61257 8192000000 10285318144 10738130944 1354036528 14.184 98 19 37 0 1369304624 11375216 3892880
15:36:21 63953 8192000000 10289193024 10735476736 1330371904 13.971 96 17 34 0 1342333568 8467728 3493936
15:38:30 103108 8192000000 10268538624 10731995136 1271399344 13.434 92 8 16 0 1284124656 11321488 1403824
15:41:14 65027 8192000000 10279663840 10731515904 1328919840 13.957 97 15 30 1 1347167296 15035472 3211984
15:44:22 40478 8192000000 10296200912 10727624704 1340768144 14.064 97 37 74 1 1360169648 11429200 7972304
15:46:15 93799 8192000000 10283607264 10730397696 1323429936 13.908 96 34 68 1 1346365680 15299648 7636096
15:48:56 14785 8192000000 10268872176 10712907776 1351730160 14.163 99 31 61 1 1366472144 7878160 6863824
15:50:13 35036 8192000000 10276447808 10728046592 1374917792 14.371 100 37 74 2 1394002736 10925008 8159936
15:51:52 45881 8192000000 10266968784 10723123200 1364157136 14.275 100 15 30 1 1378666496 11177920 3331440
15:53:38 67433 8192000000 10249763008 10706255872 1315808496 13.839 96 15 30 0 1325713008 6302832 3601680
15:55:50 61641 8192000000 10276353120 10735333376 1362091680 14.256 99 26 52 0 1377992816 9816112 6085024
15:57:52 46882 8192000000 10278096848 10749140992 1381886976 14.433 100 33 66 0 1402454336 12792752 7774608
15:59:49 51887 8192000000 10284163824 10742607872 1369799008 14.325 99 13 26 1 1384044400 11403344 2842048
16:01:48 66834 8192000000 10308782192 10763366400 1362108016 14.256 98 22 42 1 1379903200 12579872 5215312
16:03:55 107777 8192000000 10297439552 10751574016 1358914672 14.228 98 8 16 0 1381143536 20059296 2169568
16:06:48 66642 8192000000 10305680816 10773209088 1399719440 14.592 100 32 64 0 1423561760 16746752 7095568
16:08:55 40430 8192000000 10303331040 10752110592 1369049904 14.319 98 25 50 1 1383197680 8582320 5565456
16:10:39 26942 8192000000 10312832736 10753122304 1362981344 14.264 98 43 86 2 1383874912 11700768 9192800
16:12:07 120451 8192000000 10304894224 10758782976 1385109040 14.462 99 27 53 2 1410653968 19189568 6355360
16:15:12 11723 8192000000 10298880912 10757054464 1372711088 14.351 99 22 44 0 1396098128 18540896 4846144
16:16:33 60819 8192000000 10285206592 10767568896 1372761808 14.352 99 9 18 0 1398504368 23598160 2144400
16:19:14 105422 8192000000 10281409904 10752376832 1381225024 14.428 100 11 22 0 1396673184 12830304 2617856
16:22:00 122123 8192000000 10294238416 10764046336 1322567888 13.900 95 13 26 2 1350140848 24859664 2713296
16:25:04 44205 8192000000 10291251232 10745233408 1349383072 14.142 97 15 28 1 1360125344 7892720 2849552
16:26:48 72735 8192000000 10293803440 10765176832 1363635376 14.270 98 9 16 1 1376891280 11451104 1804800
16:29:01 113525 8192000000 10304900560 10776305664 1385457296 14.465 99 19 38 0 1404260880 14743088 4060496
16:31:55 39960 8192000000 10287403888 10728083456 1320091152 13.878 95 30 59 1 1335783952 9121648 6571152
16:33:41 28154 8192000000 10257748944 10708021248 1313320128 13.816 97 13 25 0 1323723040 7674976 2727936
16:35:09 127710 8192000000 10269226832 10711904256 1332119984 13.986 98 16 32 0 1348140304 12170272 3850048

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-06-2022 16:40:30 to 27-06-2022 16:40:45.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 150 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

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

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          243329
MON$OLDEST_ACTIVE               246140
MON$OLDEST_SNAPSHOT             246140
MON$NEXT_TRANSACTION            246140
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                       2046816
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.33603 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33603 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33603 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33603 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 ---
:506: 
:507: mscs-test-161.budzdorov.ru    Mon Jun 27 16:38:22 2022
:508:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:509: 
:510: 
:511: mscs-test-161.budzdorov.ru    Mon Jun 27 16:38:25 2022
:512:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:513: 
:514: 
:515: mscs-test-161.budzdorov.ru    Mon Jun 27 16:38:27 2022
:516:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:517: 
:518: 
:519: mscs-test-161.budzdorov.ru    Mon Jun 27 16:38:30 2022
:520:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:521: 
:522: 
:523: mscs-test-161.budzdorov.ru    Mon Jun 27 16:38:34 2022
:524:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:525: 
--- end of diff output ---

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

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