Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 22.04.2022 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 16282324 kB
MemAvailable 31506912 kB
Buffers 0 kB
Cached 15390768 kB
SwapCached 2648 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-04-22 19:38:14
DTS_END                         2022-04-22 19:38:14.8870
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/35692
TRN_ID                          248993
ATT_ID                          730
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: 9801.85 23600 1176222
customer order: creation 180.08 308 21609
customer order: refuse 15.59 343 1871
order to supplier: creation 48.02 1152 5762
order to supplier: removal 3.57 100488 428
invoice (draft): creation 49.19 3261 5903
invoice (draft): removal 55.56 844 6667
invoice accept: apply 44.27 15870 5312
invoice accept: cancel 49.91 792 5989
customer reserve: creation 4143.41 154 497209
customer reserve: removal 4898.28 56 587794
realization accept: apply 86.98 151 10438
realization accept: cancel 90.38 100 10845
payment from customer: creation 63.00 100 7560
payment from customer: removal 6.23 57 748
payment to supplier: creation 50.88 112 6105
payment to supplier: removal 3.97 83 476
service: total inventory turnovers 5.14 939 617
service: total monetary turnovers 6.58 25 789
service: aggregate perf. data 0.80 22740 96
service: refresh index statistics 0.03 324422 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 22-04-2022 19:39:15 to 22-04-2022 19:39:17.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2185
TEST_TIME 2 4063
TEST_TIME 3 4318
TEST_TIME 4 4581
TEST_TIME 5 4603
TEST_TIME 6 4405
TEST_TIME 7 4715
TEST_TIME 8 4736
TEST_TIME 9 5237
TEST_TIME 10 5666
TEST_TIME 11 6273
TEST_TIME 12 6772
TEST_TIME 13 6866
TEST_TIME 14 7272
TEST_TIME 15 7436
TEST_TIME 16 7998
TEST_TIME 17 8305
TEST_TIME 18 8769
TEST_TIME 19 8997
TEST_TIME 20 9010
TEST_TIME 21 9073
TEST_TIME 22 9456
TEST_TIME 23 9335
TEST_TIME 24 9431
TEST_TIME 25 10159
TEST_TIME 26 10034
TEST_TIME 27 10850
TEST_TIME 28 11473
TEST_TIME 29 11478
TEST_TIME 30 11359
TEST_TIME 31 11223
TEST_TIME 32 11288
TEST_TIME 33 11171
TEST_TIME 34 11074
TEST_TIME 35 11074
TEST_TIME 36 10909
TEST_TIME 37 10880
TEST_TIME 38 10851
TEST_TIME 39 10926
TEST_TIME 40 10912
TEST_TIME 41 11004
TEST_TIME 42 11082
TEST_TIME 43 10991
TEST_TIME 44 10826
TEST_TIME 45 10693
TEST_TIME 46 10603
TEST_TIME 47 10662
TEST_TIME 48 10576
TEST_TIME 49 10459
TEST_TIME 50 10390
TEST_TIME 51 10418
TEST_TIME 52 10313
TEST_TIME 53 10386
TEST_TIME 54 10462
TEST_TIME 55 10348
TEST_TIME 56 10305
TEST_TIME 57 10217
TEST_TIME 58 10108
TEST_TIME 59 10110
TEST_TIME 60 10158
TEST_TIME 61 10221
TEST_TIME 62 10167
TEST_TIME 63 10120
TEST_TIME 64 10256
TEST_TIME 65 10228
TEST_TIME 66 10179
TEST_TIME 67 10264
TEST_TIME 68 10262
TEST_TIME 69 10202
TEST_TIME 70 10288
TEST_TIME 71 10273
TEST_TIME 72 10281
TEST_TIME 73 10313
TEST_TIME 74 10333
TEST_TIME 75 10385
TEST_TIME 76 10408
TEST_TIME 77 10300
TEST_TIME 78 10262
TEST_TIME 79 10241
TEST_TIME 80 10268
TEST_TIME 81 10220
TEST_TIME 82 10196
TEST_TIME 83 10174
TEST_TIME 84 10153
TEST_TIME 85 10169
TEST_TIME 86 10077
TEST_TIME 87 10021
TEST_TIME 88 10031
TEST_TIME 89 9994
TEST_TIME 90 9982
TEST_TIME 91 9987
TEST_TIME 92 9967
TEST_TIME 93 10034
TEST_TIME 94 10053
TEST_TIME 95 10017
TEST_TIME 96 10068
TEST_TIME 97 10033
TEST_TIME 98 10060
TEST_TIME 99 10061
TEST_TIME 100 10042
TEST_TIME 101 10077
TEST_TIME 102 10125
TEST_TIME 103 10160
TEST_TIME 104 10134
TEST_TIME 105 10141
TEST_TIME 106 10052
TEST_TIME 107 10049
TEST_TIME 108 9999
TEST_TIME 109 9977
TEST_TIME 110 9925
TEST_TIME 111 9935
TEST_TIME 112 9901
TEST_TIME 113 9860
TEST_TIME 114 9827
TEST_TIME 115 9863
TEST_TIME 116 9861
TEST_TIME 117 9833
TEST_TIME 118 9824
TEST_TIME 119 9871
TEST_TIME 120 9803

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 22-04-2022 19:39:19 to 22-04-2022 19:39:22.

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1635055 1635055 0 0.00 0 18478 6 0 0
doc_list_biud 1635055 1635055 0 0.00 0 56609 26 0 0
sp_add_invoice_to_stock 5417 5312 105 1.93 30 231137 15871 0 105
sp_cancel_adding_invoice 5989 5989 0 0.00 4 70367 793 0 0
sp_cancel_client_order 1871 1871 0 0.00 4 9456 344 0 0
sp_cancel_customer_reserve 587794 587794 0 0.00 1 52692 56 0 0
sp_cancel_pay_from_customer 748 748 0 0.00 3 3068 58 0 0
sp_cancel_pay_to_supplier 476 476 0 0.00 3 7292 83 0 0
sp_cancel_supplier_invoice 6678 6667 11 0.16 1 56616 845 0 11
sp_cancel_supplier_order 428 428 0 0.00 8 1263485 100489 0 0
sp_cancel_write_off 10845 10845 0 0.00 1 16122 101 0 0
sp_client_order 21609 21609 0 0.00 3 15867 308 0 0
sp_customer_reserve 497209 497209 0 0.00 0 107842 155 0 0
sp_fill_shopping_cart 33232 33232 0 0.00 1 15844 35 0 0
sp_fill_shopping_cart_clo_res 497206 497206 0 0.00 0 21729 13 0 0
sp_get_clo_for_invoice 5307 5307 0 0.00 0 40761 278 0 0
sp_kill_cost_storno 18058 18058 0 0.00 0 10319 53 0 0
sp_kill_qstorno_ret_qs2qd 1193528 1193528 0 0.00 0 56605 31 0 0
sp_kill_qty_storno 629338 629338 0 0.00 0 56607 67 0 0
sp_lock_selected_doc 22018 22018 0 0.00 0 7087 4 0 0
sp_make_cost_storno 29409 29409 0 0.00 0 18475 47 0 0
sp_multiply_rows_for_pdistr 29409 29409 0 0.00 0 7994 26 0 0
sp_multiply_rows_for_qdistr 21609 21609 0 0.00 0 11710 135 0 0
sp_pay_from_customer 7560 7560 0 0.00 4 13476 100 0 0
sp_pay_to_supplier 6105 6105 0 0.00 4 12808 113 0 0
sp_payment_common 13665 13665 0 0.00 1 13471 23 0 0
sp_qd_handle_on_cancel_clo 1871 1871 0 0.00 0 9448 314 0 0
sp_qd_handle_on_invoice_upd_sts 11295 11295 0 0.00 0 12430 329 0 0
sp_qd_handle_on_reserve_upd_sts 10438 10438 0 0.00 0 17087 52 0 0
sp_reserve_write_off 10438 10438 0 0.00 6 18491 152 0 0
sp_supplier_invoice 5903 5903 0 0.00 6 83537 3262 0 0
sp_supplier_order 5762 5762 0 0.00 5 26547 1153 0 0
srv_aggregate_perf_data 96 96 0 0.00 2591 497346 22740 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 2629 162362 47521 0 0
srv_find_qd_qs_mism 1124774 1124774 0 0.00 0 17977 5 0 0
srv_make_invnt_saldo 617 617 0 0.00 9 27573 939 0 0
srv_make_money_saldo 789 789 0 0.00 1 1258 26 0 0
srv_recalc_idx_stat 4 4 0 0.00 113708 632505 324422 0 0
t$perf-abend:sp_add_invoice_to_stock 105 105 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5312 5312 0 0.00 0 7474 151 0 0
t$perf-norm:sp_cancel_adding_invoice 430 430 0 0.00 1 1888 71 0 0
t$perf-norm:sp_cancel_client_order 1871 1871 0 0.00 0 7718 14 0 0
t$perf-norm:sp_cancel_customer_reserve 762 762 0 0.00 0 1096 8 0 0
t$perf-norm:sp_cancel_pay_from_customer 748 748 0 0.00 0 1134 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 476 476 0 0.00 0 1392 9 0 0
t$perf-norm:sp_cancel_supplier_invoice 440 440 0 0.00 0 890 17 0 0
t$perf-norm:sp_cancel_supplier_order 428 428 0 0.00 0 24695 1787 0 0
t$perf-norm:sp_cancel_write_off 1120 1120 0 0.00 0 2817 12 0 0
t$perf-norm:sp_client_order 21609 21609 0 0.00 0 9408 15 0 0
t$perf-norm:sp_customer_reserve 1060 1060 0 0.00 0 7583 10 0 0
t$perf-norm:sp_pay_from_customer 7560 7560 0 0.00 0 5000 8 0 0
t$perf-norm:sp_pay_to_supplier 6105 6105 0 0.00 0 3651 10 0 0
t$perf-norm:sp_reserve_write_off 10438 10438 0 0.00 0 14255 11 0 0
t$perf-norm:sp_supplier_invoice 5903 5903 0 0.00 0 5375 15 0 0
t$perf-norm:sp_supplier_order 5762 5762 0 0.00 0 7764 15 0 0
t$perf-norm:srv_aggregate_perf_data 96 96 0 0.00 0 3287 44 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 25 1 0 0
t$perf-norm:srv_make_invnt_saldo 617 617 0 0.00 0 3504 23 0 0
t$perf-norm:srv_make_money_saldo 789 789 0 0.00 0 677 12 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 38 12 0 0
v_add_invoice_to_stock 10820 10610 210 1.94 0 5658 17 0 210
v_all_customers 39840 39840 0 0.00 0 103 1 0 0
v_all_suppliers 32236 32236 0 0.00 0 20 1 0 0
v_all_wares 43218 43218 0 0.00 0 103 1 0 0
v_cancel_adding_invoice 860 860 0 0.00 0 265 5 0 0
v_cancel_client_order 3742 3742 0 0.00 0 2277 6 0 0
v_cancel_customer_prepayment 1496 1496 0 0.00 0 1757 5 0 0
v_cancel_customer_reserve 1524 1524 0 0.00 0 2161 7 0 0
v_cancel_payment_to_supplier 952 952 0 0.00 0 2192 6 0 0
v_cancel_supplier_invoice 902 880 22 2.43 0 1596 15 0 22
v_cancel_supplier_order 856 856 0 0.00 0 3318 9 0 0
v_cancel_write_off 2240 2240 0 0.00 0 2762 15 0 0
v_max_id_clo_ord 5762 5762 0 0.00 0 706 1 0 0
v_max_id_clo_res 1060 1060 0 0.00 0 2297 19 0 0
v_max_id_ord_sup 5861 5861 0 0.00 0 2096 2 0 0
v_max_non_paid_invoice 1610 1610 0 0.00 0 450 2 0 0
v_max_non_paid_realizn 3778 3778 0 0.00 0 4198 5 0 0
v_min_id_clo_ord 5762 5762 0 0.00 0 2090 4 0 0
v_min_id_clo_res 1060 1060 0 0.00 0 8745 15 0 0
v_min_id_ord_sup 5861 5861 0 0.00 0 6232 3 0 0
v_min_non_paid_invoice 6105 6105 0 0.00 0 12795 13 0 0
v_min_non_paid_realizn 7560 7560 0 0.00 0 13465 17 0 0
v_our_firm 10942 10942 0 0.00 0 18 1 0 0
v_reserve_write_off 20876 20876 0 0.00 0 11687 6 0 0
x_lock_depdocs_on_canc_invoice 12216 12216 0 0.00 0 16844 128 0 0
x_lock_depdocs_on_canc_sup_ord 428 428 0 0.00 0 11651 373 0 0
x_make_qstorno_1000_1200 5762 5762 0 0.00 1 26524 1080 0 0
x_make_qstorno_1200_2000 5903 5903 0 0.00 3 78739 3125 0 0
x_make_qstorno_2100_3300 497209 497209 0 0.00 0 107841 140 0 0

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

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
17:08:18 28 8192000000 8812143712 8827609088 15226192 0.185 4 4 6 0 15467408 91376 149840
17:09:19 26697 8192000000 9906391056 10217164800 982158848 10.705 100 82 162 1 1009322208 5505360 21658000
17:10:51 20811 8192000000 9952201936 10276360192 1036528208 11.231 100 90 180 0 1067145712 7836176 22781328
17:12:14 19535 8192000000 9984827328 10320371712 1070804048 11.560 100 77 154 0 1099902112 9946368 19151696
17:13:40 15269 8192000000 10046105584 10396418048 1132688096 12.147 100 79 156 1 1162180128 11169472 18322560
17:14:59 9431 8192000000 10092879456 10452254720 1174363808 12.538 100 78 156 2 1204384944 12577984 17443152
17:16:10 5121 8192000000 10129994160 10497724416 1218302752 12.946 100 83 166 0 1250962400 14388256 18271392
17:17:18 2671 8192000000 10170381824 10547642368 1257630320 13.308 100 86 171 2 1288915520 12674176 18611024
17:18:22 2257 8192000000 10207436160 10588987392 1287858800 13.585 100 87 174 1 1320910144 13480688 19570656
17:19:25 31148 8192000000 10213594000 10608746496 1303198928 13.724 100 58 114 0 1326883184 9632096 14052160
17:20:57 3373 8192000000 10203654048 10611601408 1314032656 13.823 100 37 74 2 1329902960 7949648 7920656
17:22:01 5768 8192000000 10213997664 10629955584 1328528208 13.954 100 30 61 2 1346659536 11310368 6820960
17:23:08 6536 8192000000 10220128528 10642800640 1336168448 14.023 100 17 32 0 1345786944 5325696 4292800
17:24:15 35491 8192000000 10251793968 10658934784 1342927296 14.084 100 54 108 1 1361274144 5384336 12962512
17:25:51 5710 8192000000 10238309568 10662871040 1346572128 14.117 100 29 58 2 1359471168 6850912 6048128
17:26:59 7839 8192000000 10237613776 10649116672 1328828640 13.957 99 19 38 0 1338798112 5244016 4725456
17:28:07 55985 8192000000 10180417088 10588594176 1235118352 13.101 94 36 71 3 1248609232 4986000 8504880
17:30:04 4999 8192000000 10177445472 10577833984 1255759184 13.291 98 26 52 1 1268497568 6742928 5995456
17:31:12 7910 8192000000 10150893920 10558033920 1254168464 13.277 99 17 34 2 1263852688 5979248 3704976
17:32:22 14941 8192000000 10160666336 10563436544 1236544400 13.114 97 24 48 0 1247654176 5149808 5959968
17:33:39 20905 8192000000 10150809584 10550317056 1203965632 12.813 95 20 40 0 1212919312 4959600 3994080
17:35:01 6130 8192000000 10182151088 10592505856 1289614144 13.601 100 23 46 0 1301037872 6395472 5028256
17:36:12 11943 8192000000 10200586416 10611830784 1305280160 13.743 100 27 54 2 1317012016 5495392 6236464
17:37:26 96938 8192000000 10235955040 10641727488 1325170544 13.923 100 43 86 1 1343495872 7193408 11131920
17:40:12 25518 8192000000 10267086192 10679865344 1349903136 14.147 100 54 108 0 1376357632 14229088 12225408
17:41:40 49591 8192000000 10280776256 10704142336 1363118320 14.265 100 45 90 3 1391555184 17943680 10493184
17:43:35 12570 8192000000 10310704944 10727112704 1387401584 14.483 100 47 94 0 1420340880 21656320 11282976
17:44:50 20423 8192000000 10314446304 10733953024 1390577952 14.511 100 44 88 3 1426448176 25544784 10325440
17:46:16 6321 8192000000 10329559712 10745114624 1399773504 14.593 100 67 134 1 1440624944 25878112 14973328
17:47:25 8228 8192000000 10338467136 10755231744 1399907808 14.594 100 74 148 1 1452105808 33536384 18661616
17:48:42 2628 8192000000 10357327312 10775695360 1413409664 14.714 100 80 160 1 1469633168 35097840 21125664
17:49:47 4317 8192000000 10356568176 10765283328 1396179408 14.561 100 77 154 1 1453719312 38792128 18747776
17:50:56 4376 8192000000 10335238176 10750816256 1383889536 14.451 100 62 121 0 1440704400 41488448 15326416
17:52:06 3765 8192000000 10323681424 10732523520 1357511600 14.215 97 84 167 1 1418371024 39462576 21396848
17:53:17 3303 8192000000 10346023040 10755616768 1375703648 14.378 99 84 168 3 1441920608 45127040 21089920
17:54:26 3577 8192000000 10358773792 10769977344 1400579216 14.600 100 86 172 4 1458968512 37446768 20942528
17:55:35 9300 8192000000 10349313152 10775085056 1388532480 14.493 99 54 108 1 1445073376 42588512 13952384
17:57:07 7454 8192000000 10328869392 10756493312 1387175792 14.481 100 48 96 3 1439895872 40394240 12325840
17:58:19 14951 8192000000 10338468048 10770026496 1388789392 14.495 99 40 80 1 1440535584 40376576 11369616
17:59:37 19842 8192000000 10342679312 10775322624 1394036880 14.542 100 45 90 0 1453803296 47158400 12608016
18:01:00 11179 8192000000 10329088992 10759213056 1362572288 14.260 99 36 71 0 1418157808 45586544 9998976
18:02:14 15510 8192000000 10318273056 10759847936 1365698576 14.288 100 26 52 0 1425911328 52803520 7409232
18:03:35 25764 8192000000 10309328192 10744381440 1354770736 14.190 99 25 49 2 1406245872 44711184 6763952
18:05:27 36527 8192000000 10288703376 10744823808 1356268496 14.204 100 16 32 0 1402580544 41438432 4873616
18:07:07 33878 8192000000 10266735632 10721730560 1337572544 14.036 98 12 24 0 1352708864 12659712 2476608
18:08:45 65349 8192000000 10279525216 10729807872 1339296288 14.051 98 14 28 0 1357159216 14956352 2906576
18:11:32 120618 8192000000 10279614224 10733924352 1319336272 13.871 96 14 28 1 1333437920 11308368 2793280
18:14:34 26759 8192000000 10286475168 10729697280 1370402112 14.331 100 10 20 0 1382146720 9855952 1888656
18:16:01 58151 8192000000 10290487504 10743140352 1336053552 14.022 97 8 16 0 1359442896 21687952 1701392
18:18:13 162362 8192000000 10297680704 10759360512 1357037664 14.211 98 16 31 0 1376505792 16177648 3290480
18:21:56 75989 8192000000 10313146752 10759053312 1394216704 14.543 100 44 88 1 1415396048 11061168 10118176
18:24:13 92330 8192000000 10300775936 10760683520 1354475056 14.188 97 14 28 1 1368699184 11096240 3127888
18:26:47 91659 8192000000 10285833904 10741084160 1352444768 14.169 97 34 68 2 1368297568 8188448 7664352
18:30:06 48263 8192000000 10302897088 10752016384 1365482224 14.287 98 24 48 1 1383481328 11938656 6060448
18:31:57 9041 8192000000 10290247376 10741460992 1356708368 14.208 98 32 65 1 1376250400 12100704 7441328
18:33:09 34958 8192000000 10283057120 10750173184 1371312032 14.339 99 4 8 0 1390642928 18468096 862800
18:34:44 132970 8192000000 10298508448 10758266880 1355461376 14.197 97 12 24 0 1371284928 13251568 2571984
18:38:06 59523 8192000000 10297719536 10759802880 1358803232 14.227 97 33 67 2 1382361264 15932592 7625440
18:40:12 45907 8192000000 10274006336 10733289472 1297337472 13.671 93 10 20 0 1312289440 12968400 1983568
18:41:59 89823 8192000000 10298591184 10754121728 1340686192 14.064 96 18 36 1 1361113824 16668720 3758912
18:44:39 38601 8192000000 10297187248 10746331136 1344257008 14.096 97 42 84 0 1366768784 12973424 9538352
18:46:28 40971 8192000000 10313130048 10767376384 1393583280 14.538 100 30 60 0 1413814928 13475520 6756128
18:48:10 44242 8192000000 10286068224 10731646976 1319183376 13.869 95 23 46 0 1335784880 11463376 5138128
18:50:00 45180 8192000000 10282385376 10727772160 1339374896 14.052 98 20 40 0 1356032704 12093808 4564000
18:51:58 32293 8192000000 10276783328 10719289344 1338665936 14.045 98 10 20 0 1349433520 8657776 2109808
18:53:30 67275 8192000000 10244418464 10699206656 1263953600 13.366 94 14 28 1 1286407344 19494480 2959264
18:55:40 125942 8192000000 10246509920 10701549568 1296468288 13.663 96 28 56 0 1313723152 10057776 7197088
18:58:48 16374 8192000000 10253926528 10711154688 1334282112 14.006 99 11 22 0 1357930896 20838032 2810752
19:00:04 107216 8192000000 10262994544 10727399424 1338523648 14.044 99 7 14 1 1356841312 16382624 1935040
19:02:52 52427 8192000000 10251657184 10711277568 1322750992 13.902 97 22 43 0 1342244800 14617232 4876576
19:04:52 63322 8192000000 10252730400 10711621632 1343221952 14.086 99 11 22 1 1363345312 16336688 3786672
19:06:57 53712 8192000000 10256040896 10714771456 1348959296 14.138 99 12 25 2 1362215568 11449088 1807184
19:08:52 37870 8192000000 10269128752 10728837120 1358655408 14.225 98 15 30 0 1369786432 7946976 3184048
19:10:40 38685 8192000000 10285860256 10746580992 1361007312 14.246 98 23 46 1 1375338896 9525184 4806400
19:13:14 36085 8192000000 10313862992 10762932224 1386491584 14.475 99 26 52 0 1401012464 8933680 5587200
19:14:50 35853 8192000000 10299272800 10756550656 1391805520 14.522 100 20 40 1 1404484416 8125472 4553424
19:16:26 54186 8192000000 10293063984 10751143936 1384737200 14.459 100 21 41 1 1400745616 11221088 4787328
19:18:21 91436 8192000000 10286267136 10744483840 1344076496 14.094 97 20 40 2 1361736832 13506944 4153392
19:20:55 74392 8192000000 10272933184 10734628864 1344304176 14.096 97 11 22 0 1360555024 13602448 2648400
19:23:10 117255 8192000000 10281730544 10732326912 1372050256 14.345 100 13 26 1 1390510112 15868160 2591696
19:26:08 39259 8192000000 10270187680 10734555136 1349605376 14.144 98 7 13 0 1363308976 12569552 1134048
19:27:49 29981 8192000000 10273194096 10729025536 1371134112 14.337 100 13 26 0 1383546720 9215024 3197584
19:29:22 54823 8192000000 10248258384 10708111360 1311578736 13.800 96 15 30 0 1325076032 9670688 3826608
19:31:17 83931 8192000000 10260360224 10712403968 1355156400 14.194 99 14 29 0 1368712416 9596336 3959680
19:33:41 38218 8192000000 10271378384 10724438016 1363054448 14.265 100 9 18 0 1373853248 9082656 1716144
19:35:22 44934 8192000000 10270623456 10722267136 1314931072 13.831 96 27 54 0 1331109280 10114448 6063760
19:37:10 47184 8192000000 10230175728 10686382080 1275511440 13.472 94 18 36 0 1288202432 8899008 3791984

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 22-04-2022 19:39:29 to 22-04-2022 19:39:44.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 105 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 22-04-2022 19:39:45 to 22-04-2022 19:39: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          247954
MON$OLDEST_ACTIVE               249244
MON$OLDEST_SNAPSHOT             249244
MON$NEXT_TRANSACTION            249244
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                       2065056
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.33577 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33577 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33577 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33577 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 ---
:1469: 
:1470: mscs-test-161.budzdorov.ru    Fri Apr 22 19:38:19 2022
:1471:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1472: 
--- end of diff output ---

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

22.04.22 19:39: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-04-22 19:39:50 No severe PSQL-related problems occured
22.04.22 19:39:52. End of report.