Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 23.09.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 15520052 kB
MemAvailable 30575824 kB
Buffers 12 kB
Cached 16161888 kB
SwapCached 2036 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-09-23 19:38:31
DTS_END                         2022-09-23 19:38:31.7820
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_ORDER(38:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/58788
TRN_ID                          234061
ATT_ID                          679
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: 9370.47 23587 1124456
customer order: creation 163.58 336 19630
customer order: refuse 13.94 369 1673
order to supplier: creation 43.38 1343 5205
order to supplier: removal 3.58 102843 430
invoice (draft): creation 44.68 3574 5361
invoice (draft): removal 55.52 815 6662
invoice accept: apply 40.19 19749 4823
invoice accept: cancel 50.13 849 6015
customer reserve: creation 3770.97 192 452516
customer reserve: removal 4898.41 58 587809
realization accept: apply 78.78 193 9454
realization accept: cancel 83.70 118 10044
payment from customer: creation 55.85 145 6702
payment from customer: removal 5.46 77 655
payment to supplier: creation 47.86 159 5743
payment to supplier: removal 3.09 87 371
service: total inventory turnovers 4.58 1031 550
service: total monetary turnovers 6.14 43 737
service: aggregate perf. data 0.60 31326 72
service: refresh index statistics 0.03 308427 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 23-09-2022 19:39:13 to 23-09-2022 19:39:15.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3646
TEST_TIME 2 3200
TEST_TIME 3 2447
TEST_TIME 4 3128
TEST_TIME 5 5104
TEST_TIME 6 5383
TEST_TIME 7 5642
TEST_TIME 8 6322
TEST_TIME 9 6359
TEST_TIME 10 6795
TEST_TIME 11 6993
TEST_TIME 12 6992
TEST_TIME 13 7068
TEST_TIME 14 7322
TEST_TIME 15 7450
TEST_TIME 16 7513
TEST_TIME 17 7624
TEST_TIME 18 7886
TEST_TIME 19 7923
TEST_TIME 20 7933
TEST_TIME 21 8301
TEST_TIME 22 8246
TEST_TIME 23 8360
TEST_TIME 24 8483
TEST_TIME 25 8634
TEST_TIME 26 8809
TEST_TIME 27 9127
TEST_TIME 28 9046
TEST_TIME 29 9103
TEST_TIME 30 9399
TEST_TIME 31 10830
TEST_TIME 32 10666
TEST_TIME 33 10762
TEST_TIME 34 10575
TEST_TIME 35 10591
TEST_TIME 36 10416
TEST_TIME 37 10349
TEST_TIME 38 10242
TEST_TIME 39 10096
TEST_TIME 40 9933
TEST_TIME 41 9866
TEST_TIME 42 9684
TEST_TIME 43 9716
TEST_TIME 44 9821
TEST_TIME 45 9894
TEST_TIME 46 9748
TEST_TIME 47 9604
TEST_TIME 48 9590
TEST_TIME 49 9543
TEST_TIME 50 9650
TEST_TIME 51 9506
TEST_TIME 52 9481
TEST_TIME 53 9459
TEST_TIME 54 9503
TEST_TIME 55 9539
TEST_TIME 56 9425
TEST_TIME 57 9304
TEST_TIME 58 9222
TEST_TIME 59 9250
TEST_TIME 60 9273
TEST_TIME 61 9214
TEST_TIME 62 9137
TEST_TIME 63 9172
TEST_TIME 64 9288
TEST_TIME 65 9264
TEST_TIME 66 9299
TEST_TIME 67 9314
TEST_TIME 68 9273
TEST_TIME 69 9378
TEST_TIME 70 9416
TEST_TIME 71 9419
TEST_TIME 72 9379
TEST_TIME 73 9493
TEST_TIME 74 9427
TEST_TIME 75 9462
TEST_TIME 76 9470
TEST_TIME 77 9391
TEST_TIME 78 9358
TEST_TIME 79 9280
TEST_TIME 80 9294
TEST_TIME 81 9264
TEST_TIME 82 9211
TEST_TIME 83 9210
TEST_TIME 84 9190
TEST_TIME 85 9158
TEST_TIME 86 9167
TEST_TIME 87 9257
TEST_TIME 88 9255
TEST_TIME 89 9255
TEST_TIME 90 9260
TEST_TIME 91 9320
TEST_TIME 92 9300
TEST_TIME 93 9366
TEST_TIME 94 9415
TEST_TIME 95 9407
TEST_TIME 96 9392
TEST_TIME 97 9488
TEST_TIME 98 9425
TEST_TIME 99 9355
TEST_TIME 100 9371
TEST_TIME 101 9332
TEST_TIME 102 9291
TEST_TIME 103 9344
TEST_TIME 104 9300
TEST_TIME 105 9258
TEST_TIME 106 9264
TEST_TIME 107 9227
TEST_TIME 108 9210
TEST_TIME 109 9206
TEST_TIME 110 9222
TEST_TIME 111 9243
TEST_TIME 112 9289
TEST_TIME 113 9281
TEST_TIME 114 9301
TEST_TIME 115 9260
TEST_TIME 116 9291
TEST_TIME 117 9399
TEST_TIME 118 9418
TEST_TIME 119 9408
TEST_TIME 120 9375

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 23-09-2022 19:39:16 to 23-09-2022 19:39:20.

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 1543363 1543363 0 0.00 0 17054 7 0 0
doc_list_biud 1543363 1543363 0 0.00 0 40346 28 0 0
sp_add_invoice_to_stock 4896 4823 73 1.49 31 202385 19749 0 73
sp_cancel_adding_invoice 6015 6015 0 0.00 4 61942 850 0 0
sp_cancel_client_order 1673 1673 0 0.00 4 9904 370 0 0
sp_cancel_customer_reserve 587809 587809 0 0.00 0 40348 59 0 0
sp_cancel_pay_from_customer 655 655 0 0.00 3 5457 78 0 0
sp_cancel_pay_to_supplier 371 371 0 0.00 3 3109 87 0 0
sp_cancel_supplier_invoice 6664 6662 2 0.03 1 14867 815 0 2
sp_cancel_supplier_order 430 430 0 0.00 7 1064951 102843 0 0
sp_cancel_write_off 10044 10044 0 0.00 1 20377 118 0 0
sp_client_order 19630 19630 0 0.00 3 23002 337 0 0
sp_customer_reserve 452516 452516 0 0.00 0 139675 193 0 0
sp_fill_shopping_cart 30196 30196 0 0.00 0 15580 43 0 0
sp_fill_shopping_cart_clo_res 452510 452510 0 0.00 0 22635 13 0 0
sp_get_clo_for_invoice 4817 4817 0 0.00 0 27828 301 0 0
sp_kill_cost_storno 17085 17085 0 0.00 0 10905 60 0 0
sp_kill_qstorno_ret_qs2qd 1192754 1192754 0 0.00 0 40342 32 0 0
sp_kill_qty_storno 626904 626904 0 0.00 0 40346 69 0 0
sp_lock_selected_doc 19829 19829 0 0.00 0 6799 5 0 0
sp_make_cost_storno 26716 26716 0 0.00 0 16151 59 0 0
sp_multiply_rows_for_pdistr 26716 26716 0 0.00 0 8179 37 0 0
sp_multiply_rows_for_qdistr 19630 19630 0 0.00 0 13614 144 0 0
sp_pay_from_customer 6702 6702 0 0.00 4 13342 146 0 0
sp_pay_to_supplier 5743 5743 0 0.00 5 16228 160 0 0
sp_payment_common 12445 12445 0 0.00 1 12858 44 0 0
sp_qd_handle_on_cancel_clo 1673 1673 0 0.00 0 9896 343 0 0
sp_qd_handle_on_invoice_upd_sts 10832 10832 0 0.00 0 27449 331 0 0
sp_qd_handle_on_reserve_upd_sts 9454 9454 0 0.00 0 24169 66 0 0
sp_reserve_write_off 9454 9454 0 0.00 6 24181 193 0 0
sp_supplier_invoice 5361 5361 0 0.00 6 92489 3575 0 0
sp_supplier_order 5205 5205 0 0.00 5 40990 1344 0 0
srv_aggregate_perf_data 72 72 0 0.00 2741 306295 31326 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1592 171013 47072 0 0
srv_find_qd_qs_mism 1078401 1078401 0 0.00 0 17003 5 0 0
srv_make_invnt_saldo 550 550 0 0.00 45 47530 1031 0 0
srv_make_money_saldo 737 737 0 0.00 1 2712 44 0 0
srv_recalc_idx_stat 4 4 0 0.00 240576 420371 308428 0 0
t$perf-abend:sp_add_invoice_to_stock 73 73 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 2 2 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4823 4823 0 0.00 0 10268 186 0 0
t$perf-norm:sp_cancel_adding_invoice 401 401 0 0.00 0 3269 115 0 0
t$perf-norm:sp_cancel_client_order 1673 1673 0 0.00 0 1668 13 0 0
t$perf-norm:sp_cancel_customer_reserve 691 691 0 0.00 0 4364 23 0 0
t$perf-norm:sp_cancel_pay_from_customer 655 655 0 0.00 0 1229 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 371 371 0 0.00 0 434 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 389 389 0 0.00 0 2274 33 0 0
t$perf-norm:sp_cancel_supplier_order 430 430 0 0.00 0 19555 1717 0 0
t$perf-norm:sp_cancel_write_off 948 948 0 0.00 0 5401 15 0 0
t$perf-norm:sp_client_order 19630 19630 0 0.00 0 14837 20 0 0
t$perf-norm:sp_customer_reserve 968 968 0 0.00 0 5348 14 0 0
t$perf-norm:sp_pay_from_customer 6702 6702 0 0.00 0 14357 16 0 0
t$perf-norm:sp_pay_to_supplier 5743 5743 0 0.00 0 9815 14 0 0
t$perf-norm:sp_reserve_write_off 9454 9454 0 0.00 0 14983 19 0 0
t$perf-norm:sp_supplier_invoice 5361 5361 0 0.00 0 14771 40 0 0
t$perf-norm:sp_supplier_order 5205 5205 0 0.00 0 10253 18 0 0
t$perf-norm:srv_aggregate_perf_data 72 72 0 0.00 0 634 23 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 109 3 0 0
t$perf-norm:srv_make_invnt_saldo 550 550 0 0.00 0 1131 30 0 0
t$perf-norm:srv_make_money_saldo 737 737 0 0.00 0 1891 24 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 2 0 0
v_add_invoice_to_stock 9780 9634 146 1.49 0 3327 17 0 146
v_all_customers 35924 35924 0 0.00 0 58 1 0 0
v_all_suppliers 29886 29886 0 0.00 0 24 1 0 0
v_all_wares 39260 39260 0 0.00 0 40 1 0 0
v_cancel_adding_invoice 802 802 0 0.00 0 201 6 0 0
v_cancel_client_order 3346 3346 0 0.00 0 3158 6 0 0
v_cancel_customer_prepayment 1310 1310 0 0.00 0 921 7 0 0
v_cancel_customer_reserve 1382 1382 0 0.00 0 8806 13 0 0
v_cancel_payment_to_supplier 742 742 0 0.00 0 2797 9 0 0
v_cancel_supplier_invoice 782 778 4 0.51 0 3390 18 0 4
v_cancel_supplier_order 860 860 0 0.00 0 1927 6 0 0
v_cancel_write_off 1896 1896 0 0.00 0 992 15 0 0
v_max_id_clo_ord 5205 5205 0 0.00 0 980 1 0 0
v_max_id_clo_res 968 968 0 0.00 0 4070 15 0 0
v_max_id_ord_sup 5361 5361 0 0.00 0 7515 9 0 0
v_max_non_paid_invoice 1366 1366 0 0.00 0 6469 13 0 0
v_max_non_paid_realizn 3528 3528 0 0.00 0 6480 8 0 0
v_min_id_clo_ord 5205 5205 0 0.00 0 7624 11 0 0
v_min_id_clo_res 968 968 0 0.00 0 8040 21 0 0
v_min_id_ord_sup 5361 5361 0 0.00 0 5491 11 0 0
v_min_non_paid_invoice 5743 5743 0 0.00 0 7968 30 0 0
v_min_non_paid_realizn 6702 6702 0 0.00 0 12727 34 0 0
v_our_firm 9684 9684 0 0.00 0 30 1 0 0
v_reserve_write_off 18908 18908 0 0.00 0 10272 7 0 0
x_lock_depdocs_on_canc_invoice 12288 12288 0 0.00 0 24279 141 0 0
x_lock_depdocs_on_canc_sup_ord 430 430 0 0.00 0 6632 388 0 0
x_make_qstorno_1000_1200 5205 5205 0 0.00 1 40968 1257 0 0
x_make_qstorno_1200_2000 5361 5361 0 0.00 3 92353 3422 0 0
x_make_qstorno_2100_3300 452516 452516 0 0.00 0 139673 176 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 23-09-2022 19:39:21 to 23-09-2022 19:39:25.

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:36 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:36 25204 8192000000 9872855392 10178674688 960745696 10.496 100 90 180 0 991572432 6202848 24623888
17:11:10 14427 8192000000 9919262928 10231738368 1000714176 10.885 100 94 188 0 1033479136 7979744 24785216
17:12:52 11795 8192000000 9961389056 10286223360 1043370832 11.297 100 89 178 1 1076207232 9393456 23442944
17:14:08 10523 8192000000 10025156400 10371698688 1110561968 11.938 100 73 147 0 1138349088 9669920 18117200
17:15:25 5102 8192000000 10102712000 10462318592 1185470400 12.641 100 81 164 0 1215742640 11915680 18356560
17:16:32 3945 8192000000 10140791968 10509848576 1226628624 13.023 100 79 157 0 1256860240 13302592 16929024
17:17:39 1748 8192000000 10161597120 10533613568 1248568256 13.225 100 85 166 0 1281210816 14576272 18066288
17:18:43 810 8192000000 10199148528 10577207296 1279487968 13.508 100 90 180 1 1314251184 13920928 20842288
17:19:46 1845 8192000000 10204406560 10593464320 1297571728 13.673 100 86 174 0 1330323232 13278320 19473184
17:20:48 21869 8192000000 10204186736 10602782720 1304800384 13.739 100 57 117 2 1326074096 7413952 13859760
17:22:11 5210 8192000000 10213865408 10620379136 1313206064 13.815 100 35 70 0 1332057232 11248720 7602448
17:23:17 4552 8192000000 10210602112 10625556480 1317126208 13.851 100 30 56 2 1335187600 11996352 6065040
17:24:23 12795 8192000000 10211605136 10633834496 1321971504 13.895 100 11 22 0 1338076064 14230128 1874432
17:25:36 62890 8192000000 10239972544 10643562496 1327577360 13.945 100 41 82 2 1343281552 5509248 10194944
17:27:40 7700 8192000000 10237131600 10653536256 1331426992 13.980 100 23 47 0 1342183744 5785888 4970864
17:28:51 8237 8192000000 10208438464 10629718016 1311839104 13.803 99 16 30 0 1325265856 9996160 3430592
17:30:00 33488 8192000000 10164488112 10574303232 1211992144 12.888 93 30 60 0 1232205920 13395680 6818096
17:31:35 9930 8192000000 10154229632 10556379136 1230939424 13.063 97 23 46 0 1242233232 6170864 5122944
17:32:50 7907 8192000000 10136706368 10533711872 1214802576 12.914 97 26 51 2 1226247680 5463344 5981760
17:33:58 9698 8192000000 10161442080 10565967872 1255827200 13.292 99 22 42 1 1270477152 9269872 5380080
17:35:10 20447 8192000000 10167830608 10575966208 1277440432 13.490 100 30 60 0 1289653520 5168560 7044528
17:36:34 6897 8192000000 10193172736 10603622400 1299436912 13.690 100 21 41 1 1308741840 4760144 4544784
17:37:43 12688 8192000000 10194838176 10616893440 1309791360 13.784 100 26 50 0 1320607616 5361120 5455136
17:39:00 46190 8192000000 10246187904 10660360192 1343497152 14.089 100 40 78 0 1357861600 5049088 9315360
17:40:55 101166 8192000000 10268413024 10690580480 1368607056 14.315 100 45 87 0 1388755088 10475664 9672368
17:43:50 28420 8192000000 10280088976 10707918848 1374491072 14.367 100 38 76 0 1396806192 13790640 8524480
17:45:26 1640 8192000000 10302698320 10722263040 1386223328 14.472 100 63 126 0 1414790720 13845760 14721632
17:46:30 9120 8192000000 10302650896 10720632832 1383158576 14.445 100 72 141 0 1415473856 16733440 15581840
17:47:46 2811 8192000000 10317113248 10742063104 1395304880 14.553 100 52 102 0 1430571472 23505520 11761072
17:48:57 3458 8192000000 10341177424 10763071488 1409218736 14.677 100 71 139 0 1450728688 25197696 16312256
17:50:06 2263 8192000000 10347294160 10764017664 1409497056 14.679 100 86 173 0 1455923472 25483120 20943296
17:51:13 2515 8192000000 10343443152 10758299648 1404809824 14.638 100 82 160 2 1450130096 25480416 19839856
17:52:21 5572 8192000000 10332136096 10757537792 1398060576 14.578 100 50 100 2 1444151904 33749424 12341904
17:53:34 7420 8192000000 10340156416 10759471104 1398214544 14.579 100 61 120 1 1447480960 34252992 15013424
17:54:53 3475 8192000000 10331458768 10743750656 1384963440 14.461 100 68 137 0 1435977456 33485232 17528784
17:56:06 2757 8192000000 10336889984 10739548160 1373472464 14.358 99 81 160 1 1437436416 43943712 20020240
17:57:14 4640 8192000000 10348584464 10765193216 1396155680 14.561 100 65 126 0 1452281808 39641280 16484848
17:58:22 2784 8192000000 10348698208 10756591616 1386280064 14.473 100 82 162 0 1451946720 45066016 20600640
17:59:33 1592 8192000000 10360225600 10764734464 1390381008 14.509 100 81 158 1 1457844880 47965136 19498736
18:00:39 5917 8192000000 10335290384 10754662400 1388481520 14.492 100 45 88 0 1443822160 43184624 12156016
18:01:52 6216 8192000000 10344746224 10759397376 1382857504 14.442 100 64 128 0 1447978992 47660384 17461104
18:03:02 3186 8192000000 10344889856 10755428352 1375085792 14.373 100 70 140 1 1445973712 52805648 18082272
18:04:12 18360 8192000000 10333671504 10766983168 1379602512 14.413 100 36 71 1 1445422064 55724064 10095488
18:05:33 28494 8192000000 10334848432 10771460096 1390234736 14.508 100 26 53 0 1442175968 44500064 7441168
18:07:44 34245 8192000000 10301124896 10739372032 1334019840 14.003 99 20 40 0 1398805376 58267120 6518416
18:09:45 53748 8192000000 10277690128 10703294464 1337884384 14.038 98 21 42 1 1350530528 8244720 4401424
18:11:42 69964 8192000000 10279039312 10713137152 1324260624 13.915 96 12 25 1 1337414704 10453680 2700400
18:13:53 40478 8192000000 10301677840 10730164224 1379055376 14.408 100 58 116 2 1403209888 10989984 13164528
18:15:34 171013 8192000000 10275442144 10723377152 1356173616 14.203 98 27 54 0 1373650736 11347776 6129344
18:19:26 88295 8192000000 10318720928 10765578240 1392278864 14.526 100 18 37 0 1412869904 16142096 4448944
18:22:01 45424 8192000000 10294767072 10760122368 1371673088 14.342 98 16 33 0 1392294560 16786544 3834928
18:23:48 42541 8192000000 10297252800 10767536128 1390099824 14.507 100 9 17 1 1410080432 18148720 1831888
18:25:32 96602 8192000000 10285370512 10737074176 1357559648 14.215 97 40 79 0 1379721792 12853232 9308912
18:28:30 48418 8192000000 10297002880 10765828096 1395635264 14.556 100 11 22 0 1410655984 12619488 2401232
18:30:31 70223 8192000000 10300620880 10761932800 1388433168 14.492 99 26 52 0 1407640112 13248080 5958864
18:32:42 45256 8192000000 10295733360 10757181440 1385562112 14.466 99 13 26 1 1396509584 7677504 3269968
18:34:34 44470 8192000000 10276811088 10737831936 1357738720 14.217 97 7 14 0 1369851616 10721120 1391776
18:36:26 91839 8192000000 10286481984 10759307264 1379872672 14.415 99 20 40 0 1395119744 10403200 4843872
18:39:00 41975 8192000000 10285900656 10748248064 1382327008 14.437 99 21 40 0 1396220416 9524256 4369152
18:40:56 71599 8192000000 10271340144 10737737728 1335009648 14.012 96 16 32 0 1353581232 14976032 3595552
18:43:16 28597 8192000000 10289715904 10741030912 1360842592 14.245 98 40 80 0 1378944080 9525792 8575696
18:44:45 59343 8192000000 10287961472 10741714944 1355705872 14.199 98 26 51 1 1371120016 10021648 5392496
18:46:48 36491 8192000000 10279159648 10738454528 1373291024 14.357 100 40 80 0 1396664032 14371664 9001344
18:48:28 62393 8192000000 10268176208 10720866304 1348434960 14.133 98 14 26 0 1360711456 9513808 2762688
18:50:40 54234 8192000000 10287515312 10749267968 1373886432 14.362 99 20 40 0 1390715568 11514976 5314160
18:52:35 57026 8192000000 10245970448 10694029312 1270313504 13.424 93 32 64 1 1287229168 9709376 7206288
18:54:38 101629 8192000000 10243754976 10675757056 1295683392 13.656 96 19 38 0 1307810816 8103920 4023504
18:57:30 28316 8192000000 10251672000 10703147008 1336974944 14.030 99 31 62 1 1359635392 15819184 6841264
18:59:02 51938 8192000000 10243172512 10700869632 1339394528 14.052 99 10 20 0 1350816784 9176608 2245648
19:00:54 56643 8192000000 10228461872 10691096576 1330984576 13.976 99 5 10 0 1340403600 8014288 1404736
19:03:14 59492 8192000000 10251372800 10696683520 1303989216 13.731 96 8 16 0 1318833024 13096672 1747136
19:05:15 115407 8192000000 10240243280 10684641280 1315654064 13.837 97 12 24 0 1330090496 11623536 2812896
19:08:20 73877 8192000000 10258826432 10692280320 1291947840 13.622 94 25 49 1 1308171968 10800672 5423456
19:10:37 86314 8192000000 10285660704 10742779904 1350253568 14.150 99 12 24 0 1364653456 12045376 2354512
19:13:11 47000 8192000000 10294506096 10731634688 1366986896 14.300 99 37 74 1 1385665216 10073840 8604480
19:15:03 94519 8192000000 10311157392 10750308352 1360424368 14.241 98 22 44 0 1382017024 16666928 4925728
19:17:41 87695 8192000000 10283527472 10737676288 1349421536 14.142 97 17 34 0 1363089568 9779648 3888384
19:20:13 43375 8192000000 10301679472 10759827456 1379935600 14.416 99 26 53 0 1404040160 18474864 5629696
19:22:07 27425 8192000000 10275259856 10746609664 1375216144 14.374 99 10 20 0 1387849776 10412816 2220816
19:23:35 123178 8192000000 10279800400 10744553472 1351856368 14.164 97 17 32 0 1366217312 10263216 4097728
19:26:44 46390 8192000000 10292565024 10756816896 1375016800 14.372 99 28 56 0 1397418704 15645488 6756416
19:28:36 65007 8192000000 10302343680 10754781184 1380732208 14.423 99 18 34 0 1403403536 18628240 4043088
19:30:47 59778 8192000000 10294690160 10747600896 1347539552 14.125 97 21 40 0 1361288352 9498640 4250160
19:33:36 32141 8192000000 10315661152 10767867904 1397899008 14.576 100 40 80 0 1422225536 15289856 9036672
19:35:36 109645 8192000000 10292628160 10768052224 1329786272 13.965 96 10 20 0 1356383584 24570624 2026688
19:38:26 13466 8192000000 8971324784 9158316032 140431680 1.685 11 1 2 0 142161520 1687488 42352

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 23-09-2022 19:39:26 to 23-09-2022 19:39:41.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 73 v_add_invoice_to_stock 335544517
except 2 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 23-09-2022 19:39:42 to 23-09-2022 19:39:44.

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          234366
MON$OLDEST_ACTIVE               234367
MON$OLDEST_SNAPSHOT             234367
MON$NEXT_TRANSACTION            234367
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                       2035552
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.33617 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33617 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33617 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33617 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 ---
--- end of diff output ---

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

23.09.22 19:39:45. 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-09-23 19:39:47 No severe PSQL-related problems occured
23.09.22 19:39:49. End of report.