Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 16.10.2022 19:40
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 15593628 kB
MemAvailable 30528260 kB
Buffers 0 kB
Cached 16154244 kB
SwapCached 2172 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.11
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-10-16 19:38:27
DTS_END                         2022-10-16 19:38:27.4070
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(69:14) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/45576
TRN_ID                          239565
ATT_ID                          697
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: 9194.17 19730 1103300
customer order: creation 173.54 353 20825
customer order: refuse 14.63 331 1755
order to supplier: creation 45.63 1296 5475
order to supplier: removal 3.56 98924 427
invoice (draft): creation 45.93 3383 5512
invoice (draft): removal 51.41 878 6169
invoice accept: apply 42.85 18766 5142
invoice accept: cancel 46.58 796 5590
customer reserve: creation 3923.30 189 470796
customer reserve: removal 4552.39 60 546287
realization accept: apply 82.84 163 9941
realization accept: cancel 82.35 107 9882
payment from customer: creation 58.77 106 7052
payment from customer: removal 5.98 70 717
payment to supplier: creation 49.25 145 5910
payment to supplier: removal 3.21 63 385
service: total inventory turnovers 4.94 1040 593
service: total monetary turnovers 6.30 25 756
service: aggregate perf. data 0.68 25912 82
service: refresh index statistics 0.03 241998 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 16-10-2022 19:40:52 to 16-10-2022 19:40:54.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2195
TEST_TIME 2 3272
TEST_TIME 3 4126
TEST_TIME 4 4508
TEST_TIME 5 5081
TEST_TIME 6 5433
TEST_TIME 7 5487
TEST_TIME 8 5633
TEST_TIME 9 5818
TEST_TIME 10 5883
TEST_TIME 11 6186
TEST_TIME 12 6396
TEST_TIME 13 6342
TEST_TIME 14 6152
TEST_TIME 15 6368
TEST_TIME 16 7104
TEST_TIME 17 7206
TEST_TIME 18 7182
TEST_TIME 19 7579
TEST_TIME 20 7704
TEST_TIME 21 7986
TEST_TIME 22 8092
TEST_TIME 23 8539
TEST_TIME 24 8591
TEST_TIME 25 8596
TEST_TIME 26 8600
TEST_TIME 27 9441
TEST_TIME 28 10038
TEST_TIME 29 10647
TEST_TIME 30 10495
TEST_TIME 31 10246
TEST_TIME 32 10264
TEST_TIME 33 10049
TEST_TIME 34 10043
TEST_TIME 35 10086
TEST_TIME 36 9933
TEST_TIME 37 9933
TEST_TIME 38 9934
TEST_TIME 39 9823
TEST_TIME 40 9745
TEST_TIME 41 9541
TEST_TIME 42 9532
TEST_TIME 43 9559
TEST_TIME 44 9459
TEST_TIME 45 9562
TEST_TIME 46 9394
TEST_TIME 47 9364
TEST_TIME 48 9302
TEST_TIME 49 9229
TEST_TIME 50 9180
TEST_TIME 51 9207
TEST_TIME 52 9110
TEST_TIME 53 8995
TEST_TIME 54 9013
TEST_TIME 55 9127
TEST_TIME 56 9096
TEST_TIME 57 9159
TEST_TIME 58 9157
TEST_TIME 59 9257
TEST_TIME 60 9304
TEST_TIME 61 9331
TEST_TIME 62 9300
TEST_TIME 63 9337
TEST_TIME 64 9338
TEST_TIME 65 9342
TEST_TIME 66 9370
TEST_TIME 67 9396
TEST_TIME 68 9365
TEST_TIME 69 9382
TEST_TIME 70 9534
TEST_TIME 71 9418
TEST_TIME 72 9332
TEST_TIME 73 9364
TEST_TIME 74 9294
TEST_TIME 75 9261
TEST_TIME 76 9262
TEST_TIME 77 9220
TEST_TIME 78 9169
TEST_TIME 79 9115
TEST_TIME 80 9072
TEST_TIME 81 9042
TEST_TIME 82 9091
TEST_TIME 83 9141
TEST_TIME 84 9133
TEST_TIME 85 9167
TEST_TIME 86 9168
TEST_TIME 87 9133
TEST_TIME 88 9236
TEST_TIME 89 9215
TEST_TIME 90 9188
TEST_TIME 91 9199
TEST_TIME 92 9223
TEST_TIME 93 9259
TEST_TIME 94 9312
TEST_TIME 95 9316
TEST_TIME 96 9297
TEST_TIME 97 9239
TEST_TIME 98 9169
TEST_TIME 99 9202
TEST_TIME 100 9206
TEST_TIME 101 9179
TEST_TIME 102 9176
TEST_TIME 103 9152
TEST_TIME 104 9187
TEST_TIME 105 9150
TEST_TIME 106 9113
TEST_TIME 107 9089
TEST_TIME 108 9158
TEST_TIME 109 9139
TEST_TIME 110 9118
TEST_TIME 111 9169
TEST_TIME 112 9177
TEST_TIME 113 9265
TEST_TIME 114 9236
TEST_TIME 115 9224
TEST_TIME 116 9252
TEST_TIME 117 9241
TEST_TIME 118 9314
TEST_TIME 119 9269
TEST_TIME 120 9196

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 16-10-2022 19:40:56 to 16-10-2022 19:40:59.

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 1540079 1540079 0 0.00 0 31890 7 0 0
doc_list_biud 1540079 1540079 0 0.00 0 98390 27 0 0
sp_add_invoice_to_stock 5279 5142 137 2.59 38 252665 18767 0 137
sp_cancel_adding_invoice 5590 5590 0 0.00 3 83410 797 0 0
sp_cancel_client_order 1755 1755 0 0.00 4 11716 331 0 0
sp_cancel_customer_reserve 546287 546287 0 0.00 0 98395 60 0 0
sp_cancel_pay_from_customer 717 717 0 0.00 2 4519 70 0 0
sp_cancel_pay_to_supplier 385 385 0 0.00 3 4012 63 0 0
sp_cancel_supplier_invoice 6188 6169 19 0.30 2 17358 879 0 19
sp_cancel_supplier_order 427 427 0 0.00 5 990008 98925 0 0
sp_cancel_write_off 9882 9882 0 0.00 1 15332 107 0 0
sp_client_order 20825 20825 0 0.00 3 24512 353 0 0
sp_customer_reserve 470796 470796 0 0.00 0 47789 190 0 0
sp_fill_shopping_cart 31807 31807 0 0.00 1 11277 35 0 0
sp_fill_shopping_cart_clo_res 470794 470794 0 0.00 0 16795 13 0 0
sp_get_clo_for_invoice 5140 5140 0 0.00 0 33590 321 0 0
sp_kill_cost_storno 16574 16574 0 0.00 0 31878 55 0 0
sp_kill_qstorno_ret_qs2qd 1109052 1109052 0 0.00 0 98376 33 0 0
sp_kill_qty_storno 585191 585191 0 0.00 0 98390 71 0 0
sp_lock_selected_doc 20920 20920 0 0.00 0 8095 4 0 0
sp_make_cost_storno 28043 28043 0 0.00 0 30655 53 0 0
sp_multiply_rows_for_pdistr 28043 28043 0 0.00 0 6705 23 0 0
sp_multiply_rows_for_qdistr 20825 20825 0 0.00 0 21920 154 0 0
sp_pay_from_customer 7052 7052 0 0.00 4 17627 107 0 0
sp_pay_to_supplier 5910 5910 0 0.00 4 49989 145 0 0
sp_payment_common 12962 12962 0 0.00 1 49668 29 0 0
sp_qd_handle_on_cancel_clo 1755 1755 0 0.00 0 11706 300 0 0
sp_qd_handle_on_invoice_upd_sts 10730 10730 0 0.00 0 30566 318 0 0
sp_qd_handle_on_reserve_upd_sts 9941 9941 0 0.00 0 25915 54 0 0
sp_reserve_write_off 9941 9941 0 0.00 6 25943 164 0 0
sp_supplier_invoice 5512 5512 0 0.00 5 99787 3384 0 0
sp_supplier_order 5475 5475 0 0.00 4 74573 1297 0 0
srv_aggregate_perf_data 82 82 0 0.00 2254 443395 25912 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 1636 199072 49108 0 0
srv_find_qd_qs_mism 1055606 1055606 0 0.00 0 14168 6 0 0
srv_make_invnt_saldo 593 593 0 0.00 25 76197 1040 0 0
srv_make_money_saldo 756 756 0 0.00 0 1481 26 0 0
srv_recalc_idx_stat 4 4 0 0.00 172981 409301 241998 0 0
t$perf-abend:sp_add_invoice_to_stock 137 137 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 19 19 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5142 5142 0 0.00 0 14832 163 0 0
t$perf-norm:sp_cancel_adding_invoice 435 435 0 0.00 0 7820 85 0 0
t$perf-norm:sp_cancel_client_order 1755 1755 0 0.00 0 10375 21 0 0
t$perf-norm:sp_cancel_customer_reserve 711 711 0 0.00 0 1358 8 0 0
t$perf-norm:sp_cancel_pay_from_customer 717 717 0 0.00 0 806 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 385 385 0 0.00 0 2176 8 0 0
t$perf-norm:sp_cancel_supplier_invoice 410 410 0 0.00 0 2315 19 0 0
t$perf-norm:sp_cancel_supplier_order 427 427 0 0.00 0 29371 1580 0 0
t$perf-norm:sp_cancel_write_off 999 999 0 0.00 0 2939 13 0 0
t$perf-norm:sp_client_order 20825 20825 0 0.00 0 9954 15 0 0
t$perf-norm:sp_customer_reserve 997 997 0 0.00 0 2208 7 0 0
t$perf-norm:sp_pay_from_customer 7052 7052 0 0.00 0 13788 9 0 0
t$perf-norm:sp_pay_to_supplier 5910 5910 0 0.00 0 11425 13 0 0
t$perf-norm:sp_reserve_write_off 9941 9941 0 0.00 0 3443 9 0 0
t$perf-norm:sp_supplier_invoice 5512 5512 0 0.00 0 9563 20 0 0
t$perf-norm:sp_supplier_order 5475 5475 0 0.00 0 9798 18 0 0
t$perf-norm:srv_aggregate_perf_data 82 82 0 0.00 0 123 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 45 1 0 0
t$perf-norm:srv_make_invnt_saldo 593 593 0 0.00 0 1535 22 0 0
t$perf-norm:srv_make_money_saldo 756 756 0 0.00 0 1296 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 2 2 0 0
v_add_invoice_to_stock 10554 10280 274 2.59 0 4467 16 0 274
v_all_customers 37896 37896 0 0.00 0 59 1 0 0
v_all_suppliers 30338 30338 0 0.00 0 17 1 0 0
v_all_wares 41650 41650 0 0.00 0 168 1 0 0
v_cancel_adding_invoice 870 870 0 0.00 0 8706 28 0 0
v_cancel_client_order 3510 3510 0 0.00 0 4474 6 0 0
v_cancel_customer_prepayment 1434 1434 0 0.00 0 3677 7 0 0
v_cancel_customer_reserve 1422 1422 0 0.00 0 2100 7 0 0
v_cancel_payment_to_supplier 770 770 0 0.00 0 3997 13 0 0
v_cancel_supplier_invoice 858 820 38 4.42 0 3286 27 0 38
v_cancel_supplier_order 854 854 0 0.00 0 3113 7 0 0
v_cancel_write_off 1998 1998 0 0.00 0 2341 12 0 0
v_max_id_clo_ord 5475 5475 0 0.00 0 1254 2 0 0
v_max_id_clo_res 997 997 0 0.00 0 21253 38 0 0
v_max_id_ord_sup 5505 5505 0 0.00 0 1950 2 0 0
v_max_non_paid_invoice 1721 1721 0 0.00 0 323 2 0 0
v_max_non_paid_realizn 3783 3783 0 0.00 0 5937 5 0 0
v_min_id_clo_ord 5475 5475 0 0.00 0 4292 5 0 0
v_min_id_clo_res 997 997 0 0.00 0 22303 46 0 0
v_min_id_ord_sup 5505 5505 0 0.00 0 5455 7 0 0
v_min_non_paid_invoice 5910 5910 0 0.00 0 49665 25 0 0
v_min_non_paid_realizn 7052 7052 0 0.00 0 11423 19 0 0
v_our_firm 10292 10292 0 0.00 0 13 1 0 0
v_reserve_write_off 19882 19882 0 0.00 0 4975 7 0 0
x_lock_depdocs_on_canc_invoice 11349 11349 0 0.00 0 25629 111 0 0
x_lock_depdocs_on_canc_sup_ord 427 427 0 0.00 0 22892 411 0 0
x_make_qstorno_1000_1200 5475 5475 0 0.00 1 74562 1226 0 0
x_make_qstorno_1200_2000 5512 5512 0 0.00 2 96373 3252 0 0
x_make_qstorno_2100_3300 470796 470796 0 0.00 0 42262 174 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 16-10-2022 19:41:00 to 16-10-2022 19:41:04.

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:31 6 8192000000 8804629152 8817971200 10004032 0.121 3 3 3 0 10156816 77840 74944
17:09:31 19411 8192000000 9903802048 10211639296 985862256 10.741 100 91 182 0 1015099904 5831408 23406240
17:10:55 22284 8192000000 9941469888 10262642688 1021834112 11.090 100 96 192 0 1055215472 8312160 25069200
17:12:24 14660 8192000000 9977643216 10308952064 1057560576 11.433 100 85 170 0 1088497936 9820112 21117248
17:13:47 11072 8192000000 10020046176 10364497920 1101964848 11.856 100 82 161 0 1132268208 10852192 19451168
17:15:03 7735 8192000000 10072326416 10429501440 1158047600 12.385 100 79 157 0 1187960640 11915888 17997152
17:16:15 4550 8192000000 10110804240 10481119232 1205349744 12.826 100 75 148 1 1234613088 12745824 16517520
17:17:21 2134 8192000000 10159792816 10532052992 1244655584 13.189 100 80 158 2 1275079216 13164864 17258768
17:18:24 1230 8192000000 10186548192 10567499776 1273071200 13.450 100 85 170 0 1306436720 15094528 18270992
17:19:26 1470 8192000000 10211877472 10598907904 1299573008 13.691 100 83 167 1 1331774816 14086752 18115056
17:20:29 5791 8192000000 10207462416 10608414720 1306988960 13.759 100 58 117 1 1332752624 12401696 13361968
17:21:36 6268 8192000000 10203409328 10623135744 1319879232 13.876 100 21 43 0 1331652368 7253872 4519264
17:22:45 6807 8192000000 10210008768 10629435392 1325155008 13.923 100 18 36 0 1334660416 5305296 4200112
17:23:56 8847 8192000000 10219663536 10639097856 1332547104 13.990 100 22 44 0 1343283088 5881120 4854864
17:25:05 33972 8192000000 10216268384 10643628032 1336296736 14.024 100 32 64 0 1349287280 6222528 6768016
17:26:41 5558 8192000000 10232307984 10650431488 1330311296 13.970 100 22 43 1 1346380960 10643424 5426240
17:27:47 9329 8192000000 10217091824 10637094912 1308915552 13.776 98 17 36 0 1319205520 5751008 4538960
17:29:00 59273 8192000000 10194606624 10601844736 1260859664 13.338 96 29 54 1 1272419840 4609408 6950768
17:31:00 6831 8192000000 10165400000 10569895936 1253993760 13.275 98 26 53 0 1265581568 5727824 5859984
17:32:09 9717 8192000000 10146017360 10538737664 1230483264 13.059 98 17 34 0 1239790480 5473104 3834112
17:33:21 67530 8192000000 10165991488 10569940992 1238228016 13.130 97 41 82 2 1253807600 4839392 10740192
17:35:29 6851 8192000000 10188155792 10594512896 1293423392 13.635 100 29 58 1 1306672160 6785968 6462800
17:36:37 9456 8192000000 10198902112 10609680384 1303926112 13.731 100 30 58 2 1316210096 4851456 7432528
17:37:48 10422 8192000000 10192188480 10611884032 1306667344 13.756 100 9 16 0 1313422112 4926400 1828368
17:38:59 42923 8192000000 10244105968 10652270592 1332357376 13.988 100 43 86 0 1348077792 5589392 10131024
17:40:48 10200 8192000000 10254087888 10667626496 1346636576 14.117 100 47 93 0 1368273200 11261344 10375280
17:42:09 8485 8192000000 10280882288 10693099520 1364935280 14.282 100 53 104 0 1392112272 15463616 11713376
17:43:25 9791 8192000000 10293773536 10711515136 1374011808 14.363 100 48 96 1 1407250528 21796512 11442208
17:44:39 13677 8192000000 10302273504 10723500032 1387030608 14.479 100 43 86 0 1415870576 18727536 10112432
17:45:54 12033 8192000000 10308834704 10731143168 1393974736 14.541 100 42 85 1 1422684560 18277648 10432176
17:47:11 18258 8192000000 10328524800 10752069632 1397912736 14.576 100 49 96 0 1431758304 21831040 12014528
17:48:37 7921 8192000000 10340965648 10764591104 1415524688 14.733 100 59 116 0 1453490928 24658416 13307824
17:50:01 4151 8192000000 10353642016 10772135936 1417496112 14.750 100 76 152 1 1464628176 29456592 17675472
17:51:10 12639 8192000000 10319581456 10745712640 1378886256 14.407 99 54 106 0 1425621456 33122960 13612240
17:52:33 11158 8192000000 10345106640 10768519168 1396570176 14.564 100 43 84 0 1450355072 42605216 11179680
17:53:54 3200 8192000000 10370856816 10788720640 1397679888 14.574 99 73 140 1 1465611936 49724944 18207104
17:55:02 6797 8192000000 10354894240 10772246528 1402430912 14.617 100 66 132 0 1463111088 44256896 16423280
17:56:18 1634 8192000000 10349018352 10756472832 1388876464 14.496 100 76 152 1 1452083072 44412944 18793664
17:57:33 3249 8192000000 10346286432 10759561216 1373442448 14.358 99 66 132 0 1442295584 51196496 17656640
17:58:41 8160 8192000000 10335025200 10753429504 1377701184 14.396 100 52 98 1 1437212768 46752016 12759568
17:59:53 8413 8192000000 10332393184 10752774144 1375397008 14.375 99 55 108 3 1437186416 47071488 14717920
18:01:09 5187 8192000000 10334112208 10756497408 1363808960 14.272 99 51 95 1 1430315808 53424112 13082736
18:02:25 23981 8192000000 10327665648 10756640768 1361450912 14.250 98 29 58 0 1418249680 48555488 8243280
18:03:51 31439 8192000000 10327263968 10755489792 1341647296 14.072 98 24 48 1 1409587824 60675648 7264880
18:05:49 17726 8192000000 10318936048 10753880064 1353123584 14.176 100 31 58 1 1420453840 57769632 9560624
18:08:15 60649 8192000000 10257888096 10694557696 1333092016 13.995 98 24 49 1 1362826416 24299600 5434800
18:10:18 52896 8192000000 10267451440 10714996736 1358343296 14.222 99 19 38 0 1375855904 13471360 4041248
18:12:11 102299 8192000000 10282259728 10741719040 1373787312 14.361 100 31 61 0 1399640528 18356384 7496832
18:16:10 90317 8192000000 10288866688 10732654592 1369616720 14.324 99 10 20 1 1384842096 13417792 1807584
18:18:44 199072 8192000000 10302206000 10756399104 1388514288 14.493 100 29 58 0 1409357040 14476048 6366704
18:23:15 87768 8192000000 10283909104 10728808448 1342517936 14.080 97 13 26 0 1357751440 12172720 3060784
18:25:47 14755 8192000000 10288643168 10735194112 1374692560 14.369 99 39 78 1 1392507104 9083904 8730640
18:27:05 37572 8192000000 10317563088 10755211264 1385538208 14.466 99 42 84 0 1408252848 13408400 9306240
18:28:45 65765 8192000000 10309988032 10776215552 1407957216 14.666 100 18 36 0 1431975504 19395184 4623104
18:30:52 65019 8192000000 10307708672 10773159936 1410172592 14.685 100 12 24 0 1430611600 17374656 3064352
18:32:58 18004 8192000000 10284063680 10735144960 1376852400 14.388 98 43 83 0 1403374192 15794928 10726864
18:34:16 65716 8192000000 10314675504 10780753920 1409325744 14.678 100 12 24 0 1425849408 13958240 2565424
18:36:49 80204 8192000000 10308542240 10778738688 1400952128 14.603 99 22 44 0 1422060528 15695856 5412544
18:39:10 53982 8192000000 10310758624 10774958080 1398350304 14.580 99 15 29 0 1418204480 17108288 2745888
18:41:15 101233 8192000000 10295039328 10747133952 1310822608 13.794 93 19 38 0 1324227360 9364656 4040096
18:43:57 57212 8192000000 10300121280 10755121152 1373021264 14.354 98 18 36 0 1387934992 11151856 3761872
18:45:54 51088 8192000000 10307068144 10748796928 1346553072 14.116 96 31 62 0 1362648496 9725200 6370224
18:47:56 86234 8192000000 10267231600 10734329856 1332613888 13.991 97 6 12 0 1352306992 18254736 1438368
18:50:23 35820 8192000000 10265548848 10708185088 1317468016 13.854 96 53 106 0 1336717968 6675776 12574176
18:52:31 33818 8192000000 10258686112 10716413952 1352685440 14.172 99 9 18 0 1364475344 9878128 1911776
18:54:06 51753 8192000000 10246167408 10699018240 1324126000 13.914 97 13 24 0 1333593248 6928304 2538944
18:56:03 41435 8192000000 10242889280 10680991744 1259728352 13.328 92 29 58 1 1274489760 7189168 7572240
18:57:44 86167 8192000000 10233332448 10687913984 1321665536 13.892 98 15 30 0 1336045056 10747568 3631952
19:00:11 74333 8192000000 10235182944 10691985408 1291313776 13.616 96 17 33 0 1305854592 10606224 3934592
19:02:30 38766 8192000000 10262205680 10692837376 1324031280 13.913 98 32 64 1 1341562256 10090400 7440576
19:04:10 79875 8192000000 10260896544 10708709376 1353527024 14.179 100 14 28 0 1367742160 10861824 3353312
19:06:38 120974 8192000000 10274533696 10714578944 1324181232 13.915 97 12 24 0 1336179504 9614464 2383808
19:09:42 47148 8192000000 10284814640 10737930240 1379831168 14.415 100 33 66 0 1399526704 12661728 7033808
19:11:35 63830 8192000000 10297544352 10749595648 1381739328 14.432 99 17 34 0 1397363392 11918608 3705456
19:13:41 77978 8192000000 10306702496 10747920384 1367400528 14.304 98 19 38 1 1384440064 13286736 3752800
19:16:00 142258 8192000000 10307650064 10766426112 1389559296 14.502 99 32 62 1 1412121248 15202688 7359264
19:19:22 51110 8192000000 10294952480 10757070848 1394661648 14.547 99 14 26 0 1414814416 16985184 3167584
19:22:06 66129 8192000000 10274472304 10732683264 1335799216 14.020 95 14 28 0 1350637424 11507776 3330432
19:24:19 56664 8192000000 10299554496 10745139200 1367934528 14.309 98 12 24 1 1380467712 9822128 2711056
19:26:36 65070 8192000000 10271885376 10722177024 1320601808 13.882 95 14 28 0 1333835424 9938752 3294864
19:28:42 72848 8192000000 10304274880 10756677632 1348918624 14.138 97 21 42 0 1370609920 16775264 4916032
19:30:59 59512 8192000000 10279848224 10734075904 1330373632 13.971 96 15 30 0 1353536784 19671632 3491520
19:33:43 30540 8192000000 10287147680 10714943488 1337075904 14.031 98 45 90 1 1360940080 13326304 10537872
19:35:18 39771 8192000000 10289690576 10716618752 1326319856 13.934 97 45 90 0 1352519792 16357792 9842144
19:36:59 108850 8192000000 9458025728 9773654016 592910064 6.749 44 8 16 0 603692592 8952528 1830000

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 16-10-2022 19:41:06 to 16-10-2022 19:41:21.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 137 v_add_invoice_to_stock 335544517
except 19 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 16-10-2022 19:41:22 to 16-10-2022 19:41:24.

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          238995
MON$OLDEST_ACTIVE               239796
MON$OLDEST_SNAPSHOT             239796
MON$NEXT_TRANSACTION            239796
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                       2017856
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33634 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33634 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33634 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33634 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 ---
:439: 
:440: mscs-test-161.budzdorov.ru    Sun Oct 16 19:39:18 2022
:441:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:442: 
--- end of diff output ---

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

16.10.22 19:41:25. 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-10-16 19:41:27 No severe PSQL-related problems occured
16.10.22 19:41:29. End of report.