Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 27.12.2021 19: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 16768136 kB
MemAvailable 31346004 kB
Buffers 0 kB
Cached 14889700 kB
SwapCached 436 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.9
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 2021-12-27 19:38:02
DTS_END                         2021-12-27 19:38:02.3900
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/56356
TRN_ID                          238506
ATT_ID                          681
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: 9123.85 25004 1094862
customer order: creation 168.33 387 20200
customer order: refuse 14.66 358 1759
order to supplier: creation 44.83 1348 5379
order to supplier: removal 3.53 92783 424
invoice (draft): creation 44.44 3263 5333
invoice (draft): removal 52.30 745 6276
invoice accept: apply 40.36 17492 4843
invoice accept: cancel 47.21 807 5665
customer reserve: creation 3778.61 170 453433
customer reserve: removal 4654.30 55 558516
realization accept: apply 82.50 170 9900
realization accept: cancel 64.95 123 7794
payment from customer: creation 58.45 119 7014
payment from customer: removal 5.20 105 624
payment to supplier: creation 48.55 124 5826
payment to supplier: removal 3.72 68 446
service: total inventory turnovers 4.36 1280 523
service: total monetary turnovers 6.68 26 801
service: aggregate perf. data 0.85 21571 102
service: refresh index statistics 0.03 359092 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 27-12-2021 19:38:47 to 27-12-2021 19:38:49.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7141
TEST_TIME 2 7271
TEST_TIME 3 5741
TEST_TIME 4 5071
TEST_TIME 5 4745
TEST_TIME 6 4824
TEST_TIME 7 4949
TEST_TIME 8 4716
TEST_TIME 9 5167
TEST_TIME 10 5403
TEST_TIME 11 6091
TEST_TIME 12 6369
TEST_TIME 13 6385
TEST_TIME 14 6693
TEST_TIME 15 6740
TEST_TIME 16 7213
TEST_TIME 17 7268
TEST_TIME 18 8263
TEST_TIME 19 8442
TEST_TIME 20 9060
TEST_TIME 21 9181
TEST_TIME 22 9391
TEST_TIME 23 9672
TEST_TIME 24 10051
TEST_TIME 25 10117
TEST_TIME 26 10236
TEST_TIME 27 10626
TEST_TIME 28 10531
TEST_TIME 29 10495
TEST_TIME 30 10452
TEST_TIME 31 10557
TEST_TIME 32 10397
TEST_TIME 33 10232
TEST_TIME 34 9998
TEST_TIME 35 10062
TEST_TIME 36 9963
TEST_TIME 37 9833
TEST_TIME 38 9796
TEST_TIME 39 9624
TEST_TIME 40 9779
TEST_TIME 41 9679
TEST_TIME 42 9573
TEST_TIME 43 9556
TEST_TIME 44 9577
TEST_TIME 45 9425
TEST_TIME 46 9367
TEST_TIME 47 9258
TEST_TIME 48 9173
TEST_TIME 49 9061
TEST_TIME 50 9032
TEST_TIME 51 9053
TEST_TIME 52 9057
TEST_TIME 53 8968
TEST_TIME 54 8922
TEST_TIME 55 8927
TEST_TIME 56 8892
TEST_TIME 57 8885
TEST_TIME 58 8818
TEST_TIME 59 8936
TEST_TIME 60 9032
TEST_TIME 61 9001
TEST_TIME 62 9004
TEST_TIME 63 9092
TEST_TIME 64 9118
TEST_TIME 65 9167
TEST_TIME 66 9128
TEST_TIME 67 9220
TEST_TIME 68 9239
TEST_TIME 69 9221
TEST_TIME 70 9250
TEST_TIME 71 9253
TEST_TIME 72 9150
TEST_TIME 73 9104
TEST_TIME 74 9019
TEST_TIME 75 9035
TEST_TIME 76 9069
TEST_TIME 77 9036
TEST_TIME 78 9032
TEST_TIME 79 9042
TEST_TIME 80 8952
TEST_TIME 81 8946
TEST_TIME 82 8936
TEST_TIME 83 8956
TEST_TIME 84 8932
TEST_TIME 85 8943
TEST_TIME 86 8953
TEST_TIME 87 8980
TEST_TIME 88 9022
TEST_TIME 89 9029
TEST_TIME 90 9122
TEST_TIME 91 9135
TEST_TIME 92 9171
TEST_TIME 93 9198
TEST_TIME 94 9226
TEST_TIME 95 9273
TEST_TIME 96 9248
TEST_TIME 97 9195
TEST_TIME 98 9147
TEST_TIME 99 9173
TEST_TIME 100 9153
TEST_TIME 101 9099
TEST_TIME 102 9112
TEST_TIME 103 9079
TEST_TIME 104 9048
TEST_TIME 105 9030
TEST_TIME 106 8997
TEST_TIME 107 8969
TEST_TIME 108 8995
TEST_TIME 109 8995
TEST_TIME 110 8997
TEST_TIME 111 9005
TEST_TIME 112 9059
TEST_TIME 113 9043
TEST_TIME 114 9046
TEST_TIME 115 9103
TEST_TIME 116 9098
TEST_TIME 117 9160
TEST_TIME 118 9157
TEST_TIME 119 9207
TEST_TIME 120 9131

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 27-12-2021 19:38:51 to 27-12-2021 19:38:54.

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1514426 1514426 0 0.00 0 24814 6 0 0
doc_list_biud 1514425 1514425 0 0.00 0 23237 26 0 0
sp_add_invoice_to_stock 4961 4843 118 2.37 41 194473 17493 0 118
sp_cancel_adding_invoice 5665 5665 0 0.00 3 69044 807 0 0
sp_cancel_client_order 1759 1759 0 0.00 4 11533 358 0 0
sp_cancel_customer_reserve 558516 558516 0 0.00 1 23286 56 0 0
sp_cancel_pay_from_customer 624 624 0 0.00 2 6563 106 0 0
sp_cancel_pay_to_supplier 446 446 0 0.00 2 3232 68 0 0
sp_cancel_supplier_invoice 6283 6276 7 0.11 2 22714 745 0 7
sp_cancel_supplier_order 424 424 0 0.00 6 1000266 92783 0 0
sp_cancel_write_off 7794 7794 0 0.00 1 12135 123 0 0
sp_client_order 20200 20200 0 0.00 3 29157 387 0 0
sp_customer_reserve 453433 453433 0 0.00 0 68223 170 0 0
sp_fill_shopping_cart 30912 30912 0 0.00 1 16357 41 0 0
sp_fill_shopping_cart_clo_res 453425 453425 0 0.00 0 23426 14 0 0
sp_get_clo_for_invoice 4834 4834 0 0.00 0 32399 326 0 0
sp_kill_cost_storno 14529 14529 0 0.00 0 20239 63 0 0
sp_kill_qstorno_ret_qs2qd 1131526 1131526 0 0.00 0 23209 30 0 0
sp_kill_qty_storno 595168 595168 0 0.00 0 23237 66 0 0
sp_lock_selected_doc 20419 20419 0 0.00 0 8173 5 0 0
sp_make_cost_storno 27574 27574 0 0.00 0 24801 50 0 0
sp_multiply_rows_for_pdistr 27574 27574 0 0.00 0 6722 27 0 0
sp_multiply_rows_for_qdistr 20200 20200 0 0.00 0 29138 182 0 0
sp_pay_from_customer 7014 7014 0 0.00 4 22453 119 0 0
sp_pay_to_supplier 5826 5826 0 0.00 4 10135 124 0 0
sp_payment_common 12840 12840 0 0.00 1 22429 29 0 0
sp_qd_handle_on_cancel_clo 1759 1759 0 0.00 0 11472 319 0 0
sp_qd_handle_on_invoice_upd_sts 10499 10499 0 0.00 0 16176 330 0 0
sp_qd_handle_on_reserve_upd_sts 9900 9900 0 0.00 0 17935 58 0 0
sp_reserve_write_off 9900 9900 0 0.00 6 17966 171 0 0
sp_supplier_invoice 5333 5333 0 0.00 6 70462 3264 0 0
sp_supplier_order 5379 5379 0 0.00 5 72973 1348 0 0
srv_aggregate_perf_data 102 102 0 0.00 2464 274173 21572 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1070 123671 47910 0 0
srv_find_qd_qs_mism 1047844 1047844 0 0.00 0 10515 5 0 0
srv_make_invnt_saldo 523 523 0 0.00 48 119118 1281 0 0
srv_make_money_saldo 801 801 0 0.00 0 3114 27 0 0
srv_recalc_idx_stat 4 4 0 0.00 225443 674555 359093 0 0
t$perf-abend:sp_add_invoice_to_stock 118 118 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4844 4844 0 0.00 0 11074 157 0 0
t$perf-norm:sp_cancel_adding_invoice 417 417 0 0.00 0 7434 111 0 0
t$perf-norm:sp_cancel_client_order 1759 1759 0 0.00 0 6894 16 0 0
t$perf-norm:sp_cancel_customer_reserve 673 673 0 0.00 0 712 7 0 0
t$perf-norm:sp_cancel_pay_from_customer 624 624 0 0.00 0 532 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 446 446 0 0.00 0 2163 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 404 404 0 0.00 0 1760 17 0 0
t$perf-norm:sp_cancel_supplier_order 424 424 0 0.00 0 19683 1395 0 0
t$perf-norm:sp_cancel_write_off 938 938 0 0.00 0 4000 9 0 0
t$perf-norm:sp_client_order 20200 20200 0 0.00 0 7286 14 0 0
t$perf-norm:sp_customer_reserve 953 953 0 0.00 0 3657 15 0 0
t$perf-norm:sp_pay_from_customer 7014 7014 0 0.00 0 7246 10 0 0
t$perf-norm:sp_pay_to_supplier 5826 5826 0 0.00 0 4720 11 0 0
t$perf-norm:sp_reserve_write_off 9900 9900 0 0.00 0 5274 9 0 0
t$perf-norm:sp_supplier_invoice 5333 5333 0 0.00 0 2929 13 0 0
t$perf-norm:sp_supplier_order 5379 5379 0 0.00 0 6937 16 0 0
t$perf-norm:srv_aggregate_perf_data 102 102 0 0.00 0 222 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 7034 115 0 0
t$perf-norm:srv_make_invnt_saldo 523 523 0 0.00 0 1584 27 0 0
t$perf-norm:srv_make_money_saldo 801 801 0 0.00 0 3681 21 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 8 4 0 0
v_add_invoice_to_stock 9904 9668 236 2.38 0 5746 25 0 236
v_all_customers 36412 36412 0 0.00 0 18 1 0 0
v_all_suppliers 29840 29840 0 0.00 0 16 1 0 0
v_all_wares 40400 40400 0 0.00 0 14 1 0 0
v_cancel_adding_invoice 834 834 0 0.00 0 1089 8 0 0
v_cancel_client_order 3518 3518 0 0.00 0 4355 10 0 0
v_cancel_customer_prepayment 1248 1248 0 0.00 0 6552 13 0 0
v_cancel_customer_reserve 1346 1346 0 0.00 0 3204 14 0 0
v_cancel_payment_to_supplier 892 892 0 0.00 0 1706 5 0 0
v_cancel_supplier_invoice 822 808 14 1.70 0 2957 25 0 14
v_cancel_supplier_order 848 848 0 0.00 0 1311 5 0 0
v_cancel_write_off 1876 1876 0 0.00 0 8456 26 0 0
v_max_id_clo_ord 5379 5379 0 0.00 0 8948 3 0 0
v_max_id_clo_res 953 953 0 0.00 0 3784 15 0 0
v_max_id_ord_sup 5333 5333 0 0.00 0 2843 4 0 0
v_max_non_paid_invoice 1618 1618 0 0.00 0 3176 5 0 0
v_max_non_paid_realizn 3937 3937 0 0.00 0 4396 3 0 0
v_min_id_clo_ord 5379 5379 0 0.00 0 13813 10 0 0
v_min_id_clo_res 953 953 0 0.00 0 7702 27 0 0
v_min_id_ord_sup 5333 5333 0 0.00 0 4559 8 0 0
v_min_non_paid_invoice 5826 5826 0 0.00 0 5500 18 0 0
v_min_non_paid_realizn 7014 7014 0 0.00 0 8449 21 0 0
v_our_firm 10142 10142 0 0.00 0 14 1 0 0
v_reserve_write_off 19800 19800 0 0.00 0 5803 7 0 0
x_lock_depdocs_on_canc_invoice 11537 11537 0 0.00 0 31019 107 0 0
x_lock_depdocs_on_canc_sup_ord 424 424 0 0.00 0 4920 329 0 0
x_make_qstorno_1000_1200 5379 5379 0 0.00 1 72963 1260 0 0
x_make_qstorno_1200_2000 5333 5333 0 0.00 3 69437 3123 0 0
x_make_qstorno_2100_3300 453433 453433 0 0.00 0 68216 153 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 27-12-2021 19:38:55 to 27-12-2021 19:38:59.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
17:08:06 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:07 5074 8192000000 9877532960 10182152192 965007680 10.538 100 91 182 0 995439568 5210032 25221856
17:10:19 22417 8192000000 9943044576 10266685440 1024783872 11.118 100 86 172 0 1055288912 7603568 22901472
17:11:45 18021 8192000000 9981500480 10313900032 1063260144 11.488 100 87 175 0 1094658528 9517200 21881184
17:13:05 18023 8192000000 10038987024 10385211392 1117678640 12.005 100 84 168 1 1148144208 10559344 19906224
17:14:30 10922 8192000000 10078696192 10436669440 1161784672 12.420 100 75 148 0 1190423168 11020784 17617712
17:15:44 4509 8192000000 10122874864 10494451712 1214483968 12.911 100 81 162 0 1245873424 13186192 18203264
17:16:51 3770 8192000000 10152763920 10524729344 1237927888 13.127 100 84 167 1 1269902464 12915808 19058768
17:17:56 2218 8192000000 10179222608 10564894720 1277393456 13.489 100 89 178 4 1309415552 12386320 19635776
17:19:00 1577 8192000000 10211845152 10596868096 1296907072 13.667 100 91 181 0 1329182592 12319824 19955696
17:20:04 31232 8192000000 10210375760 10607067136 1308295264 13.771 100 67 134 1 1332094208 8751840 15047104
17:21:37 4842 8192000000 10200129296 10616049664 1315351568 13.835 100 31 62 1 1329161104 6886272 6923264
17:22:43 7288 8192000000 10207909232 10625777664 1321228816 13.888 100 18 36 0 1330980704 5623040 4128848
17:23:52 6644 8192000000 10211702608 10631561216 1328487248 13.953 100 33 64 1 1342895152 6770048 7637856
17:25:02 38786 8192000000 10228266560 10647920640 1331863008 13.984 100 32 64 0 1345139616 5326912 7949696
17:26:41 8938 8192000000 10236692240 10656882688 1337455280 14.034 100 23 46 1 1349461600 6851040 5155280
17:27:52 5472 8192000000 10228646704 10646822912 1330575184 13.972 100 25 48 1 1342515136 6389280 5550672
17:29:00 34893 8192000000 10135873872 10546147328 1180980672 12.599 91 29 56 0 1192870912 5634000 6256240
17:30:39 5411 8192000000 10121952512 10524053504 1215785152 12.923 97 32 60 3 1229126416 5760272 7580992
17:31:45 8293 8192000000 10144692224 10543316992 1201825552 12.793 95 22 45 1 1211872304 5016560 5030192
17:32:54 6611 8192000000 10163004944 10561667072 1247206032 13.213 98 26 52 1 1259158128 5587984 6364112
17:34:03 62467 8192000000 10173075024 10586497024 1275219136 13.469 99 30 60 0 1288835008 6357552 7258320
17:36:07 5564 8192000000 10194247264 10606501888 1300143872 13.697 100 22 42 2 1314746608 9788864 4813872
17:37:13 8440 8192000000 10200812240 10612719616 1306023824 13.750 100 25 51 2 1321884656 10136048 5724784
17:38:26 26522 8192000000 10238116000 10648981504 1338603712 14.045 100 51 102 1 1357080128 6977312 11499104
17:39:58 62554 8192000000 10259816832 10682011648 1355780032 14.199 100 54 108 0 1381451520 13624512 12046976
17:42:22 12164 8192000000 10283602032 10703769600 1375407328 14.375 100 51 102 1 1398260528 11404720 11448480
17:43:51 27956 8192000000 10297942000 10720903168 1386820992 14.477 100 46 91 2 1411614208 13865200 10928016
17:45:25 26078 8192000000 10312011152 10739523584 1395035088 14.551 100 40 80 1 1425337728 20832016 9470624
17:47:03 2717 8192000000 10331647296 10753732608 1402784176 14.620 100 54 108 1 1438989104 22573072 13631856
17:48:09 7666 8192000000 10342387952 10767343616 1416092864 14.738 100 52 104 2 1454880416 26590624 12196928
17:49:22 1322 8192000000 10359227968 10772901888 1415582240 14.734 100 80 158 2 1463413072 29047152 18783680
17:50:28 1067 8192000000 10356306032 10773475328 1419886704 14.772 100 90 180 1 1465583696 23247712 22449280
17:51:33 9257 8192000000 10354846336 10778537984 1408447632 14.670 99 64 128 0 1452885552 27886720 16551200
17:52:52 2878 8192000000 10349920608 10758254592 1401372768 14.607 100 89 175 1 1454592464 30557536 22662160
17:54:00 3209 8192000000 10354210480 10766962688 1403895248 14.630 100 76 148 0 1458999312 34711104 20392960
17:55:08 4816 8192000000 10357462272 10765946880 1397868736 14.576 100 89 177 0 1459881440 39854880 22157824
17:56:22 2390 8192000000 10334830320 10736721920 1366061824 14.292 99 91 181 2 1434774624 45334208 23378592
17:57:27 14202 8192000000 10334788448 10752098304 1384114448 14.453 100 49 99 0 1436025968 38617024 13294496
17:58:46 18432 8192000000 10311256128 10762375168 1388610240 14.493 100 29 58 3 1429532304 33184624 7737440
18:00:14 22174 8192000000 10304916800 10740408320 1347381744 14.124 98 26 52 0 1384593600 29198592 8013264
18:01:43 35718 8192000000 10292610336 10727354368 1333690496 14.000 97 25 50 1 1373449616 32954720 6804400
18:03:33 24897 8192000000 10290212160 10730352640 1367903312 14.308 100 17 34 1 1397758656 25053328 4802016
18:05:18 41277 8192000000 10298483664 10736521216 1362025168 14.256 99 26 52 0 1405115296 35819200 7270928
18:07:03 53833 8192000000 10278181392 10724233216 1350419408 14.151 98 27 54 0 1372479200 15773136 6286656
18:09:14 60091 8192000000 10262560880 10713567232 1356957344 14.210 99 13 26 0 1375345680 15788928 2599408
18:11:15 41171 8192000000 10275476624 10721419264 1362099888 14.256 99 17 31 1 1373273536 8185104 2988544
18:12:59 94116 8192000000 10263683952 10710499328 1315341568 13.835 96 17 35 0 1326761072 7866928 3552576
18:15:33 71902 8192000000 10280880352 10737254400 1366143952 14.292 100 14 28 0 1387563536 17539408 3880176
18:18:25 67164 8192000000 10268362448 10722652160 1341106064 14.067 98 18 36 1 1365086928 19432352 4548512
18:20:37 49292 8192000000 10286926640 10736111616 1381318000 14.428 100 16 33 0 1395326592 10476704 3531888
18:22:50 55156 8192000000 10283753456 10747711488 1370007840 14.327 99 14 29 0 1388216512 15406640 2802032
18:24:47 49341 8192000000 10275284224 10732929024 1372669072 14.351 99 14 28 0 1383628048 7746960 3212016
18:26:37 72423 8192000000 10286595856 10745741312 1369598224 14.323 98 23 46 0 1386960032 12128496 5233312
18:29:28 57612 8192000000 10274854608 10725490688 1335759728 14.019 96 47 94 2 1355391520 9012992 10618800
18:31:34 41420 8192000000 10298266176 10745536512 1379290432 14.410 99 41 82 0 1401008912 12435504 9282976
18:33:34 59476 8192000000 10299077600 10758574080 1400999696 14.604 100 12 24 0 1413062336 9232800 2829840
18:35:34 41925 8192000000 10307338048 10770620416 1409679136 14.681 100 15 30 0 1426451024 13278528 3493360
18:37:20 43976 8192000000 10294753440 10758328320 1368350464 14.312 97 14 28 1 1387599648 15862144 3387040
18:39:08 43212 8192000000 10309057200 10761285632 1368050800 14.310 97 35 70 1 1389293824 13487184 7755840
18:40:52 94425 8192000000 10278759232 10728001536 1286407024 13.571 92 10 20 1 1298659840 10594624 1658192
18:43:30 64053 8192000000 10304898672 10741796864 1355290752 14.195 98 28 56 0 1373500944 11845920 6364272
18:45:37 94153 8192000000 10278242640 10722545664 1288054512 13.586 93 19 38 0 1313604576 21352448 4197616
18:48:11 98201 8192000000 10271938080 10729840640 1330861344 13.975 97 12 24 0 1345705232 11922000 2921888
18:50:49 89551 8192000000 10280887552 10711040000 1299448544 13.690 94 25 50 1 1312335744 7038400 5848800
18:53:25 18888 8192000000 10260863072 10704150528 1344429216 14.097 99 37 74 1 1368213312 15578528 8205568
18:54:46 35125 8192000000 10246483264 10692014080 1328261360 13.951 98 13 26 0 1339373152 8545456 2566336
18:56:36 58818 8192000000 10259167408 10715041792 1362500976 14.260 100 11 22 0 1376200976 11226720 2473280
18:58:41 63456 8192000000 10247656848 10700779520 1332540656 13.990 98 16 32 0 1346250784 10295680 3414448
19:00:49 56018 8192000000 10244225360 10699444224 1321559056 13.891 97 10 20 1 1333686240 9926992 2200192
19:02:45 21957 8192000000 10272407840 10714329088 1367666496 14.306 100 35 70 1 1384794608 9052688 8075424
19:04:14 28840 8192000000 10246248016 10684596224 1285707824 13.565 95 19 38 1 1300499888 9756928 5035136
19:05:52 71836 8192000000 10270864896 10718273536 1348644480 14.135 99 22 44 0 1371844704 17635840 5564384
19:08:04 43227 8192000000 10281352208 10730659840 1361536000 14.251 100 18 35 2 1389721120 23859008 4326112
19:10:12 50039 8192000000 10275985712 10718048256 1337112576 14.031 97 29 58 0 1353213648 10097632 6003440
19:12:06 112415 8192000000 10294777936 10749513728 1330703888 13.974 96 10 20 1 1347391696 14534576 2153232
19:15:06 58169 8192000000 10279879552 10736250880 1367910160 14.308 99 19 38 1 1386362544 13803472 4648912
19:17:10 33633 8192000000 10297747952 10753495040 1386017440 14.470 100 18 36 1 1406413584 16221936 4174208
19:18:44 94904 8192000000 10303046560 10763010048 1403777568 14.629 100 10 20 0 1414268656 8412112 2078976
19:21:25 54217 8192000000 10296434320 10756419584 1394819776 14.549 99 13 26 0 1405047360 6831600 3395984
19:23:34 51787 8192000000 10296940272 10760163328 1386293072 14.473 99 11 22 0 1399873472 10937168 2643232
19:25:46 123671 8192000000 10302263584 10769092608 1362988048 14.264 97 12 24 0 1376025632 10545856 2491728
19:28:51 82460 8192000000 10282855536 10739515392 1283175936 13.542 92 11 22 0 1297337280 12112096 2049248
19:31:17 42293 8192000000 10283647680 10727239680 1341458448 14.071 98 33 66 3 1358261728 9334256 7469024
19:33:02 82521 8192000000 10263043952 10706407424 1279263792 13.506 93 16 32 0 1295465712 12257424 3944496
19:35:26 96276 8192000000 10264436288 10714406912 1292775312 13.630 95 15 30 1 1311056096 14912144 3368640

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-12-2021 19:39:01 to 27-12-2021 19:39:16.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 118 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 27-12-2021 19:39:17 to 27-12-2021 19:39:18.

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          238360
MON$OLDEST_ACTIVE               238683
MON$OLDEST_SNAPSHOT             238683
MON$NEXT_TRANSACTION            238683
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                       2005760
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.9.33547 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.9.33547 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.9.33547 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.9.33547 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 ---
:255: 
:256: mscs-test-161.budzdorov.ru    Mon Dec 27 19:38:03 2021
:257:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:258: 
:259: 
:260: mscs-test-161.budzdorov.ru    Mon Dec 27 19:38:17 2021
:261:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:262: 
--- end of diff output ---

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

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