Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 29.01.2023 16:39
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 15179088 kB
MemAvailable 30460344 kB
Buffers 0 kB
Cached 16551300 kB
SwapCached 4332 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 2023-01-29 16:38:41
DTS_END                         2023-01-29 16:38:41.9730
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/47836
TRN_ID                          239368
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: 9082.36 25761 1089883
customer order: creation 169.39 358 20327
customer order: refuse 14.04 353 1685
order to supplier: creation 44.49 1288 5339
order to supplier: removal 3.38 110485 406
invoice (draft): creation 44.83 3489 5379
invoice (draft): removal 50.82 895 6098
invoice accept: apply 41.78 18163 5014
invoice accept: cancel 45.26 924 5431
customer reserve: creation 3848.31 178 461797
customer reserve: removal 4528.83 64 543460
realization accept: apply 82.47 200 9896
realization accept: cancel 80.78 119 9693
payment from customer: creation 57.44 137 6893
payment from customer: removal 5.50 108 660
payment to supplier: creation 49.35 143 5922
payment to supplier: removal 3.57 104 428
service: total inventory turnovers 4.82 1242 578
service: total monetary turnovers 6.47 39 776
service: aggregate perf. data 0.81 20686 97
service: refresh index statistics 0.03 356254 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 29-01-2023 16:40:20 to 29-01-2023 16:40:23.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4614
TEST_TIME 2 3662
TEST_TIME 3 3362
TEST_TIME 4 3237
TEST_TIME 5 4219
TEST_TIME 6 4683
TEST_TIME 7 5272
TEST_TIME 8 5575
TEST_TIME 9 5669
TEST_TIME 10 5632
TEST_TIME 11 5543
TEST_TIME 12 5819
TEST_TIME 13 6207
TEST_TIME 14 6377
TEST_TIME 15 7164
TEST_TIME 16 7158
TEST_TIME 17 7157
TEST_TIME 18 7374
TEST_TIME 19 7866
TEST_TIME 20 8345
TEST_TIME 21 8381
TEST_TIME 22 8325
TEST_TIME 23 8544
TEST_TIME 24 8487
TEST_TIME 25 8965
TEST_TIME 26 8766
TEST_TIME 27 9316
TEST_TIME 28 9557
TEST_TIME 29 9779
TEST_TIME 30 10854
TEST_TIME 31 10752
TEST_TIME 32 10870
TEST_TIME 33 10794
TEST_TIME 34 10955
TEST_TIME 35 10867
TEST_TIME 36 10587
TEST_TIME 37 10631
TEST_TIME 38 10446
TEST_TIME 39 10372
TEST_TIME 40 10174
TEST_TIME 41 10113
TEST_TIME 42 9986
TEST_TIME 43 9993
TEST_TIME 44 9888
TEST_TIME 45 9794
TEST_TIME 46 9722
TEST_TIME 47 9649
TEST_TIME 48 9544
TEST_TIME 49 9664
TEST_TIME 50 9573
TEST_TIME 51 9415
TEST_TIME 52 9362
TEST_TIME 53 9331
TEST_TIME 54 9357
TEST_TIME 55 9401
TEST_TIME 56 9353
TEST_TIME 57 9300
TEST_TIME 58 9186
TEST_TIME 59 9067
TEST_TIME 60 9197
TEST_TIME 61 9224
TEST_TIME 62 9163
TEST_TIME 63 9126
TEST_TIME 64 9130
TEST_TIME 65 9095
TEST_TIME 66 9060
TEST_TIME 67 9115
TEST_TIME 68 9126
TEST_TIME 69 9180
TEST_TIME 70 9201
TEST_TIME 71 9127
TEST_TIME 72 9200
TEST_TIME 73 9232
TEST_TIME 74 9175
TEST_TIME 75 9180
TEST_TIME 76 9221
TEST_TIME 77 9284
TEST_TIME 78 9343
TEST_TIME 79 9334
TEST_TIME 80 9294
TEST_TIME 81 9218
TEST_TIME 82 9187
TEST_TIME 83 9112
TEST_TIME 84 9092
TEST_TIME 85 9070
TEST_TIME 86 9038
TEST_TIME 87 9096
TEST_TIME 88 9105
TEST_TIME 89 9042
TEST_TIME 90 8988
TEST_TIME 91 8999
TEST_TIME 92 9009
TEST_TIME 93 9047
TEST_TIME 94 9088
TEST_TIME 95 9077
TEST_TIME 96 9141
TEST_TIME 97 9118
TEST_TIME 98 9167
TEST_TIME 99 9189
TEST_TIME 100 9160
TEST_TIME 101 9249
TEST_TIME 102 9227
TEST_TIME 103 9302
TEST_TIME 104 9286
TEST_TIME 105 9291
TEST_TIME 106 9292
TEST_TIME 107 9304
TEST_TIME 108 9270
TEST_TIME 109 9264
TEST_TIME 110 9208
TEST_TIME 111 9157
TEST_TIME 112 9133
TEST_TIME 113 9184
TEST_TIME 114 9154
TEST_TIME 115 9169
TEST_TIME 116 9137
TEST_TIME 117 9099
TEST_TIME 118 9052
TEST_TIME 119 9049
TEST_TIME 120 9086

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 29-01-2023 16:40:24 to 29-01-2023 16:40:28.

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 1516939 1516939 0 0.00 0 27684 6 0 0
doc_list_biud 1516939 1516939 0 0.00 0 43606 29 0 0
sp_add_invoice_to_stock 5118 5014 104 2.03 24 171519 18163 0 104
sp_cancel_adding_invoice 5431 5431 0 0.00 4 64369 925 0 0
sp_cancel_client_order 1685 1685 0 0.00 4 10608 354 0 0
sp_cancel_customer_reserve 543460 543460 0 0.00 0 20759 65 0 0
sp_cancel_pay_from_customer 660 660 0 0.00 3 9198 108 0 0
sp_cancel_pay_to_supplier 428 428 0 0.00 3 6843 105 0 0
sp_cancel_supplier_invoice 6107 6098 9 0.14 1 40546 895 0 9
sp_cancel_supplier_order 406 406 0 0.00 5 1278381 110485 0 0
sp_cancel_write_off 9693 9693 0 0.00 1 15266 119 0 0
sp_client_order 20327 20327 0 0.00 3 19853 358 0 0
sp_customer_reserve 461797 461797 0 0.00 0 78524 179 0 0
sp_fill_shopping_cart 31044 31044 0 0.00 1 20388 43 0 0
sp_fill_shopping_cart_clo_res 461792 461792 0 0.00 0 13724 14 0 0
sp_get_clo_for_invoice 5008 5008 0 0.00 0 21580 337 0 0
sp_kill_cost_storno 16212 16212 0 0.00 0 9192 64 0 0
sp_kill_qstorno_ret_qs2qd 1103117 1103117 0 0.00 0 40510 35 0 0
sp_kill_qty_storno 581677 581677 0 0.00 0 43604 76 0 0
sp_lock_selected_doc 20590 20590 0 0.00 0 7845 6 0 0
sp_make_cost_storno 27719 27719 0 0.00 0 27670 67 0 0
sp_multiply_rows_for_pdistr 27719 27719 0 0.00 0 7985 31 0 0
sp_multiply_rows_for_qdistr 20327 20327 0 0.00 0 18972 142 0 0
sp_pay_from_customer 6893 6893 0 0.00 4 10210 138 0 0
sp_pay_to_supplier 5922 5922 0 0.00 4 12563 143 0 0
sp_payment_common 12815 12815 0 0.00 2 12518 40 0 0
sp_qd_handle_on_cancel_clo 1685 1685 0 0.00 0 10601 327 0 0
sp_qd_handle_on_invoice_upd_sts 10439 10439 0 0.00 0 43601 393 0 0
sp_qd_handle_on_reserve_upd_sts 9896 9896 0 0.00 0 10373 64 0 0
sp_reserve_write_off 9896 9896 0 0.00 6 16943 200 0 0
sp_supplier_invoice 5379 5379 0 0.00 6 89458 3490 0 0
sp_supplier_order 5339 5339 0 0.00 5 26862 1288 0 0
srv_aggregate_perf_data 97 97 0 0.00 2563 257288 20686 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 934 120410 42424 0 0
srv_find_qd_qs_mism 1042358 1042358 0 0.00 0 11804 5 0 0
srv_make_invnt_saldo 578 578 0 0.00 28 107106 1243 0 0
srv_make_money_saldo 776 776 0 0.00 1 3818 39 0 0
srv_recalc_idx_stat 4 4 0 0.00 95533 472467 356254 0 0
t$perf-abend:sp_add_invoice_to_stock 104 104 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5014 5014 0 0.00 0 8772 185 0 0
t$perf-norm:sp_cancel_adding_invoice 422 422 0 0.00 0 5529 92 0 0
t$perf-norm:sp_cancel_client_order 1685 1685 0 0.00 0 5908 21 0 0
t$perf-norm:sp_cancel_customer_reserve 703 703 0 0.00 0 1418 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 660 660 0 0.00 0 1085 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 428 428 0 0.00 0 707 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 426 426 0 0.00 0 2217 30 0 0
t$perf-norm:sp_cancel_supplier_order 406 406 0 0.00 0 21223 1715 0 0
t$perf-norm:sp_cancel_write_off 956 956 0 0.00 0 3675 7 0 0
t$perf-norm:sp_client_order 20327 20327 0 0.00 0 5390 17 0 0
t$perf-norm:sp_customer_reserve 969 969 0 0.00 0 2800 13 0 0
t$perf-norm:sp_pay_from_customer 6893 6893 0 0.00 0 6079 12 0 0
t$perf-norm:sp_pay_to_supplier 5922 5922 0 0.00 0 5150 13 0 0
t$perf-norm:sp_reserve_write_off 9896 9896 0 0.00 0 14538 12 0 0
t$perf-norm:sp_supplier_invoice 5379 5379 0 0.00 0 12429 24 0 0
t$perf-norm:sp_supplier_order 5339 5339 0 0.00 0 6572 15 0 0
t$perf-norm:srv_aggregate_perf_data 97 97 0 0.00 0 572 14 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 24 1 0 0
t$perf-norm:srv_make_invnt_saldo 578 578 0 0.00 0 1460 24 0 0
t$perf-norm:srv_make_money_saldo 776 776 0 0.00 0 736 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 6 3 0 0
v_add_invoice_to_stock 10224 10016 208 2.03 0 5414 17 0 208
v_all_customers 36644 36644 0 0.00 0 24 1 0 0
v_all_suppliers 29952 29952 0 0.00 0 26 1 0 0
v_all_wares 40654 40654 0 0.00 0 34 1 0 0
v_cancel_adding_invoice 844 844 0 0.00 0 236 6 0 0
v_cancel_client_order 3370 3370 0 0.00 0 1852 5 0 0
v_cancel_customer_prepayment 1320 1320 0 0.00 0 3751 9 0 0
v_cancel_customer_reserve 1406 1406 0 0.00 0 642 4 0 0
v_cancel_payment_to_supplier 856 856 0 0.00 0 6829 14 0 0
v_cancel_supplier_invoice 870 852 18 2.06 0 8138 24 0 18
v_cancel_supplier_order 812 812 0 0.00 0 900 6 0 0
v_cancel_write_off 1912 1912 0 0.00 0 4422 19 0 0
v_max_id_clo_ord 5338 5338 0 0.00 0 729 2 0 0
v_max_id_clo_res 969 969 0 0.00 0 3027 15 0 0
v_max_id_ord_sup 5379 5379 0 0.00 0 3655 4 0 0
v_max_non_paid_invoice 1663 1663 0 0.00 0 402 3 0 0
v_max_non_paid_realizn 3718 3718 0 0.00 0 715 3 0 0
v_min_id_clo_ord 5338 5338 0 0.00 0 7458 10 0 0
v_min_id_clo_res 969 969 0 0.00 0 2800 6 0 0
v_min_id_ord_sup 5379 5379 0 0.00 0 5831 10 0 0
v_min_non_paid_invoice 5922 5922 0 0.00 0 12514 31 0 0
v_min_non_paid_realizn 6893 6893 0 0.00 0 9816 31 0 0
v_our_firm 10360 10360 0 0.00 0 82 1 0 0
v_reserve_write_off 19792 19792 0 0.00 0 6649 5 0 0
x_lock_depdocs_on_canc_invoice 11103 11103 0 0.00 0 19441 146 0 0
x_lock_depdocs_on_canc_sup_ord 406 406 0 0.00 0 12319 429 0 0
x_make_qstorno_1000_1200 5339 5339 0 0.00 1 26840 1204 0 0
x_make_qstorno_1200_2000 5379 5379 0 0.00 3 81249 3333 0 0
x_make_qstorno_2100_3300 461797 461797 0 0.00 0 78522 162 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 29-01-2023 16:40:29 to 29-01-2023 16:40:33.

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
14:08:45 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:09:46 23502 8192000000 9889967824 10194882560 971308688 10.599 100 86 172 0 999430512 5535552 22586272
14:11:14 25805 8192000000 9934331344 10252644352 1016706064 11.040 100 86 172 0 1046421136 7438960 22276112
14:12:41 11968 8192000000 9958733408 10284421120 1045326640 11.316 100 90 180 0 1077574992 9320976 22927376
14:14:03 9307 8192000000 10008336304 10355408896 1103532848 11.871 100 75 150 1 1132166912 10124400 18509664
14:15:17 5754 8192000000 10073201664 10431361024 1158187616 12.386 100 72 144 0 1186484064 11434576 16861872
14:16:26 4212 8192000000 10117557520 10484322304 1206172976 12.834 100 78 154 1 1234975696 11635968 17166752
14:17:33 3424 8192000000 10141844752 10515533824 1233423808 13.086 100 86 172 0 1265736992 14192912 18120272
14:18:38 2055 8192000000 10173757168 10552705024 1258948752 13.320 100 89 175 0 1292408064 13998304 19461008
14:19:42 1141 8192000000 10201326400 10581241856 1288187264 13.588 100 89 174 0 1322161664 13976928 19997472
14:20:44 24878 8192000000 10208300400 10599374848 1301900928 13.713 100 69 134 1 1326946672 9863120 15182624
14:22:10 7413 8192000000 10183900288 10598854656 1304351984 13.735 100 20 38 2 1314109296 5709280 4048032
14:23:19 5208 8192000000 10198280576 10612453376 1311625488 13.801 100 32 64 0 1325600736 6465344 7509904
14:24:29 6608 8192000000 10197624208 10613415936 1314877296 13.830 100 23 46 0 1326531648 6498272 5156080
14:25:36 60908 8192000000 10212505872 10632261632 1325204784 13.924 100 38 76 0 1340618464 6033600 9380080
14:27:38 6176 8192000000 10195443424 10615779328 1310911168 13.794 99 19 37 0 1320538096 5227776 4399152
14:28:47 10144 8192000000 10197950720 10617421824 1302003984 13.713 99 20 41 1 1312572064 5812688 4755392
14:29:57 5269 8192000000 10145408848 10545844224 1223927760 12.998 95 27 54 1 1234839280 5180080 5731440
14:31:03 40176 8192000000 10131918448 10530279424 1186657856 12.652 94 29 58 0 1198432400 4710432 7064112
14:32:45 5946 8192000000 10137529936 10529456128 1185939792 12.646 95 36 72 1 1204407744 10072448 8395504
14:33:51 5969 8192000000 10158641568 10561597440 1260298288 13.333 99 27 52 1 1271266560 4996224 5972048
14:35:02 45653 8192000000 10193915968 10601705472 1294609344 13.646 100 24 48 0 1304931424 4722288 5599792
14:36:57 4750 8192000000 10189671232 10598203392 1294108384 13.642 100 21 42 0 1304767248 5487696 5171168
14:38:02 5418 8192000000 10207838160 10621198336 1312186800 13.806 100 26 51 1 1322448336 4604416 5657120
14:39:13 7959 8192000000 10240008912 10650390528 1335681664 14.018 100 54 101 1 1355195808 6668192 12845952
14:40:26 88752 8192000000 10264391536 10685190144 1355186576 14.194 100 42 81 0 1373554560 8726256 9641728
14:43:11 24324 8192000000 10288908608 10710081536 1371935872 14.344 100 43 86 0 1397240208 15319408 9984928
14:44:41 7982 8192000000 10295920448 10721284096 1383671136 14.449 100 39 76 2 1407098016 14417648 9009232
14:46:01 11178 8192000000 10319366368 10742894592 1395944240 14.559 100 67 131 0 1429132144 17727584 15460320
14:47:24 9697 8192000000 10323952144 10749042688 1395024864 14.551 99 59 118 2 1426759168 17438112 14296192
14:48:56 11969 8192000000 10323059360 10748018688 1405370000 14.643 100 49 98 0 1436278880 19268352 11640528
14:50:13 6135 8192000000 10320327840 10745630720 1393563824 14.538 99 48 94 1 1426632112 20522960 12545328
14:51:24 10392 8192000000 10330262992 10755710976 1394071600 14.542 99 51 101 1 1435753280 29373136 12308544
14:52:41 2718 8192000000 10341519760 10760642560 1402164736 14.614 100 69 135 2 1446444720 27514704 16765280
14:53:49 932 8192000000 10342520768 10752626688 1395815088 14.558 100 93 186 0 1448809360 30813776 22180496
14:54:53 4102 8192000000 10320279264 10737758208 1370525760 14.332 98 60 115 1 1409083904 24702752 13855392
14:56:06 4052 8192000000 10334001872 10747777024 1387354224 14.482 100 74 148 1 1439930768 33807120 18769424
14:57:14 3729 8192000000 10321449760 10728001536 1353766016 14.181 97 72 142 2 1406890208 34963184 18161008
14:58:22 3035 8192000000 10326161856 10740150272 1380439568 14.420 100 66 132 0 1431689360 34577440 16672352
14:59:28 4015 8192000000 10324451120 10740043776 1363575680 14.269 98 55 110 0 1407571472 30358496 13637296
15:00:38 1056 8192000000 10336501552 10746667008 1384139680 14.454 100 76 148 1 1439557776 36598928 18819168
15:01:43 11983 8192000000 10327238896 10746376192 1371803408 14.343 99 39 76 0 1419341968 37069952 10468608
15:03:03 2681 8192000000 10369986864 10785726464 1400614208 14.600 100 68 136 0 1468914464 50124736 18175520
15:04:11 10644 8192000000 10342323952 10769940480 1392637216 14.529 100 37 74 0 1445219136 42398720 10183200
15:05:23 12932 8192000000 10349639376 10775937024 1375591712 14.377 99 44 85 2 1444414048 56353312 12469024
15:06:39 37664 8192000000 10314859232 10760085504 1359244848 14.231 99 20 40 0 1409181712 43391424 6545440
15:08:17 51588 8192000000 10293665072 10750312448 1357234208 14.213 99 17 32 0 1396864432 35193808 4436416
15:10:49 38627 8192000000 10296360096 10736222208 1367542224 14.305 99 27 56 0 1386774816 12947152 6285440
15:13:00 50025 8192000000 10299880112 10741714944 1379974000 14.416 100 24 48 1 1404522256 19502112 5046144
15:14:51 52180 8192000000 10279062144 10736148480 1367220032 14.302 99 25 48 1 1385004896 12154400 5630464
15:16:45 120410 8192000000 10305578880 10761928704 1390705344 14.512 100 11 23 0 1404161472 11475856 1980272
15:19:46 64799 8192000000 10305305168 10762522624 1387742736 14.486 99 12 25 0 1405314768 14855232 2716800
15:21:59 63027 8192000000 10304914912 10755014656 1400680112 14.601 100 27 54 1 1416705744 9955296 6070336
15:24:10 51312 8192000000 10310870048 10766626816 1387664720 14.485 98 19 38 0 1406271904 13999024 4608160
15:26:02 85842 8192000000 10315378224 10771644416 1405196944 14.641 100 26 50 0 1429223888 17361360 6665584
15:28:47 43215 8192000000 10326663712 10786353152 1420916944 14.781 100 30 60 1 1438640960 10737136 6986880
15:30:38 47164 8192000000 10329057248 10779357184 1399749008 14.593 99 27 54 0 1418562160 12489248 6323904
15:32:34 37259 8192000000 10302022448 10772963328 1380705472 14.423 98 15 29 0 1397400592 13165376 3529744
15:34:32 76040 8192000000 10271500768 10739789824 1343530528 14.089 96 11 22 0 1354425408 8499600 2395280
15:36:48 95067 8192000000 10307122128 10768715776 1380479840 14.421 98 25 50 0 1398268992 11820304 5968848
15:39:24 52147 8192000000 10292359424 10750763008 1381710608 14.432 99 19 38 0 1407645152 21518640 4415904
15:41:18 57366 8192000000 10285751088 10752192512 1365403440 14.286 98 16 32 0 1380684768 12130080 3151248
15:44:10 35683 8192000000 10278533776 10733662208 1354553760 14.188 98 26 52 0 1366654048 6469408 5630880
15:45:47 44810 8192000000 10265898816 10717634560 1296866144 13.667 94 28 55 0 1312796096 9752640 6177312
15:47:33 56951 8192000000 10257453200 10716803072 1333155664 13.996 98 15 30 0 1346047328 9928832 2962832
15:49:31 7278 8192000000 10290823968 10723000320 1356142736 14.203 99 60 118 1 1379653120 9773328 13737056
15:50:39 93792 8192000000 10263465760 10719985664 1314795472 13.830 96 23 46 0 1334321568 14833504 4692592
15:53:13 41067 8192000000 10270271536 10712072192 1329780384 13.965 97 30 58 0 1347585008 11337248 6467376
15:54:55 80623 8192000000 10262459008 10715840512 1300588912 13.701 95 12 24 1 1316447152 13375616 2482624
15:57:28 107561 8192000000 10253947728 10709012480 1321744800 13.893 97 28 56 1 1339849984 10781360 7323824
16:00:34 60072 8192000000 10285014688 10727862272 1356392800 14.205 99 27 54 0 1371172800 8939952 5840048
16:02:59 63163 8192000000 10276427168 10737758208 1370813296 14.334 99 16 32 1 1386769552 12226400 3729856
16:05:03 43511 8192000000 10258659424 10714112000 1331144416 13.977 97 24 48 1 1351618272 14436720 6037136
16:06:47 59292 8192000000 10276333584 10736287744 1366529024 14.296 99 12 24 0 1381150560 12137776 2483760
16:08:47 80090 8192000000 10298384080 10765619200 1388197664 14.490 100 14 28 0 1410470240 19172880 3099696
16:11:17 47517 8192000000 10296393056 10769453056 1370386064 14.331 98 12 24 0 1390474624 17239696 2848864
16:13:36 73156 8192000000 10317685136 10766163968 1381197152 14.427 99 28 56 0 1403352240 16116656 6038432
16:15:52 61122 8192000000 10313959360 10757906432 1382897872 14.442 99 20 38 0 1396113616 9086192 4129552
16:17:54 23740 8192000000 10301966576 10749411328 1360225568 14.239 97 26 52 1 1373832448 7979424 5627456
16:19:18 33789 8192000000 10304670336 10773241856 1398910640 14.585 100 16 32 1 1415351264 12715888 3724736
16:20:55 62484 8192000000 10308601152 10773135360 1400033712 14.595 100 10 20 0 1416076176 13651920 2390544
16:23:07 21646 8192000000 10305447232 10753761280 1380969584 14.425 99 33 66 0 1395804688 7251536 7583568
16:24:28 57257 8192000000 10291433504 10750717952 1357391360 14.214 98 39 78 1 1378963648 13241312 8330976
16:26:34 35746 8192000000 10299621232 10746056704 1384657392 14.458 100 33 66 0 1405583568 13713056 7213120
16:28:23 82966 8192000000 10282898512 10740568064 1376945632 14.389 99 17 32 0 1389841152 9573472 3322048
16:31:13 49789 8192000000 10283651616 10735071232 1359532112 14.233 98 20 41 0 1374165216 9860608 4772496
16:33:08 60656 8192000000 10238397760 10688741376 1297000688 13.668 94 14 29 0 1307428704 7035760 3392256
16:35:14 77653 8192000000 10275675520 10730979328 1372975376 14.354 100 11 22 0 1388663488 12816480 2871632
16:38:15 32090 8192000000 9210993760 9491017728 370848416 4.330 27 1 2 0 376196336 5305568 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 29-01-2023 16:40:35 to 29-01-2023 16:40:50.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 104 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 29-01-2023 16:40:51 to 29-01-2023 16:40:53.

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          239663
MON$OLDEST_ACTIVE               239664
MON$OLDEST_SNAPSHOT             239664
MON$NEXT_TRANSACTION            239664
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                       2055648
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.33657 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33657 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33657 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33657 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 ---
:960: 
:961: mscs-test-161.budzdorov.ru    Sun Jan 29 16:38:49 2023
:962:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:963: 
--- end of diff output ---

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

29.01.23 16:40:54. 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
2023-01-29 16:40:56 No severe PSQL-related problems occured
29.01.23 16:40:58. End of report.