Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 24.05.2022 22: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 16515900 kB
MemAvailable 31373976 kB
Buffers 0 kB
Cached 15173236 kB
SwapCached 2544 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-24 22:38:16
DTS_END                         2022-05-24 22:38:16.9650
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/43870
TRN_ID                          240065
ATT_ID                          682
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: 8909.15 21435 1069098
customer order: creation 175.99 359 21119
customer order: refuse 14.50 379 1740
order to supplier: creation 46.73 1298 5607
order to supplier: removal 3.39 104391 407
invoice (draft): creation 46.93 3478 5631
invoice (draft): removal 49.13 959 5895
invoice accept: apply 41.30 18406 4956
invoice accept: cancel 44.42 861 5330
customer reserve: creation 3820.65 181 458478
customer reserve: removal 4373.13 62 524775
realization accept: apply 85.48 192 10257
realization accept: cancel 75.03 114 9003
payment from customer: creation 59.99 127 7199
payment from customer: removal 6.43 90 772
payment to supplier: creation 50.41 136 6049
payment to supplier: removal 3.60 112 432
service: total inventory turnovers 4.72 1185 567
service: total monetary turnovers 6.50 26 780
service: aggregate perf. data 0.81 22795 97
service: refresh index statistics 0.03 273544 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 24-05-2022 22:39:11 to 24-05-2022 22:39:13.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4647
TEST_TIME 2 4389
TEST_TIME 3 4473
TEST_TIME 4 4539
TEST_TIME 5 4759
TEST_TIME 6 5783
TEST_TIME 7 5602
TEST_TIME 8 5688
TEST_TIME 9 5762
TEST_TIME 10 6020
TEST_TIME 11 6232
TEST_TIME 12 6661
TEST_TIME 13 6545
TEST_TIME 14 6702
TEST_TIME 15 6887
TEST_TIME 16 7114
TEST_TIME 17 7638
TEST_TIME 18 7701
TEST_TIME 19 7808
TEST_TIME 20 8094
TEST_TIME 21 8146
TEST_TIME 22 8293
TEST_TIME 23 8344
TEST_TIME 24 8852
TEST_TIME 25 8773
TEST_TIME 26 8656
TEST_TIME 27 9002
TEST_TIME 28 9634
TEST_TIME 29 10594
TEST_TIME 30 10546
TEST_TIME 31 10508
TEST_TIME 32 10357
TEST_TIME 33 10324
TEST_TIME 34 10156
TEST_TIME 35 9963
TEST_TIME 36 9847
TEST_TIME 37 9846
TEST_TIME 38 9771
TEST_TIME 39 9615
TEST_TIME 40 9542
TEST_TIME 41 9484
TEST_TIME 42 9385
TEST_TIME 43 9304
TEST_TIME 44 9291
TEST_TIME 45 9139
TEST_TIME 46 8948
TEST_TIME 47 9128
TEST_TIME 48 8997
TEST_TIME 49 9082
TEST_TIME 50 9034
TEST_TIME 51 8959
TEST_TIME 52 8976
TEST_TIME 53 8899
TEST_TIME 54 9020
TEST_TIME 55 9037
TEST_TIME 56 8954
TEST_TIME 57 8949
TEST_TIME 58 8889
TEST_TIME 59 8911
TEST_TIME 60 8917
TEST_TIME 61 8933
TEST_TIME 62 8939
TEST_TIME 63 9049
TEST_TIME 64 9048
TEST_TIME 65 9031
TEST_TIME 66 9067
TEST_TIME 67 9301
TEST_TIME 68 9242
TEST_TIME 69 9254
TEST_TIME 70 9315
TEST_TIME 71 9342
TEST_TIME 72 9326
TEST_TIME 73 9348
TEST_TIME 74 9232
TEST_TIME 75 9172
TEST_TIME 76 9167
TEST_TIME 77 9103
TEST_TIME 78 9180
TEST_TIME 79 9132
TEST_TIME 80 9079
TEST_TIME 81 9024
TEST_TIME 82 8981
TEST_TIME 83 8994
TEST_TIME 84 8973
TEST_TIME 85 9000
TEST_TIME 86 8971
TEST_TIME 87 8976
TEST_TIME 88 9003
TEST_TIME 89 9027
TEST_TIME 90 9036
TEST_TIME 91 9061
TEST_TIME 92 9043
TEST_TIME 93 9093
TEST_TIME 94 9083
TEST_TIME 95 9087
TEST_TIME 96 9091
TEST_TIME 97 9136
TEST_TIME 98 9121
TEST_TIME 99 9160
TEST_TIME 100 9204
TEST_TIME 101 9196
TEST_TIME 102 9112
TEST_TIME 103 9094
TEST_TIME 104 9064
TEST_TIME 105 9046
TEST_TIME 106 9022
TEST_TIME 107 9011
TEST_TIME 108 8964
TEST_TIME 109 8940
TEST_TIME 110 8904
TEST_TIME 111 8875
TEST_TIME 112 8893
TEST_TIME 113 8885
TEST_TIME 114 8875
TEST_TIME 115 8909
TEST_TIME 116 8956
TEST_TIME 117 8954
TEST_TIME 118 8944
TEST_TIME 119 8960
TEST_TIME 120 8913

Performance per minute, chart


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

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 1493304 1493304 0 0.00 0 23435 7 0 0
doc_list_biud 1493304 1493304 0 0.00 0 55360 28 0 0
sp_add_invoice_to_stock 5054 4956 98 1.93 30 188670 18406 0 98
sp_cancel_adding_invoice 5330 5330 0 0.00 5 50376 861 0 0
sp_cancel_client_order 1740 1740 0 0.00 4 11655 380 0 0
sp_cancel_customer_reserve 524775 524775 0 0.00 1 55362 62 0 0
sp_cancel_pay_from_customer 772 772 0 0.00 3 13874 91 0 0
sp_cancel_pay_to_supplier 432 432 0 0.00 3 10578 112 0 0
sp_cancel_supplier_invoice 5898 5895 3 0.05 2 46358 959 0 3
sp_cancel_supplier_order 407 407 0 0.00 6 1155474 104391 0 0
sp_cancel_write_off 9003 9003 0 0.00 1 14871 115 0 0
sp_client_order 21119 21119 0 0.00 3 55047 360 0 0
sp_customer_reserve 458478 458478 0 0.00 0 50061 181 0 0
sp_fill_shopping_cart 32355 32355 0 0.00 1 10808 42 0 0
sp_fill_shopping_cart_clo_res 458470 458470 0 0.00 0 20057 13 0 0
sp_get_clo_for_invoice 4947 4947 0 0.00 0 31125 305 0 0
sp_kill_cost_storno 15537 15537 0 0.00 0 16680 62 0 0
sp_kill_qstorno_ret_qs2qd 1064855 1064855 0 0.00 0 55342 34 0 0
sp_kill_qty_storno 562354 562354 0 0.00 0 55360 75 0 0
sp_lock_selected_doc 21123 21123 0 0.00 0 8309 5 0 0
sp_make_cost_storno 28452 28452 0 0.00 0 23182 65 0 0
sp_multiply_rows_for_pdistr 28452 28452 0 0.00 0 13879 34 0 0
sp_multiply_rows_for_qdistr 21119 21119 0 0.00 0 55038 151 0 0
sp_pay_from_customer 7199 7199 0 0.00 4 12792 127 0 0
sp_pay_to_supplier 6049 6049 0 0.00 4 15459 136 0 0
sp_payment_common 13248 13248 0 0.00 1 12765 35 0 0
sp_qd_handle_on_cancel_clo 1740 1740 0 0.00 0 11644 346 0 0
sp_qd_handle_on_invoice_upd_sts 10277 10277 0 0.00 0 49061 373 0 0
sp_qd_handle_on_reserve_upd_sts 10257 10257 0 0.00 0 42618 54 0 0
sp_reserve_write_off 10257 10257 0 0.00 6 42689 193 0 0
sp_supplier_invoice 5631 5631 0 0.00 6 77820 3479 0 0
sp_supplier_order 5607 5607 0 0.00 5 44967 1299 0 0
srv_aggregate_perf_data 97 97 0 0.00 2811 250764 22796 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1236 167445 43603 0 0
srv_find_qd_qs_mism 1020608 1020608 0 0.00 0 15472 5 0 0
srv_make_invnt_saldo 567 567 0 0.00 41 118096 1185 0 0
srv_make_money_saldo 780 780 0 0.00 1 1731 27 0 0
srv_recalc_idx_stat 4 4 0 0.00 162017 379855 273544 0 0
t$perf-abend:sp_add_invoice_to_stock 98 98 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 3 3 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4957 4957 0 0.00 0 16302 187 0 0
t$perf-norm:sp_cancel_adding_invoice 438 438 0 0.00 0 2899 101 0 0
t$perf-norm:sp_cancel_client_order 1740 1740 0 0.00 0 4316 16 0 0
t$perf-norm:sp_cancel_customer_reserve 709 709 0 0.00 0 2942 19 0 0
t$perf-norm:sp_cancel_pay_from_customer 772 772 0 0.00 0 962 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 432 432 0 0.00 0 1313 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 409 409 0 0.00 0 9146 42 0 0
t$perf-norm:sp_cancel_supplier_order 407 407 0 0.00 0 28154 1824 0 0
t$perf-norm:sp_cancel_write_off 1013 1013 0 0.00 0 1420 7 0 0
t$perf-norm:sp_client_order 21119 21119 0 0.00 0 10261 18 0 0
t$perf-norm:sp_customer_reserve 988 988 0 0.00 0 8230 18 0 0
t$perf-norm:sp_pay_from_customer 7199 7199 0 0.00 0 9147 9 0 0
t$perf-norm:sp_pay_to_supplier 6049 6049 0 0.00 0 10693 11 0 0
t$perf-norm:sp_reserve_write_off 10257 10257 0 0.00 0 9166 14 0 0
t$perf-norm:sp_supplier_invoice 5631 5631 0 0.00 0 12650 24 0 0
t$perf-norm:sp_supplier_order 5607 5607 0 0.00 0 8337 19 0 0
t$perf-norm:srv_aggregate_perf_data 97 97 0 0.00 0 278 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 378 7 0 0
t$perf-norm:srv_make_invnt_saldo 567 567 0 0.00 0 1448 29 0 0
t$perf-norm:srv_make_money_saldo 780 780 0 0.00 0 1212 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 15 7 0 0
v_add_invoice_to_stock 10090 9894 196 1.94 0 3918 18 0 196
v_all_customers 37836 37836 0 0.00 0 16 1 0 0
v_all_suppliers 31446 31446 0 0.00 0 28 1 0 0
v_all_wares 42238 42238 0 0.00 0 72 1 0 0
v_cancel_adding_invoice 876 876 0 0.00 0 3305 13 0 0
v_cancel_client_order 3480 3480 0 0.00 0 6440 8 0 0
v_cancel_customer_prepayment 1544 1544 0 0.00 0 3798 6 0 0
v_cancel_customer_reserve 1418 1418 0 0.00 0 2860 10 0 0
v_cancel_payment_to_supplier 864 864 0 0.00 0 3908 16 0 0
v_cancel_supplier_invoice 824 818 6 0.72 0 4434 22 0 6
v_cancel_supplier_order 814 814 0 0.00 0 405 4 0 0
v_cancel_write_off 2026 2026 0 0.00 0 3746 12 0 0
v_max_id_clo_ord 5607 5607 0 0.00 0 1840 2 0 0
v_max_id_clo_res 988 988 0 0.00 0 1066 9 0 0
v_max_id_ord_sup 5628 5628 0 0.00 0 5870 8 0 0
v_max_non_paid_invoice 1561 1561 0 0.00 0 3708 5 0 0
v_max_non_paid_realizn 4102 4102 0 0.00 0 9153 6 0 0
v_min_id_clo_ord 5607 5607 0 0.00 0 4077 6 0 0
v_min_id_clo_res 988 988 0 0.00 0 3578 10 0 0
v_min_id_ord_sup 5628 5628 0 0.00 0 2670 5 0 0
v_min_non_paid_invoice 6049 6049 0 0.00 0 9788 25 0 0
v_min_non_paid_realizn 7199 7199 0 0.00 0 12762 31 0 0
v_our_firm 10596 10596 0 0.00 0 15 1 0 0
v_reserve_write_off 20512 20512 0 0.00 0 5860 6 0 0
x_lock_depdocs_on_canc_invoice 10816 10816 0 0.00 0 15442 126 0 0
x_lock_depdocs_on_canc_sup_ord 407 407 0 0.00 0 6380 359 0 0
x_make_qstorno_1000_1200 5607 5607 0 0.00 1 44959 1213 0 0
x_make_qstorno_1200_2000 5631 5631 0 0.00 3 77808 3330 0 0
x_make_qstorno_2100_3300 458478 458478 0 0.00 0 50053 165 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 24-05-2022 22:39:19 to 24-05-2022 22:39:23.

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
20:08:20 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:21 32026 8192000000 9907696432 10218840064 986320384 10.746 100 89 176 1 1016927360 6380304 24226672
20:11:00 27871 8192000000 9961116848 10283765760 1036126160 11.227 100 88 176 0 1067230016 8263760 22840096
20:12:34 22402 8192000000 9989438560 10322956288 1070538576 11.557 100 87 174 0 1101609712 9585744 21485392
20:14:01 8347 8192000000 10037239696 10386026496 1128113056 12.104 100 81 160 2 1158433472 11435472 18884944
20:15:17 8244 8192000000 10096880544 10457145344 1182638368 12.615 100 85 169 0 1214336224 12775984 18921872
20:16:29 5030 8192000000 10130711088 10501373952 1221156064 12.972 100 81 162 1 1251899248 13256336 17486848
20:17:37 7038 8192000000 10148957424 10525921280 1242785072 13.172 100 69 139 2 1271215104 13403104 15026928
20:18:47 2646 8192000000 10195164064 10573897728 1279288544 13.507 100 86 173 0 1311561536 13568912 18704080
20:19:53 1209 8192000000 10218069488 10599092224 1296613120 13.664 100 91 182 0 1330907280 13933712 20360448
20:20:56 1613 8192000000 10220753360 10605428736 1305544800 13.746 100 89 178 1 1340249296 14253024 20451472
20:22:00 1781 8192000000 10213844192 10613067776 1310682800 13.792 100 73 146 0 1339139936 12062800 16394336
20:23:07 7143 8192000000 10197005888 10614898688 1315734624 13.838 100 24 48 2 1328636512 7867840 5034048
20:24:18 13594 8192000000 10205466960 10622939136 1326638272 13.937 100 27 54 0 1338137552 5150144 6349136
20:25:32 11927 8192000000 10209209344 10629910528 1328110960 13.950 100 28 54 1 1340290624 5890176 6289488
20:26:46 45296 8192000000 10247341968 10653380608 1334763472 14.010 100 42 84 2 1349750496 4635648 10351376
20:28:32 6719 8192000000 10202742528 10622615552 1306934928 13.758 99 22 44 2 1318778944 6651264 5192752
20:29:42 7853 8192000000 10174361072 10588549120 1283846592 13.548 99 16 31 2 1292707664 5303216 3557856
20:30:55 17019 8192000000 10172273088 10574045184 1251466320 13.252 97 34 68 1 1265531456 6351232 7713904
20:32:14 1953 8192000000 10202191120 10584051712 1267421584 13.398 99 91 185 5 1295998560 6042960 22534016
20:33:17 6868 8192000000 10135221456 10539315200 1227980784 13.035 98 22 45 1 1238976048 6094816 4900448
20:34:25 6146 8192000000 10172087392 10571964416 1272809616 13.447 100 22 44 0 1283834672 6157984 4867072
20:35:33 17599 8192000000 10175497040 10579292160 1279663040 13.510 100 46 92 1 1297000144 6188128 11148976
20:36:51 5460 8192000000 10187661744 10598035456 1295869648 13.658 100 25 47 0 1308165616 6006496 6289472
20:37:59 7550 8192000000 10204947776 10621890560 1310504096 13.791 100 38 73 5 1329407248 10208976 8694176
20:39:16 4370 8192000000 10234463024 10644013056 1336176496 14.023 100 54 107 0 1357121456 8035808 12909152
20:40:25 14213 8192000000 10255609520 10671321088 1350268304 14.150 100 50 98 2 1373248880 11573232 11407344
20:41:47 64651 8192000000 10267909136 10694201344 1361334784 14.249 100 34 67 1 1379818336 10098896 8384656
20:44:00 20983 8192000000 10289029952 10716491776 1379658528 14.413 100 37 72 2 1404076512 15287504 9130480
20:45:23 7063 8192000000 10308620944 10729644032 1389456512 14.501 100 61 119 0 1416642960 13440432 13746016
20:46:33 7269 8192000000 10313419680 10743304192 1400381136 14.598 100 46 92 2 1429749632 18428544 10939952
20:47:46 14155 8192000000 10337636576 10759671808 1410549648 14.689 100 56 110 1 1442524768 19468608 12506512
20:49:05 5180 8192000000 10352476560 10777583616 1421722832 14.788 100 61 122 1 1459221664 22558720 14940112
20:50:18 1917 8192000000 10345999776 10761080832 1406692304 14.655 99 91 182 1 1455080752 26683360 21705088
20:51:23 4884 8192000000 10354534416 10784735232 1422259424 14.793 100 55 110 0 1464614848 28650448 13704976
20:52:32 3117 8192000000 10340035952 10763653120 1390518432 14.510 99 58 115 2 1438236832 33018352 14700048
20:53:40 3745 8192000000 10357560288 10771050496 1403873904 14.629 100 69 138 1 1458872656 37942720 17056032
20:54:47 2114 8192000000 10350528368 10763288576 1391836096 14.522 100 79 155 1 1453745984 42208432 19701456
20:55:52 5771 8192000000 10358520400 10767409152 1401757520 14.611 100 85 170 2 1459879408 37010496 21111392
20:57:05 1236 8192000000 10360334224 10768760832 1387828704 14.486 98 85 171 0 1452279968 43511808 20939456
20:58:12 1503 8192000000 10368753312 10788106240 1408177216 14.668 100 72 144 1 1474755936 47302944 19275776
20:59:31 9217 8192000000 10330272656 10757840896 1385951424 14.470 100 33 66 2 1438358288 42937152 9469712
21:00:45 12345 8192000000 10326568224 10746298368 1372318384 14.348 100 48 96 2 1430073984 44932912 12822688
21:02:01 9070 8192000000 10345108784 10768519168 1379562512 14.413 100 40 80 3 1443972672 53384032 11026128
21:03:20 27423 8192000000 10325407536 10756542464 1371173760 14.338 99 27 54 1 1426204144 46966976 8063408
21:05:14 17350 8192000000 10318824512 10751856640 1356603152 14.207 100 33 63 1 1423076320 57150336 9322832
21:06:34 27453 8192000000 10284995840 10724835328 1320188160 13.878 99 21 42 1 1373175280 46908992 6078128
21:08:11 72694 8192000000 10263613120 10687873024 1341917104 14.075 99 25 50 4 1356036800 8214960 5904736
21:10:25 48758 8192000000 10267348864 10699288576 1346481424 14.116 99 17 35 0 1357074768 7068080 3525264
21:12:16 57730 8192000000 10273896224 10728525824 1365747520 14.289 100 11 21 0 1377914624 9993568 2173536
21:14:21 28138 8192000000 10269391088 10720907264 1370519088 14.332 100 21 43 1 1385939536 10263488 5156960
21:15:50 52039 8192000000 10276818512 10725789696 1371929248 14.344 100 10 21 0 1384452256 10229376 2293632
21:17:42 101272 8192000000 10302880816 10743898112 1383163024 14.445 100 31 60 0 1400394112 10482592 6748496
21:20:26 42336 8192000000 10296183776 10736005120 1374127920 14.364 99 30 60 1 1389454768 8693072 6633776
21:22:25 75658 8192000000 10292703184 10748035072 1379108720 14.409 99 10 20 2 1394467792 12865920 2493152
21:26:18 82192 8192000000 10291026512 10744717312 1348775600 14.136 97 31 61 0 1375098816 19403536 6919680
21:28:48 41611 8192000000 10296216208 10753011712 1376154288 14.382 99 18 36 3 1400116976 19618544 4344144
21:30:34 50589 8192000000 10312727696 10755710976 1383020448 14.444 99 33 65 2 1406512544 15644000 7848096
21:32:39 54282 8192000000 10286170944 10760990720 1387409280 14.483 100 11 22 0 1407582080 17339200 2833600
21:34:35 45758 8192000000 10300191536 10750373888 1382626624 14.440 99 22 44 0 1394179840 6947968 4605248
21:36:32 37257 8192000000 10292826752 10745286656 1366970608 14.300 98 34 67 4 1381427632 6429024 8028000
21:38:14 42302 8192000000 10259257600 10708938752 1292820608 13.630 93 6 12 0 1299410288 5604032 985648
21:40:09 59813 8192000000 10288175600 10739318784 1345091600 14.103 97 10 20 0 1357402400 10429616 1881184
21:42:15 70805 8192000000 10304500624 10753421312 1377643648 14.395 99 14 28 0 1395384224 14301808 3438768
21:44:26 74068 8192000000 10262337344 10724790272 1302875664 13.721 94 12 24 1 1336165760 30027184 3262912
21:46:41 49283 8192000000 10296224032 10736181248 1350214496 14.149 98 33 64 1 1368857184 11001648 7641040
21:48:45 70276 8192000000 10271444224 10722926592 1309972288 13.786 95 7 14 1 1323305264 11830464 1502512
21:50:56 167445 8192000000 10271847936 10714513408 1323534736 13.909 97 16 32 1 1341990752 14850272 3605744
21:54:46 57430 8192000000 10272532176 10708447232 1345346272 14.106 99 41 82 0 1370412960 15864304 9202384
21:56:45 56733 8192000000 10260588752 10713018368 1344625424 14.099 99 12 24 0 1357270400 10294000 2350976
21:58:48 88089 8192000000 10256697680 10705920000 1320144496 13.878 96 11 22 0 1333328000 10421296 2762208
22:01:54 66568 8192000000 10259789152 10708131840 1356176192 14.203 100 10 20 0 1369239824 10739280 2324352
22:04:02 40288 8192000000 10258908528 10706313216 1356326992 14.204 100 17 32 1 1368864576 9222192 3315392
22:05:43 73854 8192000000 10281750512 10713432064 1339359488 14.052 98 22 44 1 1363499216 19109248 5030480
22:08:01 53849 8192000000 10298466720 10738171904 1369290640 14.321 99 20 40 0 1381517312 8072672 4154000
22:09:55 48617 8192000000 10296062096 10739113984 1361549856 14.251 98 24 48 1 1376645664 9897968 5197840
22:11:44 28266 8192000000 10298494064 10740064256 1377083088 14.390 99 37 73 5 1393024672 7819040 8122544
22:13:16 116300 8192000000 10293024880 10750042112 1348984176 14.138 97 19 38 1 1362550208 9686560 3879472
22:16:13 44268 8192000000 10285858640 10721370112 1339520960 14.053 97 27 49 1 1354259968 9641328 5097680
22:17:58 52412 8192000000 10299307344 10768576512 1382263296 14.437 100 16 32 0 1407238400 21410656 3564448
22:19:56 84786 8192000000 10291818880 10739675136 1371117056 14.337 99 15 29 0 1386577968 11631888 3829024
22:23:57 52607 8192000000 10262571280 10722275328 1357097136 14.211 99 15 30 0 1371505792 11248032 3160624
22:25:56 76230 8192000000 10280855232 10738257920 1382287168 14.437 100 10 20 0 1392215408 7497424 2430816
22:28:17 75092 8192000000 10276757952 10740436992 1369582752 14.323 99 21 42 0 1389069056 14173536 5312768
22:30:37 33321 8192000000 10289780032 10743955456 1355023968 14.193 98 16 32 0 1374016224 14866896 4125360
22:32:14 46045 8192000000 10283483312 10734104576 1360558864 14.242 98 18 36 1 1374273504 9338592 4376048
22:34:02 47068 8192000000 10263902016 10720301056 1336204192 14.023 97 20 40 1 1350539456 9610048 4725216
22:36:00 32917 8192000000 10273920704 10719285248 1345784336 14.110 98 35 70 1 1364588896 10924992 7879568
22:37:37 26408 8192000000 10289780400 10723930112 1352231616 14.168 99 33 66 1 1368324336 8975648 7117072

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 24-05-2022 22:39:24 to 24-05-2022 22:39:39.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 98 v_add_invoice_to_stock 335544517
except 3 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 24-05-2022 22:39:40 to 24-05-2022 22:39:42.

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          237102
MON$OLDEST_ACTIVE               240189
MON$OLDEST_SNAPSHOT             240189
MON$NEXT_TRANSACTION            240189
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                       2024800
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.33594 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33594 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33594 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33594 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 ---
:20: 
:21: mscs-test-161.budzdorov.ru    Tue May 24 22:38:18 2022
:22:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:23: 
:24: 
:25: mscs-test-161.budzdorov.ru    Tue May 24 22:38:18 2022
:26:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:27: 
:28: 
:29: mscs-test-161.budzdorov.ru    Tue May 24 22:38:19 2022
:30:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:31: 
--- end of diff output ---

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

24.05.22 22:39:43. 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-24 22:39:46 No severe PSQL-related problems occured
24.05.22 22:39:47. End of report.