Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 21.02.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 15373172 kB
MemAvailable 30408924 kB
Buffers 0 kB
Cached 16323308 kB
SwapCached 3140 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-02-21 16:38:44
DTS_END                         2023-02-21 16:38:44.7630
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/34648
TRN_ID                          234745
ATT_ID                          658
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: 9484.85 20558 1138182
customer order: creation 170.37 371 20444
customer order: refuse 14.12 380 1694
order to supplier: creation 45.08 1207 5409
order to supplier: removal 3.63 93379 436
invoice (draft): creation 44.66 3035 5359
invoice (draft): removal 56.86 697 6823
invoice accept: apply 40.17 16709 4821
invoice accept: cancel 51.19 760 6143
customer reserve: creation 3757.34 164 450881
customer reserve: removal 5012.73 53 601528
realization accept: apply 81.88 179 9826
realization accept: cancel 79.59 101 9551
payment from customer: creation 58.65 121 7038
payment from customer: removal 5.58 87 669
payment to supplier: creation 48.29 152 5795
payment to supplier: removal 3.15 79 378
service: total inventory turnovers 4.62 1081 554
service: total monetary turnovers 6.16 20 739
service: aggregate perf. data 0.75 16539 90
service: refresh index statistics 0.03 276041 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 21-02-2023 16:39:52 to 21-02-2023 16:39:54.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4187
TEST_TIME 2 3413
TEST_TIME 3 3619
TEST_TIME 4 4502
TEST_TIME 5 4337
TEST_TIME 6 4277
TEST_TIME 7 4370
TEST_TIME 8 4532
TEST_TIME 9 4660
TEST_TIME 10 4894
TEST_TIME 11 5171
TEST_TIME 12 5394
TEST_TIME 13 5328
TEST_TIME 14 5559
TEST_TIME 15 5914
TEST_TIME 16 6582
TEST_TIME 17 6749
TEST_TIME 18 6993
TEST_TIME 19 7430
TEST_TIME 20 8112
TEST_TIME 21 8637
TEST_TIME 22 8793
TEST_TIME 23 8772
TEST_TIME 24 8687
TEST_TIME 25 9733
TEST_TIME 26 9903
TEST_TIME 27 9749
TEST_TIME 28 9875
TEST_TIME 29 9829
TEST_TIME 30 10117
TEST_TIME 31 9910
TEST_TIME 32 9820
TEST_TIME 33 9768
TEST_TIME 34 9696
TEST_TIME 35 9555
TEST_TIME 36 9525
TEST_TIME 37 9603
TEST_TIME 38 9779
TEST_TIME 39 9737
TEST_TIME 40 9699
TEST_TIME 41 9605
TEST_TIME 42 9474
TEST_TIME 43 9342
TEST_TIME 44 9411
TEST_TIME 45 9337
TEST_TIME 46 9421
TEST_TIME 47 9420
TEST_TIME 48 9458
TEST_TIME 49 9375
TEST_TIME 50 9388
TEST_TIME 51 9451
TEST_TIME 52 9404
TEST_TIME 53 9525
TEST_TIME 54 9531
TEST_TIME 55 9505
TEST_TIME 56 9668
TEST_TIME 57 9668
TEST_TIME 58 9771
TEST_TIME 59 9710
TEST_TIME 60 9675
TEST_TIME 61 9610
TEST_TIME 62 9530
TEST_TIME 63 9457
TEST_TIME 64 9479
TEST_TIME 65 9530
TEST_TIME 66 9452
TEST_TIME 67 9374
TEST_TIME 68 9332
TEST_TIME 69 9303
TEST_TIME 70 9369
TEST_TIME 71 9330
TEST_TIME 72 9288
TEST_TIME 73 9374
TEST_TIME 74 9352
TEST_TIME 75 9408
TEST_TIME 76 9404
TEST_TIME 77 9459
TEST_TIME 78 9448
TEST_TIME 79 9491
TEST_TIME 80 9550
TEST_TIME 81 9532
TEST_TIME 82 9613
TEST_TIME 83 9558
TEST_TIME 84 9454
TEST_TIME 85 9420
TEST_TIME 86 9440
TEST_TIME 87 9393
TEST_TIME 88 9330
TEST_TIME 89 9351
TEST_TIME 90 9339
TEST_TIME 91 9323
TEST_TIME 92 9319
TEST_TIME 93 9416
TEST_TIME 94 9432
TEST_TIME 95 9427
TEST_TIME 96 9504
TEST_TIME 97 9469
TEST_TIME 98 9562
TEST_TIME 99 9542
TEST_TIME 100 9557
TEST_TIME 101 9595
TEST_TIME 102 9597
TEST_TIME 103 9636
TEST_TIME 104 9563
TEST_TIME 105 9491
TEST_TIME 106 9518
TEST_TIME 107 9499
TEST_TIME 108 9449
TEST_TIME 109 9509
TEST_TIME 110 9454
TEST_TIME 111 9449
TEST_TIME 112 9416
TEST_TIME 113 9387
TEST_TIME 114 9350
TEST_TIME 115 9369
TEST_TIME 116 9441
TEST_TIME 117 9436
TEST_TIME 118 9442
TEST_TIME 119 9435
TEST_TIME 120 9488

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 21-02-2023 16:39:56 to 21-02-2023 16:39: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 1554830 1554830 0 0.00 0 17618 6 0 0
doc_list_biud 1554828 1554828 0 0.00 0 64507 26 0 0
sp_add_invoice_to_stock 4950 4821 129 2.60 44 196443 16710 0 129
sp_cancel_adding_invoice 6143 6143 0 0.00 5 72409 761 0 0
sp_cancel_client_order 1694 1694 0 0.00 4 12875 380 0 0
sp_cancel_customer_reserve 601528 601528 0 0.00 0 64510 54 0 0
sp_cancel_pay_from_customer 669 669 0 0.00 2 8746 88 0 0
sp_cancel_pay_to_supplier 378 378 0 0.00 2 5177 79 0 0
sp_cancel_supplier_invoice 6841 6823 18 0.26 1 18006 698 0 18
sp_cancel_supplier_order 436 436 0 0.00 4 976138 93380 0 0
sp_cancel_write_off 9551 9551 0 0.00 1 11888 101 0 0
sp_client_order 20444 20444 0 0.00 3 45072 371 0 0
sp_customer_reserve 450881 450881 0 0.00 0 90177 164 0 0
sp_fill_shopping_cart 31207 31207 0 0.00 1 9604 33 0 0
sp_fill_shopping_cart_clo_res 450874 450874 0 0.00 0 16299 15 0 0
sp_get_clo_for_invoice 4813 4813 0 0.00 0 19131 277 0 0
sp_kill_cost_storno 16741 16741 0 0.00 0 8741 45 0 0
sp_kill_qstorno_ret_qs2qd 1219866 1219866 0 0.00 0 64386 29 0 0
sp_kill_qty_storno 640814 640814 0 0.00 0 64507 63 0 0
sp_lock_selected_doc 20242 20242 0 0.00 0 7942 6 0 0
sp_make_cost_storno 27472 27472 0 0.00 0 17615 58 0 0
sp_multiply_rows_for_pdistr 27472 27472 0 0.00 0 7596 27 0 0
sp_multiply_rows_for_qdistr 20444 20444 0 0.00 0 45065 172 0 0
sp_pay_from_customer 7038 7038 0 0.00 3 15556 122 0 0
sp_pay_to_supplier 5795 5795 0 0.00 4 17548 152 0 0
sp_payment_common 12833 12833 0 0.00 1 13049 28 0 0
sp_qd_handle_on_cancel_clo 1694 1694 0 0.00 0 12868 340 0 0
sp_qd_handle_on_invoice_upd_sts 10956 10956 0 0.00 0 28329 327 0 0
sp_qd_handle_on_reserve_upd_sts 9826 9826 0 0.00 0 16553 66 0 0
sp_reserve_write_off 9826 9826 0 0.00 6 16691 180 0 0
sp_supplier_invoice 5359 5359 0 0.00 6 93807 3035 0 0
sp_supplier_order 5409 5409 0 0.00 4 29355 1207 0 0
srv_aggregate_perf_data 91 90 1 1.09 3233 129084 16540 0 1
srv_fill_mon_cache_memory 62 62 0 0.00 1039 132246 47997 0 0
srv_find_qd_qs_mism 1090888 1090888 0 0.00 0 16813 5 0 0
srv_make_invnt_saldo 554 554 0 0.00 24 42974 1081 0 0
srv_make_money_saldo 739 739 0 0.00 1 968 20 0 0
srv_recalc_idx_stat 4 4 0 0.00 106938 499396 276042 0 0
t$perf-abend:sp_add_invoice_to_stock 129 129 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 18 18 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4821 4821 0 0.00 0 14070 162 0 0
t$perf-norm:sp_cancel_adding_invoice 420 420 0 0.00 0 22529 207 0 0
t$perf-norm:sp_cancel_client_order 1694 1694 0 0.00 0 3096 11 0 0
t$perf-norm:sp_cancel_customer_reserve 688 688 0 0.00 0 4927 18 0 0
t$perf-norm:sp_cancel_pay_from_customer 669 669 0 0.00 0 2012 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 378 378 0 0.00 0 3342 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 369 369 0 0.00 0 3411 20 0 0
t$perf-norm:sp_cancel_supplier_order 436 436 0 0.00 0 23045 1546 0 0
t$perf-norm:sp_cancel_write_off 949 949 0 0.00 0 6780 14 0 0
t$perf-norm:sp_client_order 20444 20444 0 0.00 0 15995 19 0 0
t$perf-norm:sp_customer_reserve 905 905 0 0.00 0 5017 20 0 0
t$perf-norm:sp_pay_from_customer 7038 7038 0 0.00 0 7226 9 0 0
t$perf-norm:sp_pay_to_supplier 5795 5795 0 0.00 0 6796 8 0 0
t$perf-norm:sp_reserve_write_off 9826 9826 0 0.00 0 6765 11 0 0
t$perf-norm:sp_supplier_invoice 5359 5359 0 0.00 0 7829 16 0 0
t$perf-norm:sp_supplier_order 5409 5409 0 0.00 0 10442 14 0 0
t$perf-norm:srv_aggregate_perf_data 90 90 0 0.00 0 341 15 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 2 0 0 0
t$perf-norm:srv_make_invnt_saldo 554 554 0 0.00 0 815 16 0 0
t$perf-norm:srv_make_money_saldo 739 739 0 0.00 0 882 12 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 22 8 0 0
v_add_invoice_to_stock 9884 9626 258 2.61 0 8123 18 0 258
v_all_customers 37234 37234 0 0.00 0 277 0 0 0
v_all_suppliers 30034 30034 0 0.00 0 276 1 0 0
v_all_wares 40886 40886 0 0.00 0 21 1 0 0
v_cancel_adding_invoice 840 840 0 0.00 0 4564 16 0 0
v_cancel_client_order 3388 3388 0 0.00 0 3844 7 0 0
v_cancel_customer_prepayment 1338 1338 0 0.00 0 3038 9 0 0
v_cancel_customer_reserve 1376 1376 0 0.00 0 2729 6 0 0
v_cancel_payment_to_supplier 756 756 0 0.00 0 1525 8 0 0
v_cancel_supplier_invoice 774 738 36 4.65 0 2108 15 0 36
v_cancel_supplier_order 872 872 0 0.00 0 3844 10 0 0
v_cancel_write_off 1898 1898 0 0.00 0 1480 14 0 0
v_max_id_clo_ord 5409 5409 0 0.00 0 814 1 0 0
v_max_id_clo_res 905 905 0 0.00 0 1280 11 0 0
v_max_id_ord_sup 5355 5355 0 0.00 0 4557 6 0 0
v_max_non_paid_invoice 1542 1542 0 0.00 0 344 2 0 0
v_max_non_paid_realizn 3733 3733 0 0.00 0 9786 8 0 0
v_min_id_clo_ord 5409 5409 0 0.00 0 9581 11 0 0
v_min_id_clo_res 905 905 0 0.00 0 6427 20 0 0
v_min_id_ord_sup 5355 5355 0 0.00 0 6922 9 0 0
v_min_non_paid_invoice 5795 5795 0 0.00 0 7600 22 0 0
v_min_non_paid_realizn 7038 7038 0 0.00 0 6102 18 0 0
v_our_firm 10262 10262 0 0.00 0 277 1 0 0
v_reserve_write_off 19652 19652 0 0.00 0 4578 6 0 0
x_lock_depdocs_on_canc_invoice 12597 12597 0 0.00 0 11421 108 0 0
x_lock_depdocs_on_canc_sup_ord 436 436 0 0.00 0 12478 303 0 0
x_make_qstorno_1000_1200 5409 5409 0 0.00 1 29235 1133 0 0
x_make_qstorno_1200_2000 5359 5359 0 0.00 3 86875 2919 0 0
x_make_qstorno_2100_3300 450881 450881 0 0.00 0 90175 147 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 21-02-2023 16:40:00 to 21-02-2023 16:40: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
14:08:48 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:09:49 23662 8192000000 9893029536 10197766144 974566880 10.631 100 88 176 0 1003320560 5401776 23351904
14:11:18 26190 8192000000 9945294256 10262540288 1024674320 11.117 100 91 182 0 1055893520 7452016 23767184
14:12:49 13691 8192000000 9973484384 10306146304 1058080496 11.438 100 78 156 0 1086541456 8744704 19716256
14:14:09 11549 8192000000 10029519952 10379735040 1122330400 12.049 100 82 164 0 1152215632 10357120 19528112
14:15:24 10111 8192000000 10088676080 10447736832 1172552640 12.521 100 78 155 0 1201709872 11543952 17613280
14:16:38 3386 8192000000 10134964880 10501566464 1220477728 12.966 100 85 169 0 1251910800 13053152 18379920
14:17:47 2728 8192000000 10163306064 10540883968 1250650608 13.244 100 71 137 0 1278628112 12387328 15590176
14:18:53 2744 8192000000 10184236320 10565455872 1272474544 13.444 100 84 166 1 1303355648 12447168 18433936
14:19:58 1913 8192000000 10217585328 10602045440 1300523280 13.700 100 81 162 2 1332553200 14050992 17978928
14:21:03 708 8192000000 10231291648 10620559360 1318555360 13.864 100 85 169 1 1350473824 13656160 18262304
14:22:06 7181 8192000000 10220574592 10629021696 1324832832 13.920 100 38 77 0 1340189344 5819072 9537440
14:23:14 6210 8192000000 10218863280 10631839744 1329682192 13.964 100 35 70 0 1345973392 8335488 7955712
14:24:20 18267 8192000000 10217512048 10636615680 1336484720 14.026 100 13 26 0 1345073200 6179728 2408752
14:25:39 8298 8192000000 10226559680 10647191552 1342857808 14.083 100 33 70 1 1357278160 6255456 8164896
14:26:48 11491 8192000000 10246524480 10658242560 1339294688 14.051 100 49 94 1 1356564032 5727792 11541552
14:27:59 4412 8192000000 10236645584 10652626944 1326906656 13.939 99 32 61 0 1339851600 5304864 7640080
14:29:06 5455 8192000000 10206911600 10621493248 1294546032 13.646 97 20 40 0 1305068144 6103840 4418272
14:30:12 9513 8192000000 10178750400 10587033600 1253798400 13.273 96 12 24 0 1261462288 5032448 2631440
14:31:23 6445 8192000000 10166527536 10572582912 1270499568 13.426 99 53 107 0 1290953776 7958208 12496000
14:32:35 9125 8192000000 10153422864 10547208192 1220038976 12.962 97 33 67 0 1234050080 5603792 8407312
14:33:46 5066 8192000000 10159899264 10563043328 1266086464 13.386 100 26 52 0 1277660848 5174640 6399744
14:34:51 63280 8192000000 10168666240 10563735552 1265908544 13.384 100 49 99 1 1283159136 5204048 12046544
14:36:55 7278 8192000000 10185879232 10588438528 1288076048 13.587 100 27 53 1 1300497280 5463088 6958144
14:38:02 10564 8192000000 10199357056 10611146752 1306290800 13.752 100 18 34 0 1315062336 5028464 3743072
14:39:19 18174 8192000000 10237999632 10647236608 1329074768 13.959 100 30 58 1 1340845360 5586736 6183856
14:40:40 59992 8192000000 10259689952 10674798592 1347725712 14.127 100 40 80 0 1368884976 12559488 8599776
14:42:52 41485 8192000000 10271363520 10693713920 1364618992 14.279 100 27 51 0 1382692864 11191968 6881904
14:44:45 22502 8192000000 10297579792 10721370112 1383672016 14.449 100 48 96 0 1412995760 17892928 11430816
14:46:18 13525 8192000000 10297454272 10718949376 1380177264 14.418 99 48 96 1 1412864672 20862064 11825344
14:47:39 14496 8192000000 10313115632 10734931968 1395634960 14.556 100 49 97 0 1425649008 18308176 11705872
14:49:01 10892 8192000000 10325867456 10755276800 1408417008 14.670 100 41 80 1 1440138176 20994944 10726224
14:50:16 3442 8192000000 10335561664 10764132352 1412548368 14.707 100 42 84 1 1444029120 20709808 10770944
14:51:25 9494 8192000000 10365755376 10782924800 1428472944 14.848 100 70 138 0 1473650208 28570768 16606496
14:52:40 3978 8192000000 10364533120 10785275904 1423510032 14.804 100 65 130 1 1472933840 32127168 17296640
14:53:51 1038 8192000000 10376627536 10795278336 1422552400 14.795 100 83 167 2 1479025376 34784384 21688592
14:54:58 4212 8192000000 10340574128 10768379904 1403380944 14.625 100 47 94 0 1448976544 32783904 12811696
14:56:08 2175 8192000000 10321202496 10733137920 1370325760 14.330 99 79 156 5 1424312896 33893936 20093200
14:57:15 12736 8192000000 10327153872 10749108224 1368383040 14.313 99 45 89 1 1423390288 42514144 12493104
14:58:31 12637 8192000000 10313458672 10740297728 1376733776 14.387 100 37 73 0 1418961504 32347488 9880240
14:59:45 37430 8192000000 10280427712 10712559616 1334493616 14.008 97 23 46 2 1370516016 29645888 6376512
15:01:29 28823 8192000000 10287232704 10728472576 1355997584 14.201 99 18 36 0 1388163120 27455728 4709808
15:03:00 31515 8192000000 10267930800 10705113088 1340177536 14.059 99 19 38 0 1386382528 40358000 5846992
15:04:48 27144 8192000000 10262870352 10700288000 1344413376 14.097 99 12 22 0 1360194720 13400416 2380928
15:06:33 27925 8192000000 10267125152 10713362432 1316367424 13.844 96 11 22 0 1333008192 14108256 2532512
15:08:03 61396 8192000000 10249069120 10674749440 1253212400 13.268 92 19 39 1 1271957680 14553408 4191872
15:10:05 38487 8192000000 10271665824 10706845696 1347657360 14.126 99 35 63 1 1371505136 16849728 6998048
15:11:53 52948 8192000000 10293165712 10710171648 1344941680 14.102 99 51 98 2 1375455504 18617968 11895856
15:13:58 98331 8192000000 10272776128 10720501760 1360555760 14.242 100 18 36 0 1388860768 23971232 4333776
15:16:40 19802 8192000000 10261892192 10701701120 1348825808 14.137 99 33 66 0 1367144944 10949280 7369856
15:18:01 62415 8192000000 10286258576 10732732416 1363167136 14.266 99 10 20 0 1375110896 9346288 2597472
15:20:09 72823 8192000000 10293783952 10739142656 1384179312 14.454 100 20 38 0 1404452032 15295648 4977072
15:22:22 23817 8192000000 10281681568 10727378944 1369852816 14.326 99 27 49 3 1391354336 15390272 6111248
15:23:52 76086 8192000000 10284591232 10743570432 1372477760 14.349 99 21 42 0 1393528688 16281872 4769056
15:26:17 52678 8192000000 10265065840 10712686592 1345432720 14.106 98 13 26 1 1361958736 13853792 2672224
15:28:11 43837 8192000000 10286033680 10735525888 1380265200 14.419 100 27 54 0 1396924288 10557104 6101984
15:30:52 25789 8192000000 10302964032 10761027584 1392434016 14.528 100 23 46 0 1417689152 20028864 5226272
15:32:33 91569 8192000000 10310563872 10763366400 1393510720 14.537 100 21 42 0 1414596496 16348096 4737680
15:35:10 52194 8192000000 10292725568 10743611392 1370064096 14.328 98 17 35 0 1390303584 16500880 3738608
15:37:10 24124 8192000000 10297666304 10737549312 1370984224 14.336 99 28 55 0 1383687040 6715808 5987008
15:38:36 15966 8192000000 10300332528 10732388352 1375292576 14.374 100 41 82 0 1394446336 10372000 8781760
15:39:53 90190 8192000000 10283206240 10732380160 1358488496 14.224 98 23 46 0 1373845264 10712208 4644560
15:43:22 44454 8192000000 10257975808 10722607104 1355330896 14.195 99 9 18 0 1365791456 8422032 2038528
15:45:07 45598 8192000000 10271780016 10726006784 1364756512 14.280 99 10 20 0 1378965248 11746992 2461744
15:47:00 89760 8192000000 10275247216 10747580416 1369376576 14.321 99 20 41 0 1388558496 14669552 4512368
15:49:30 27508 8192000000 10258024176 10694283264 1286648480 13.574 94 33 64 0 1300130752 5933296 7548976
15:51:04 66811 8192000000 10243305936 10686390272 1267727104 13.401 94 16 33 1 1279334704 7980832 3626768
15:53:17 80517 8192000000 10253707840 10704158720 1328043600 13.949 98 9 14 0 1337960320 8172416 1744304
15:55:38 50163 8192000000 10265831552 10711388160 1334035184 14.004 98 34 68 1 1357356640 15582208 7739248
15:57:33 55440 8192000000 10259811712 10708090880 1334208560 14.005 99 19 38 0 1351436352 13212704 4015088
15:59:34 24895 8192000000 10280756720 10712412160 1356076752 14.202 100 41 82 0 1375523328 10167104 9279472
16:01:00 80613 8192000000 10257173104 10727682048 1337514128 14.035 98 8 16 0 1351475872 12310128 1651616
16:03:35 57313 8192000000 10268325872 10716794880 1354695440 14.190 99 26 48 1 1370417920 10458048 5264432
16:05:33 79074 8192000000 10269177216 10722959360 1351248080 14.159 99 11 22 0 1367340720 13331152 2761488
16:07:52 76017 8192000000 10286349440 10735144960 1339280032 14.051 97 19 38 0 1352572880 9443504 3849344
16:10:38 75357 8192000000 10276679776 10745241600 1330001312 13.967 96 11 22 0 1345727120 13272048 2453760
16:12:54 24926 8192000000 10297902944 10743234560 1382038416 14.435 100 23 46 0 1396977664 9915952 5023296
16:14:30 56405 8192000000 10305363520 10766864384 1392619936 14.529 100 17 34 0 1411477968 14849664 4008368
16:16:28 83338 8192000000 10307912384 10760486912 1371498704 14.340 98 9 18 0 1391936896 18072112 2366080
16:18:53 132245 8192000000 10286099072 10744365056 1313903088 13.821 94 15 30 0 1329246880 12276528 3067264
16:22:07 93803 8192000000 10294917504 10739650560 1364650080 14.279 98 28 56 0 1378045648 7146432 6249136
16:25:04 68285 8192000000 10306275920 10765864960 1384904944 14.460 99 20 40 0 1403481184 14041360 4534880
16:27:43 107053 8192000000 10308884240 10774994944 1376495184 14.385 98 9 17 0 1395460256 17136112 1828960
16:30:30 94630 8192000000 10300394544 10758901760 1362125168 14.256 97 19 36 0 1381066272 14223680 4717424
16:33:07 50173 8192000000 10279550480 10738708480 1369402304 14.322 98 23 46 1 1392724976 17840784 5481888
16:35:01 72673 8192000000 10299788144 10760978432 1380280240 14.419 99 4 9 0 1391815824 10549392 986192
16:37:18 52610 8192000000 10269515584 10718371840 1338680800 14.046 96 29 58 0 1355623680 10709024 6233856

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 21-02-2023 16:40:06 to 21-02-2023 16:40:21.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 129 v_add_invoice_to_stock 335544517
except 18 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 21-02-2023 16:40:22 to 21-02-2023 16:40: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          234836
MON$OLDEST_ACTIVE               234986
MON$OLDEST_SNAPSHOT             234986
MON$NEXT_TRANSACTION            234986
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                       2019008
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.33665 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33665 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33665 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33665 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 ---
:350: 
:351: mscs-test-161.budzdorov.ru    Tue Feb 21 16:38:52 2023
:352:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:353: 
:354: 
:355: mscs-test-161.budzdorov.ru    Tue Feb 21 16:38:52 2023
:356:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:357: 
--- end of diff output ---

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

21.02.23 16:40: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
2023-02-21 16:40:28 No severe PSQL-related problems occured
21.02.23 16:40:29. End of report.