Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 04.02.2023 16:43
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 15005428 kB
MemAvailable 30385456 kB
Buffers 0 kB
Cached 16677736 kB
SwapCached 3572 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-04 16:42:41
DTS_END                         2023-02-04 16:42:41.0630
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/55194
TRN_ID                          247962
ATT_ID                          718
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: 9659.89 27117 1159187
customer order: creation 179.05 341 21486
customer order: refuse 15.52 314 1862
order to supplier: creation 47.16 1159 5659
order to supplier: removal 3.50 101549 420
invoice (draft): creation 48.29 3225 5795
invoice (draft): removal 55.91 814 6709
invoice accept: apply 44.27 17126 5312
invoice accept: cancel 49.98 838 5998
customer reserve: creation 4021.19 172 482543
customer reserve: removal 4880.60 56 585672
realization accept: apply 89.55 178 10746
realization accept: cancel 91.48 106 10977
payment from customer: creation 61.44 114 7373
payment from customer: removal 5.96 82 715
payment to supplier: creation 50.13 132 6015
payment to supplier: removal 3.38 51 406
service: total inventory turnovers 4.92 1163 590
service: total monetary turnovers 6.70 56 804
service: aggregate perf. data 0.84 22180 101
service: refresh index statistics 0.03 392693 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 04-02-2023 16:43:51 to 04-02-2023 16:43:53.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2428
TEST_TIME 2 3844
TEST_TIME 3 4529
TEST_TIME 4 5099
TEST_TIME 5 4953
TEST_TIME 6 5697
TEST_TIME 7 6527
TEST_TIME 8 6174
TEST_TIME 9 7175
TEST_TIME 10 7412
TEST_TIME 11 7423
TEST_TIME 12 7444
TEST_TIME 13 7464
TEST_TIME 14 7261
TEST_TIME 15 7650
TEST_TIME 16 7884
TEST_TIME 17 7853
TEST_TIME 18 7867
TEST_TIME 19 8009
TEST_TIME 20 8079
TEST_TIME 21 8403
TEST_TIME 22 8197
TEST_TIME 23 8310
TEST_TIME 24 8770
TEST_TIME 25 8910
TEST_TIME 26 9284
TEST_TIME 27 9665
TEST_TIME 28 10596
TEST_TIME 29 11331
TEST_TIME 30 11246
TEST_TIME 31 11166
TEST_TIME 32 11092
TEST_TIME 33 10902
TEST_TIME 34 10757
TEST_TIME 35 10796
TEST_TIME 36 10919
TEST_TIME 37 10787
TEST_TIME 38 10768
TEST_TIME 39 10717
TEST_TIME 40 10693
TEST_TIME 41 10763
TEST_TIME 42 10777
TEST_TIME 43 10725
TEST_TIME 44 10820
TEST_TIME 45 10687
TEST_TIME 46 10641
TEST_TIME 47 10481
TEST_TIME 48 10403
TEST_TIME 49 10284
TEST_TIME 50 10470
TEST_TIME 51 10462
TEST_TIME 52 10332
TEST_TIME 53 10304
TEST_TIME 54 10273
TEST_TIME 55 10264
TEST_TIME 56 10370
TEST_TIME 57 10342
TEST_TIME 58 10246
TEST_TIME 59 10276
TEST_TIME 60 10279
TEST_TIME 61 10186
TEST_TIME 62 10125
TEST_TIME 63 10054
TEST_TIME 64 9992
TEST_TIME 65 10013
TEST_TIME 66 9984
TEST_TIME 67 9964
TEST_TIME 68 9984
TEST_TIME 69 10001
TEST_TIME 70 10000
TEST_TIME 71 10165
TEST_TIME 72 10105
TEST_TIME 73 10215
TEST_TIME 74 10166
TEST_TIME 75 10184
TEST_TIME 76 10198
TEST_TIME 77 10172
TEST_TIME 78 10235
TEST_TIME 79 10257
TEST_TIME 80 10153
TEST_TIME 81 10102
TEST_TIME 82 10089
TEST_TIME 83 10040
TEST_TIME 84 10017
TEST_TIME 85 10065
TEST_TIME 86 9992
TEST_TIME 87 10020
TEST_TIME 88 10013
TEST_TIME 89 9965
TEST_TIME 90 9927
TEST_TIME 91 9897
TEST_TIME 92 9822
TEST_TIME 93 9824
TEST_TIME 94 9824
TEST_TIME 95 9838
TEST_TIME 96 9827
TEST_TIME 97 9859
TEST_TIME 98 9864
TEST_TIME 99 9903
TEST_TIME 100 9910
TEST_TIME 101 9896
TEST_TIME 102 9937
TEST_TIME 103 9935
TEST_TIME 104 9919
TEST_TIME 105 9912
TEST_TIME 106 9952
TEST_TIME 107 9946
TEST_TIME 108 9874
TEST_TIME 109 9842
TEST_TIME 110 9814
TEST_TIME 111 9802
TEST_TIME 112 9773
TEST_TIME 113 9734
TEST_TIME 114 9677
TEST_TIME 115 9674
TEST_TIME 116 9676
TEST_TIME 117 9653
TEST_TIME 118 9669
TEST_TIME 119 9660
TEST_TIME 120 9662

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 04-02-2023 16:43:55 to 04-02-2023 16:43:58.

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 1606507 1606507 0 0.00 0 30160 6 0 0
doc_list_biud 1606507 1606507 0 0.00 0 19021 26 0 0
sp_add_invoice_to_stock 5414 5312 102 1.88 17 177748 17126 0 102
sp_cancel_adding_invoice 5998 5998 0 0.00 4 66275 839 0 0
sp_cancel_client_order 1862 1862 0 0.00 4 10048 314 0 0
sp_cancel_customer_reserve 585672 585672 0 0.00 0 17461 56 0 0
sp_cancel_pay_from_customer 715 715 0 0.00 2 4003 82 0 0
sp_cancel_pay_to_supplier 406 406 0 0.00 3 943 51 0 0
sp_cancel_supplier_invoice 6719 6709 10 0.14 1 13815 814 0 10
sp_cancel_supplier_order 420 420 0 0.00 6 1220145 101550 0 0
sp_cancel_write_off 10977 10977 0 0.00 1 10352 106 0 0
sp_client_order 21486 21486 0 0.00 3 21591 341 0 0
sp_customer_reserve 482543 482543 0 0.00 0 90889 172 0 0
sp_fill_shopping_cart 32911 32911 0 0.00 1 23605 37 0 0
sp_fill_shopping_cart_clo_res 482535 482535 0 0.00 0 10940 13 0 0
sp_get_clo_for_invoice 5304 5304 0 0.00 0 18007 315 0 0
sp_kill_cost_storno 18096 18096 0 0.00 0 8228 54 0 0
sp_kill_qstorno_ret_qs2qd 1189450 1189450 0 0.00 0 16176 31 0 0
sp_kill_qty_storno 627687 627687 0 0.00 0 19019 67 0 0
sp_lock_selected_doc 22090 22090 0 0.00 0 8636 6 0 0
sp_make_cost_storno 29437 29437 0 0.00 0 17913 53 0 0
sp_multiply_rows_for_pdistr 29437 29437 0 0.00 0 30153 28 0 0
sp_multiply_rows_for_qdistr 21486 21486 0 0.00 0 12275 160 0 0
sp_pay_from_customer 7373 7373 0 0.00 4 10251 115 0 0
sp_pay_to_supplier 6015 6015 0 0.00 4 30163 133 0 0
sp_payment_common 13388 13388 0 0.00 1 9366 30 0 0
sp_qd_handle_on_cancel_clo 1862 1862 0 0.00 0 10041 285 0 0
sp_qd_handle_on_invoice_upd_sts 11301 11301 0 0.00 0 19018 339 0 0
sp_qd_handle_on_reserve_upd_sts 10746 10746 0 0.00 0 9747 54 0 0
sp_reserve_write_off 10746 10746 0 0.00 6 10970 179 0 0
sp_supplier_invoice 5795 5795 0 0.00 6 67396 3226 0 0
sp_supplier_order 5659 5659 0 0.00 5 25792 1160 0 0
srv_aggregate_perf_data 101 101 0 0.00 2601 312820 22181 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 804 143440 46043 0 0
srv_find_qd_qs_mism 1109860 1109860 0 0.00 0 10701 5 0 0
srv_make_invnt_saldo 590 590 0 0.00 32 85167 1163 0 0
srv_make_money_saldo 804 804 0 0.00 1 11261 56 0 0
srv_recalc_idx_stat 4 4 0 0.00 343768 497462 392693 0 0
t$perf-abend:sp_add_invoice_to_stock 102 102 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5312 5312 0 0.00 0 20190 182 0 0
t$perf-norm:sp_cancel_adding_invoice 439 439 0 0.00 0 2282 67 0 0
t$perf-norm:sp_cancel_client_order 1862 1862 0 0.00 0 1086 10 0 0
t$perf-norm:sp_cancel_customer_reserve 712 712 0 0.00 0 657 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 715 715 0 0.00 0 4360 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 406 406 0 0.00 0 2149 9 0 0
t$perf-norm:sp_cancel_supplier_invoice 431 431 0 0.00 0 780 12 0 0
t$perf-norm:sp_cancel_supplier_order 420 420 0 0.00 0 20823 1478 0 0
t$perf-norm:sp_cancel_write_off 1056 1056 0 0.00 0 6803 11 0 0
t$perf-norm:sp_client_order 21486 21486 0 0.00 0 13826 18 0 0
t$perf-norm:sp_customer_reserve 1044 1044 0 0.00 0 752 4 0 0
t$perf-norm:sp_pay_from_customer 7373 7373 0 0.00 0 9492 12 0 0
t$perf-norm:sp_pay_to_supplier 6015 6015 0 0.00 0 8083 9 0 0
t$perf-norm:sp_reserve_write_off 10746 10746 0 0.00 0 18514 15 0 0
t$perf-norm:sp_supplier_invoice 5795 5795 0 0.00 0 17009 25 0 0
t$perf-norm:sp_supplier_order 5659 5659 0 0.00 0 8169 14 0 0
t$perf-norm:srv_aggregate_perf_data 101 101 0 0.00 0 213 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 11 1 0 0
t$perf-norm:srv_make_invnt_saldo 590 590 0 0.00 0 3104 29 0 0
t$perf-norm:srv_make_money_saldo 804 804 0 0.00 0 845 16 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 12 6 0 0
v_add_invoice_to_stock 10810 10606 204 1.88 0 4543 17 0 204
v_all_customers 39566 39566 0 0.00 0 21 1 0 0
v_all_suppliers 31304 31304 0 0.00 0 19 1 0 0
v_all_wares 42972 42972 0 0.00 0 71 1 0 0
v_cancel_adding_invoice 878 878 0 0.00 0 2134 9 0 0
v_cancel_client_order 3724 3724 0 0.00 0 2854 5 0 0
v_cancel_customer_prepayment 1430 1430 0 0.00 0 2332 8 0 0
v_cancel_customer_reserve 1424 1424 0 0.00 0 407 4 0 0
v_cancel_payment_to_supplier 812 812 0 0.00 0 384 3 0 0
v_cancel_supplier_invoice 882 862 20 2.26 0 1776 18 0 20
v_cancel_supplier_order 840 840 0 0.00 0 619 5 0 0
v_cancel_write_off 2112 2112 0 0.00 0 3972 17 0 0
v_max_id_clo_ord 5659 5659 0 0.00 0 893 1 0 0
v_max_id_clo_res 1044 1044 0 0.00 0 2223 16 0 0
v_max_id_ord_sup 5766 5766 0 0.00 0 12329 7 0 0
v_max_non_paid_invoice 1788 1788 0 0.00 0 6705 7 0 0
v_max_non_paid_realizn 3842 3842 0 0.00 0 3073 6 0 0
v_min_id_clo_ord 5659 5659 0 0.00 0 3464 8 0 0
v_min_id_clo_res 1044 1044 0 0.00 0 8265 24 0 0
v_min_id_ord_sup 5766 5766 0 0.00 0 2957 4 0 0
v_min_non_paid_invoice 6015 6015 0 0.00 0 6064 21 0 0
v_min_non_paid_realizn 7373 7373 0 0.00 0 9361 22 0 0
v_our_firm 10468 10468 0 0.00 0 16 1 0 0
v_reserve_write_off 21492 21492 0 0.00 0 6957 6 0 0
x_lock_depdocs_on_canc_invoice 12276 12276 0 0.00 0 28513 121 0 0
x_lock_depdocs_on_canc_sup_ord 420 420 0 0.00 0 3512 334 0 0
x_make_qstorno_1000_1200 5659 5659 0 0.00 1 25786 1077 0 0
x_make_qstorno_1200_2000 5795 5795 0 0.00 3 67385 3086 0 0
x_make_qstorno_2100_3300 482543 482543 0 0.00 0 90888 157 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 04-02-2023 16:43:59 to 04-02-2023 16:44:03.

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:12:45 7 8192000000 8806180800 8819679232 11391872 0.138 3 3 4 0 11575776 76800 107104
14:13:46 22501 8192000000 9903342640 10214899712 984466960 10.728 100 82 164 0 1011062032 5294704 21300368
14:15:11 16243 8192000000 9943125984 10264829952 1030257808 11.171 100 88 176 0 1060628000 7324288 23045904
14:16:34 15473 8192000000 9986106816 10321915904 1072096048 11.572 100 89 178 0 1104119856 9678528 22345280
14:18:08 12636 8192000000 10042360144 10391744512 1122005952 12.046 100 80 158 0 1151756464 11132400 18618112
14:19:25 5382 8192000000 10098839456 10459787264 1181592880 12.605 100 85 169 0 1213571168 13004368 18973920
14:20:32 3377 8192000000 10127688512 10500993024 1219882432 12.961 100 75 151 0 1249581280 13344240 16354608
14:21:38 946 8192000000 10164057184 10539257856 1251192864 13.249 100 89 178 1 1283446912 12549312 19704736
14:22:40 1097 8192000000 10203135520 10582384640 1291004416 13.613 100 92 182 1 1323739712 12530816 20204480
14:23:42 25690 8192000000 10205741712 10602000384 1299159040 13.688 100 58 114 1 1322025536 10010400 12856096
14:25:09 8329 8192000000 10190513936 10604052480 1306252768 13.752 100 26 52 1 1318823776 6320832 6250176
14:26:20 4943 8192000000 10211661696 10625232896 1321586064 13.891 100 25 50 0 1333907392 6834240 5487088
14:27:27 17449 8192000000 10215561232 10637672448 1334058080 14.004 100 37 72 1 1351317296 9603200 7656016
14:29:30 3190 8192000000 10234990608 10650677248 1331519040 13.981 100 40 80 0 1348960688 8455936 8985712
14:30:33 4408 8192000000 10222195744 10643558400 1321514176 13.890 99 31 62 0 1334880080 6367472 6998432
14:31:44 12697 8192000000 10229332016 10646732800 1324070928 13.914 99 22 44 1 1334546208 6001616 4473664
14:33:02 27642 8192000000 10203174928 10615787520 1276879552 13.485 96 29 57 0 1288446144 5831104 5735488
14:34:32 5969 8192000000 10153462208 10554322944 1210253792 12.871 95 16 30 0 1218790064 5342128 3194144
14:35:46 7472 8192000000 10147656320 10549092352 1244177008 13.185 99 19 36 0 1253931744 5743344 4011392
14:36:55 45123 8192000000 10139831104 10537205760 1211416576 12.882 96 24 44 2 1220808080 4639968 4751536
14:38:49 3630 8192000000 10158155584 10562351104 1264743744 13.373 99 39 79 1 1279253536 5365792 9144000
14:39:53 4738 8192000000 10196578608 10601361408 1294137856 13.642 100 33 62 0 1307872704 6951072 6783776
14:41:00 4973 8192000000 10205738352 10613657600 1306681936 13.756 100 32 64 0 1319969232 5523792 7763504
14:42:05 74102 8192000000 10215973504 10630397952 1321234544 13.888 100 33 66 0 1333595120 4578160 7782416
14:44:29 34094 8192000000 10256264928 10671063040 1348958304 14.138 100 50 98 1 1369894384 10028112 10907968
14:46:12 31093 8192000000 10280313888 10705870848 1375092144 14.373 100 34 67 0 1393538400 10474960 7971296
14:47:55 10527 8192000000 10293884496 10715131904 1386668064 14.476 100 52 102 0 1409914224 11943696 11302464
14:49:10 7318 8192000000 10294729344 10719506432 1388094848 14.489 100 55 110 0 1413095408 11762528 13238032
14:50:20 22292 8192000000 10325461728 10740785152 1392889936 14.532 100 69 138 2 1430357600 21977392 15490272
14:52:03 1831 8192000000 10333550240 10754289664 1403046128 14.622 100 69 135 1 1438152496 19266896 15839472
14:53:09 11738 8192000000 10348776240 10777538560 1418565504 14.760 100 54 108 0 1454785072 23236896 12982672
14:54:24 3433 8192000000 10359438720 10777014272 1416666464 14.743 100 75 148 0 1461554576 27488944 17399168
14:55:32 4289 8192000000 10348004368 10766123008 1407485504 14.662 100 71 140 0 1456880864 31714960 17680400
14:56:42 13269 8192000000 10353881840 10780102656 1405575888 14.645 99 49 97 0 1452899136 34882352 12440896
14:58:11 913 8192000000 10340247616 10749759488 1374678160 14.369 97 76 149 1 1431341152 37667696 18995296
14:59:19 1400 8192000000 10326681552 10728636416 1360552704 14.242 98 86 169 1 1418908992 38066272 20290016
15:00:25 1942 8192000000 10356545424 10762940416 1392397424 14.527 100 88 174 0 1457943552 43507792 22038336
15:01:34 794 8192000000 10352149088 10758332416 1387702048 14.485 100 79 149 1 1450333392 43904464 18726880
15:02:38 1391 8192000000 10346461152 10752851968 1377153904 14.391 100 86 174 1 1450452640 51510944 21787792
15:03:47 11236 8192000000 10353048096 10777956352 1402747904 14.619 100 36 72 1 1460157424 47759856 9649664
15:05:04 1698 8192000000 10362133504 10775388160 1390608016 14.511 100 64 128 0 1463729168 56560944 16560208
15:06:10 7088 8192000000 10336159792 10751139840 1369333216 14.321 100 49 95 0 1440834656 58376816 13124624
15:07:26 19569 8192000000 10344059712 10771918848 1376349536 14.384 100 38 74 2 1444686416 56909648 11427232
15:08:47 23272 8192000000 10315262400 10745810944 1357707536 14.217 100 25 50 0 1432852576 67417232 7727808
15:10:15 9314 8192000000 10322602848 10760105984 1358623376 14.225 100 43 86 0 1438873712 67986800 12263536
15:11:26 54980 8192000000 10261599376 10708045824 1332625312 13.991 98 23 45 1 1354240176 16117904 5496960
15:13:24 95596 8192000000 10289834048 10736144384 1346890608 14.119 98 12 21 0 1360079728 11481840 1707280
15:16:02 23274 8192000000 10299022096 10733826048 1380372992 14.420 100 41 81 1 1397121392 6801520 9946880
15:17:30 82938 8192000000 10298591408 10758909952 1375754944 14.379 99 11 22 0 1403029808 24850160 2424704
15:20:32 135115 8192000000 10328682496 10773368832 1368274976 14.312 98 12 24 0 1392023136 20906224 2841936
15:23:48 127949 8192000000 10305349552 10753097728 1340315616 14.060 96 9 18 0 1353566320 11564032 1686672
15:26:56 50198 8192000000 10319471008 10760179712 1367832416 14.308 98 28 54 0 1382679440 9266032 5580992
15:28:49 82182 8192000000 10322724976 10771148800 1400998688 14.604 100 21 42 1 1421207008 15596768 4611552
15:31:12 23032 8192000000 10323214688 10761662464 1390033888 14.506 99 47 89 1 1413168032 13464832 9669312
15:32:43 45850 8192000000 10309786480 10774790144 1382272976 14.437 99 15 30 0 1404660560 18860496 3527088
15:35:37 13122 8192000000 10309613568 10764963840 1403578528 14.627 100 22 44 0 1418201088 9509872 5112688
15:36:55 33462 8192000000 10315420944 10758864896 1372846352 14.353 98 39 78 0 1394779632 12629120 9304160
15:38:31 25669 8192000000 10279867680 10741424128 1365905488 14.290 99 22 43 0 1385050336 14117632 5027216
15:39:59 79015 8192000000 10279512288 10730147840 1332053792 13.986 95 10 20 0 1344601680 10173200 2374688
15:42:19 60550 8192000000 10244342384 10693980160 1271371984 13.434 92 13 26 0 1281510800 7666048 2472768
15:44:22 78662 8192000000 10245003968 10702323712 1311465216 13.799 96 34 67 1 1326044624 8079792 6499616
15:46:44 38182 8192000000 10256176064 10715435008 1343978144 14.093 99 12 24 0 1352612240 6154432 2479664
15:48:27 32853 8192000000 10277551888 10728808448 1348901488 14.138 99 23 46 1 1366793808 12566832 5325488
15:50:00 53036 8192000000 10298829600 10758062080 1365276640 14.285 99 25 50 0 1386933728 15458608 6198480
15:52:08 75611 8192000000 10281499648 10727137280 1325628464 13.928 96 18 36 0 1350512384 20216496 4667424
15:54:29 45728 8192000000 10268772080 10717057024 1327909216 13.948 97 21 42 0 1344378528 11712816 4756496
15:56:15 82843 8192000000 10278472768 10717720576 1311064608 13.796 96 28 56 0 1330933360 13948592 5920160
15:58:38 57454 8192000000 10243173952 10688016384 1304647152 13.737 96 14 28 0 1321809264 14160704 3001408
16:00:38 143440 8192000000 10262963088 10712514560 1355728240 14.199 99 15 28 1 1372990768 14233872 3028656
16:04:03 56269 8192000000 10271638400 10732089344 1358966224 14.228 99 34 68 1 1377403904 10992400 7445280
16:06:23 86925 8192000000 10293361840 10767929344 1351276224 14.159 97 21 42 0 1373261136 17560496 4424416
16:08:50 33884 8192000000 10291116016 10744659968 1375344096 14.375 99 39 78 0 1396339152 11515248 9479808
16:10:30 66555 8192000000 10287373056 10754764800 1385204144 14.463 100 10 20 0 1403644928 16106928 2333856
16:12:36 55909 8192000000 10272292336 10730590208 1342962512 14.084 97 21 42 1 1354281536 6565056 4753968
16:14:33 74599 8192000000 10278119264 10751688704 1370361952 14.330 99 11 22 0 1382095824 9421952 2311920
16:16:47 45565 8192000000 10295885504 10761064448 1370778272 14.334 98 16 32 0 1384376816 9792832 3805712
16:18:33 46545 8192000000 10280578864 10738794496 1331907664 13.984 96 30 58 1 1351167280 12501888 6757728
16:20:24 42290 8192000000 10276967408 10727772160 1346656832 14.117 98 32 64 0 1368603488 14815872 7130784
16:22:07 59463 8192000000 10286834288 10756116480 1356924752 14.210 98 15 30 0 1378195616 18071648 3199216
16:24:10 51799 8192000000 10292246336 10755137536 1372744176 14.352 99 28 56 0 1393493120 14508064 6240880
16:26:03 103393 8192000000 10295783680 10730545152 1286078064 13.568 93 28 55 0 1301566032 9269488 6218480
16:28:48 113899 8192000000 10284120000 10756825088 1353286496 14.177 98 7 14 0 1372161248 17433840 1440912
16:32:07 24739 8192000000 10274945488 10731745280 1369525552 14.323 100 21 42 1 1384761696 10695264 4540880
16:33:34 58505 8192000000 10260616656 10724368384 1362101968 14.256 99 7 14 0 1371275264 7852928 1320368
16:35:43 74602 8192000000 10264100416 10718507008 1330027568 13.967 97 9 18 0 1343199824 11180624 1991632
16:37:59 51332 8192000000 10257755392 10714804224 1327754800 13.947 97 6 12 0 1341845680 12384672 1706208
16:39:51 75427 8192000000 10272877280 10733203456 1336697216 14.028 98 20 40 0 1357261584 16260576 4303792
16:42:10 37590 8192000000 9286205120 9578749952 437888656 5.074 32 5 10 0 447150480 8339376 922448

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 04-02-2023 16:44:05 to 04-02-2023 16:44:20.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 102 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 04-02-2023 16:44:21 to 04-02-2023 16:44:23.

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          247287
MON$OLDEST_ACTIVE               248252
MON$OLDEST_SNAPSHOT             248252
MON$NEXT_TRANSACTION            248252
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                       2060064
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.33659 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33659 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33659 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33659 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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Sat Feb  4 16:42:49 2023
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Sat Feb  4 16:42:54 2023
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:13: 
:14: 
:15: mscs-test-161.budzdorov.ru    Sat Feb  4 16:42:57 2023
:16:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:17: 
--- end of diff output ---

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

04.02.23 16:44:24. 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-04 16:44:27 No severe PSQL-related problems occured
04.02.23 16:44:28. End of report.