Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 13.03.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 15155916 kB
MemAvailable 30405280 kB
Buffers 0 kB
Cached 16527244 kB
SwapCached 3168 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-03-13 16:38:45
DTS_END                         2023-03-13 16:38:45.6630
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_MAKE_INVNT_SALDO(40:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/59428
TRN_ID                          251282
ATT_ID                          660
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: 9540.86 19200 1144903
customer order: creation 184.99 348 22199
customer order: refuse 15.27 340 1832
order to supplier: creation 49.54 1267 5945
order to supplier: removal 3.58 88634 429
invoice (draft): creation 48.74 3316 5849
invoice (draft): removal 53.55 705 6426
invoice accept: apply 44.18 17816 5301
invoice accept: cancel 48.56 797 5827
customer reserve: creation 4031.35 179 483762
customer reserve: removal 4747.21 53 569665
realization accept: apply 90.58 172 10869
realization accept: cancel 85.89 114 10307
payment from customer: creation 63.78 118 7653
payment from customer: removal 5.68 79 682
payment to supplier: creation 51.83 130 6219
payment to supplier: removal 3.67 69 440
service: total inventory turnovers 4.55 1138 546
service: total monetary turnovers 7.01 29 841
service: aggregate perf. data 0.89 20239 107
service: refresh index statistics 0.03 248453 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 13-03-2023 16:39:53 to 13-03-2023 16:39:55.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5944
TEST_TIME 2 5053
TEST_TIME 3 5889
TEST_TIME 4 6057
TEST_TIME 5 5581
TEST_TIME 6 5973
TEST_TIME 7 6143
TEST_TIME 8 5957
TEST_TIME 9 5889
TEST_TIME 10 5846
TEST_TIME 11 5843
TEST_TIME 12 5925
TEST_TIME 13 6132
TEST_TIME 14 6152
TEST_TIME 15 6144
TEST_TIME 16 6463
TEST_TIME 17 6394
TEST_TIME 18 6929
TEST_TIME 19 7179
TEST_TIME 20 7444
TEST_TIME 21 7682
TEST_TIME 22 7735
TEST_TIME 23 8218
TEST_TIME 24 8450
TEST_TIME 25 8780
TEST_TIME 26 8900
TEST_TIME 27 9330
TEST_TIME 28 9402
TEST_TIME 29 9422
TEST_TIME 30 9357
TEST_TIME 31 9736
TEST_TIME 32 9766
TEST_TIME 33 10527
TEST_TIME 34 10296
TEST_TIME 35 10275
TEST_TIME 36 10453
TEST_TIME 37 10390
TEST_TIME 38 10312
TEST_TIME 39 10277
TEST_TIME 40 10132
TEST_TIME 41 9901
TEST_TIME 42 9887
TEST_TIME 43 9889
TEST_TIME 44 9848
TEST_TIME 45 9856
TEST_TIME 46 9792
TEST_TIME 47 9920
TEST_TIME 48 9910
TEST_TIME 49 9909
TEST_TIME 50 9833
TEST_TIME 51 9785
TEST_TIME 52 9652
TEST_TIME 53 9584
TEST_TIME 54 9583
TEST_TIME 55 9514
TEST_TIME 56 9527
TEST_TIME 57 9577
TEST_TIME 58 9528
TEST_TIME 59 9534
TEST_TIME 60 9659
TEST_TIME 61 9640
TEST_TIME 62 9752
TEST_TIME 63 9736
TEST_TIME 64 9799
TEST_TIME 65 9794
TEST_TIME 66 9836
TEST_TIME 67 9909
TEST_TIME 68 9938
TEST_TIME 69 9938
TEST_TIME 70 10070
TEST_TIME 71 10062
TEST_TIME 72 9974
TEST_TIME 73 9886
TEST_TIME 74 9879
TEST_TIME 75 9861
TEST_TIME 76 9797
TEST_TIME 77 9867
TEST_TIME 78 9810
TEST_TIME 79 9758
TEST_TIME 80 9766
TEST_TIME 81 9734
TEST_TIME 82 9648
TEST_TIME 83 9598
TEST_TIME 84 9531
TEST_TIME 85 9528
TEST_TIME 86 9578
TEST_TIME 87 9538
TEST_TIME 88 9554
TEST_TIME 89 9591
TEST_TIME 90 9545
TEST_TIME 91 9615
TEST_TIME 92 9639
TEST_TIME 93 9668
TEST_TIME 94 9678
TEST_TIME 95 9655
TEST_TIME 96 9697
TEST_TIME 97 9767
TEST_TIME 98 9755
TEST_TIME 99 9791
TEST_TIME 100 9834
TEST_TIME 101 9769
TEST_TIME 102 9714
TEST_TIME 103 9672
TEST_TIME 104 9618
TEST_TIME 105 9651
TEST_TIME 106 9604
TEST_TIME 107 9607
TEST_TIME 108 9590
TEST_TIME 109 9542
TEST_TIME 110 9500
TEST_TIME 111 9473
TEST_TIME 112 9496
TEST_TIME 113 9471
TEST_TIME 114 9547
TEST_TIME 115 9522
TEST_TIME 116 9525
TEST_TIME 117 9518
TEST_TIME 118 9543
TEST_TIME 119 9535
TEST_TIME 120 9545

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 13-03-2023 16:39:56 to 13-03-2023 16:40:00.

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 1594626 1594626 0 0.00 0 30932 6 0 0
doc_list_biud 1594619 1594619 0 0.00 0 75094 24 0 0
sp_add_invoice_to_stock 5487 5301 186 3.38 35 181575 17816 0 186
sp_cancel_adding_invoice 5827 5827 0 0.00 4 59497 798 0 0
sp_cancel_client_order 1832 1832 0 0.00 4 15770 341 0 0
sp_cancel_customer_reserve 569665 569665 0 0.00 0 72538 53 0 0
sp_cancel_pay_from_customer 682 682 0 0.00 3 5678 80 0 0
sp_cancel_pay_to_supplier 440 440 0 0.00 3 3498 70 0 0
sp_cancel_supplier_invoice 6443 6426 17 0.26 2 75104 705 0 17
sp_cancel_supplier_order 429 429 0 0.00 5 1119333 88634 0 0
sp_cancel_write_off 10307 10307 0 0.00 1 29342 115 0 0
sp_client_order 22199 22199 0 0.00 3 22742 348 0 0
sp_customer_reserve 483762 483762 0 0.00 0 60008 180 0 0
sp_fill_shopping_cart 33989 33989 0 0.00 0 9639 34 0 0
sp_fill_shopping_cart_clo_res 483754 483754 0 0.00 0 17892 14 0 0
sp_get_clo_for_invoice 5293 5293 0 0.00 0 25209 264 0 0
sp_kill_cost_storno 17256 17256 0 0.00 0 9919 50 0 0
sp_kill_qstorno_ret_qs2qd 1156492 1156492 0 0.00 0 75087 29 0 0
sp_kill_qty_storno 610647 610647 0 0.00 0 75091 63 0 0
sp_lock_selected_doc 22214 22214 0 0.00 0 8642 6 0 0
sp_make_cost_storno 30034 30034 0 0.00 0 14074 55 0 0
sp_multiply_rows_for_pdistr 30034 30034 0 0.00 0 8582 27 0 0
sp_multiply_rows_for_qdistr 22199 22199 0 0.00 0 11919 139 0 0
sp_pay_from_customer 7653 7653 0 0.00 4 14107 119 0 0
sp_pay_to_supplier 6219 6219 0 0.00 4 8889 130 0 0
sp_payment_common 13872 13872 0 0.00 1 8328 30 0 0
sp_qd_handle_on_cancel_clo 1832 1832 0 0.00 0 15758 309 0 0
sp_qd_handle_on_invoice_upd_sts 11119 11119 0 0.00 0 21519 310 0 0
sp_qd_handle_on_reserve_upd_sts 10869 10869 0 0.00 0 16734 52 0 0
sp_reserve_write_off 10869 10869 0 0.00 6 16752 173 0 0
sp_supplier_invoice 5849 5849 0 0.00 7 94514 3316 0 0
sp_supplier_order 5945 5945 0 0.00 5 31920 1268 0 0
srv_aggregate_perf_data 107 107 0 0.00 2627 543249 20240 0 0
srv_fill_mon_cache_memory 68 68 0 0.00 1323 151710 37423 0 0
srv_find_qd_qs_mism 1095143 1095143 0 0.00 0 30931 6 0 0
srv_make_invnt_saldo 546 546 0 0.00 60 83178 1138 0 0
srv_make_money_saldo 841 841 0 0.00 0 2129 29 0 0
srv_recalc_idx_stat 4 4 0 0.00 102695 383861 248454 0 0
t$perf-abend:sp_add_invoice_to_stock 186 186 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 17 17 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5301 5301 0 0.00 0 15084 164 0 0
t$perf-norm:sp_cancel_adding_invoice 450 450 0 0.00 0 3077 98 0 0
t$perf-norm:sp_cancel_client_order 1832 1832 0 0.00 0 3283 12 0 0
t$perf-norm:sp_cancel_customer_reserve 754 754 0 0.00 0 932 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 682 682 0 0.00 0 777 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 440 440 0 0.00 0 3604 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 423 423 0 0.00 0 23853 80 0 0
t$perf-norm:sp_cancel_supplier_order 429 429 0 0.00 0 21634 1260 0 0
t$perf-norm:sp_cancel_write_off 1044 1044 0 0.00 0 5224 12 0 0
t$perf-norm:sp_client_order 22199 22199 0 0.00 0 23645 16 0 0
t$perf-norm:sp_customer_reserve 1045 1045 0 0.00 0 2140 7 0 0
t$perf-norm:sp_pay_from_customer 7653 7653 0 0.00 0 18921 12 0 0
t$perf-norm:sp_pay_to_supplier 6219 6219 0 0.00 0 6497 10 0 0
t$perf-norm:sp_reserve_write_off 10869 10869 0 0.00 0 20029 15 0 0
t$perf-norm:sp_supplier_invoice 5849 5849 0 0.00 0 4037 13 0 0
t$perf-norm:sp_supplier_order 5945 5945 0 0.00 0 23629 19 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 1677 19 0 0
t$perf-norm:srv_fill_mon_cache_memory 68 68 0 0.00 0 530 9 0 0
t$perf-norm:srv_make_invnt_saldo 546 546 0 0.00 0 1285 23 0 0
t$perf-norm:srv_make_money_saldo 841 841 0 0.00 0 3089 20 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 3 3 0 0
v_add_invoice_to_stock 10954 10582 372 3.39 0 4050 19 0 372
v_all_customers 40190 40190 0 0.00 0 20 1 0 0
v_all_suppliers 32582 32582 0 0.00 0 37 1 0 0
v_all_wares 44394 44394 0 0.00 0 36 1 0 0
v_cancel_adding_invoice 900 900 0 0.00 0 1558 9 0 0
v_cancel_client_order 3664 3664 0 0.00 0 3375 7 0 0
v_cancel_customer_prepayment 1364 1364 0 0.00 0 3484 5 0 0
v_cancel_customer_reserve 1508 1508 0 0.00 0 1645 5 0 0
v_cancel_payment_to_supplier 880 880 0 0.00 0 1220 5 0 0
v_cancel_supplier_invoice 880 846 34 3.86 0 3282 24 0 34
v_cancel_supplier_order 858 858 0 0.00 0 3486 12 0 0
v_cancel_write_off 2088 2088 0 0.00 0 3556 19 0 0
v_max_id_clo_ord 5945 5945 0 0.00 0 730 2 0 0
v_max_id_clo_res 1045 1045 0 0.00 0 4030 22 0 0
v_max_id_ord_sup 5847 5847 0 0.00 0 6402 5 0 0
v_max_non_paid_invoice 1720 1720 0 0.00 0 2855 4 0 0
v_max_non_paid_realizn 4052 4052 0 0.00 0 2140 5 0 0
v_min_id_clo_ord 5945 5945 0 0.00 0 2855 7 0 0
v_min_id_clo_res 1045 1045 0 0.00 0 3663 10 0 0
v_min_id_ord_sup 5847 5847 0 0.00 0 4840 11 0 0
v_min_non_paid_invoice 6219 6219 0 0.00 0 6153 19 0 0
v_min_non_paid_realizn 7653 7653 0 0.00 0 6335 24 0 0
v_our_firm 11406 11406 0 0.00 0 17 1 0 0
v_reserve_write_off 21738 21738 0 0.00 0 5120 9 0 0
x_lock_depdocs_on_canc_invoice 11830 11830 0 0.00 0 17801 104 0 0
x_lock_depdocs_on_canc_sup_ord 429 429 0 0.00 0 5123 306 0 0
x_make_qstorno_1000_1200 5945 5945 0 0.00 1 31912 1192 0 0
x_make_qstorno_1200_2000 5849 5849 0 0.00 3 94453 3196 0 0
x_make_qstorno_2100_3300 483762 483762 0 0.00 0 60006 163 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 13-03-2023 16:40:01 to 13-03-2023 16:40:05.

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:49 34 8192000000 8811325680 8827146240 13634256 0.166 4 4 4 0 13822768 81392 107120
14:09:50 28137 8192000000 9901764496 10208993280 978982944 10.674 100 87 174 0 1008506112 6127296 23395872
14:11:25 7591 8192000000 9944337824 10263240704 1025551808 11.126 100 90 180 0 1056645872 8177632 22916432
14:12:40 17238 8192000000 9970554544 10302873600 1058851056 11.445 100 86 172 0 1089654448 9228560 21574832
14:14:06 17471 8192000000 10044426656 10390323200 1123791792 12.063 100 82 164 0 1153623104 10410400 19420912
14:15:28 7413 8192000000 10077784544 10433441792 1165134960 12.451 100 79 158 0 1196681856 13589056 17957840
14:16:38 3381 8192000000 10119366432 10483064832 1203567744 12.809 100 82 162 1 1235480800 13432416 18480640
14:17:44 1907 8192000000 10144803840 10518507520 1232810160 13.080 100 78 156 1 1263604368 13757056 17037152
14:18:48 4610 8192000000 10174273616 10551197696 1260995248 13.339 100 84 166 0 1292093136 13103904 17993984
14:19:54 1144 8192000000 10208509152 10591629312 1295214608 13.652 100 85 170 1 1326936368 13082400 18639360
14:20:56 13231 8192000000 10202388192 10603302912 1299357056 13.689 100 59 118 1 1321041344 7873136 13811152
14:22:10 5658 8192000000 10195460896 10606764032 1306323776 13.753 100 25 51 0 1318642512 6595552 5723184
14:23:21 12557 8192000000 10206809680 10624712704 1319415504 13.871 100 22 44 0 1330807280 5999072 5392704
14:24:36 11225 8192000000 10222678608 10639532032 1330392656 13.971 100 17 34 1 1339869072 5844400 3632016
14:25:51 54995 8192000000 10222194176 10651398144 1339831616 14.056 100 34 61 1 1352777424 5287088 7658720
14:27:49 2687 8192000000 10230636336 10648821760 1333604272 14.000 100 32 64 0 1348220992 7252304 7364416
14:28:57 6767 8192000000 10202454032 10611847168 1285863680 13.567 98 18 37 0 1294940016 5530464 3545872
14:30:05 60631 8192000000 10168014464 10568929280 1209685648 12.866 93 50 98 1 1226026688 4995888 11345152
14:32:09 4186 8192000000 10148868128 10544496640 1235313040 13.103 99 30 60 0 1248973648 7411776 6248832
14:33:15 5789 8192000000 10144958464 10548916224 1255675632 13.290 100 28 58 0 1268351920 5934832 6741456
14:34:21 9694 8192000000 10172104512 10577072128 1273934688 13.458 100 41 82 1 1294338464 11136720 9267056
14:35:36 21470 8192000000 10170128800 10579783680 1276895888 13.485 100 38 75 1 1291742928 5457744 9389296
14:37:02 6626 8192000000 10188341056 10602168320 1294229760 13.643 100 29 54 0 1311166928 10390896 6546272
14:38:10 11494 8192000000 10209680160 10627588096 1320045728 13.877 100 12 24 0 1327206512 4640592 2520192
14:39:24 11671 8192000000 10242866944 10657239040 1340394992 14.061 100 41 80 0 1355647312 6690000 8562320
14:40:46 62800 8192000000 10247417520 10678972416 1355350208 14.196 100 31 62 1 1372124832 9246992 7527632
14:42:55 2708 8192000000 10265894128 10688557056 1357494672 14.215 99 56 112 0 1380033568 9259360 13279536
14:44:00 12154 8192000000 10283674800 10707124224 1377445648 14.394 100 49 96 0 1402266384 13834288 10986448
14:45:30 11941 8192000000 10303017168 10728382464 1390308784 14.509 100 43 86 0 1414245808 13489280 10447744
14:46:58 14016 8192000000 10321072672 10744528896 1390532688 14.511 99 43 85 2 1420931024 20282960 10115376
14:48:19 18240 8192000000 10310304464 10740449280 1397638400 14.574 99 39 78 0 1428052304 21055680 9358224
14:49:44 7729 8192000000 10321525184 10748485632 1389710720 14.503 99 47 92 0 1425501968 25136592 10654656
14:50:56 8334 8192000000 10320817664 10739429376 1390298032 14.509 100 54 108 0 1433224704 30420688 12505984
14:52:10 4430 8192000000 10328994640 10741170176 1379424272 14.411 99 71 142 0 1424080992 28465232 16191488
14:53:18 1634 8192000000 10336774848 10743812096 1384707968 14.459 100 85 170 4 1440444800 35374112 20362720
14:54:27 6316 8192000000 10335207808 10759348224 1385850960 14.469 99 55 111 0 1435136912 35172032 14113920
14:55:41 1322 8192000000 10336291328 10747207680 1375992416 14.381 99 74 148 1 1433058496 38985616 18080464
14:56:47 1401 8192000000 10332628368 10743050240 1376252736 14.383 100 67 132 2 1435234672 41634000 17347936
14:57:53 4099 8192000000 10313851008 10721574912 1360550368 14.242 99 73 141 0 1411639408 32577008 18512032
14:59:05 13982 8192000000 10299742080 10724962304 1360207696 14.239 99 37 74 0 1402424656 32396464 9820496
15:00:26 9017 8192000000 10286957392 10703503360 1326219952 13.933 98 44 87 1 1373561472 35511888 11829632
15:01:39 7157 8192000000 10302850976 10729586688 1357198624 14.212 99 45 90 1 1400691744 32385552 11107568
15:02:50 7136 8192000000 10319436240 10745778176 1373768976 14.361 100 44 88 3 1424059472 38306336 11984160
15:04:00 15805 8192000000 10306831296 10731139072 1356577968 14.207 99 34 68 1 1401605376 35792496 9234912
15:05:20 8506 8192000000 10331457024 10770268160 1389463632 14.501 100 39 78 0 1438933792 39250384 10219776
15:06:53 10308 8192000000 10323677856 10754695168 1381803056 14.433 99 53 103 1 1424099456 29772688 12523712
15:08:50 13944 8192000000 10324283296 10767319040 1376617312 14.386 100 21 42 0 1425965584 43947456 5400816
15:10:05 38489 8192000000 10308757168 10759225344 1355082624 14.193 99 15 30 0 1407993904 48486944 4424336
15:11:47 61178 8192000000 10291274720 10723844096 1348461856 14.134 98 22 44 0 1364046096 10432112 5152128
15:13:53 63811 8192000000 10293637424 10744225792 1353974640 14.183 98 13 26 0 1374717296 18289616 2453040
15:16:24 60012 8192000000 10304223264 10749726720 1379074048 14.408 99 26 53 0 1396414368 12058784 5281536
15:18:36 151710 8192000000 10327395776 10784133120 1399133520 14.587 100 32 60 0 1421532592 14548768 7850304
15:22:11 36094 8192000000 10307877344 10769461248 1391177152 14.516 99 16 32 1 1409338592 14408336 3753104
15:23:48 43570 8192000000 10315840944 10770522112 1380310160 14.419 98 21 43 0 1399639136 14654704 4674272
15:25:41 25839 8192000000 10332614240 10781351936 1405330096 14.642 100 54 108 0 1432882480 15706080 11846304
15:27:08 29744 8192000000 10306214768 10770927616 1400301440 14.598 100 11 23 0 1413723952 11051840 2370672
15:28:39 34560 8192000000 10313955264 10773340160 1409875984 14.683 100 18 36 0 1420438080 6645968 3916128
15:30:16 33265 8192000000 10305518560 10774069248 1411729136 14.699 100 10 21 0 1422254336 8406624 2118576
15:32:14 15369 8192000000 10303335872 10765537280 1398471952 14.581 99 29 54 2 1413189264 8679424 6037888
15:33:32 67693 8192000000 10302273504 10778996736 1394804656 14.549 99 7 14 0 1405835792 9396864 1634272
15:35:42 54941 8192000000 10292376208 10761609216 1346246016 14.114 96 14 26 0 1357391840 8182704 2963120
15:37:38 57004 8192000000 10297411024 10758287360 1355348624 14.196 97 11 18 0 1372104480 14631072 2124784
15:39:41 34312 8192000000 10300662352 10753732608 1376858656 14.388 99 25 48 0 1392363024 9963920 5540448
15:41:16 46268 8192000000 10282411040 10733846528 1335547792 14.017 96 12 24 0 1347981072 9946736 2486544
15:43:07 34309 8192000000 10275357760 10700046336 1295366784 13.653 95 27 54 0 1309112224 8105536 5639904
15:44:45 42216 8192000000 10285081264 10749149184 1363750560 14.271 100 24 48 0 1388837984 19384080 5703344
15:46:46 100008 8192000000 10262742992 10718314496 1309810256 13.784 96 9 18 0 1327505184 15707888 1987040
15:49:26 50272 8192000000 10252249840 10703159296 1331577552 13.981 98 26 52 1 1349355168 11791360 5986256
15:51:53 62117 8192000000 10278950992 10725601280 1345312896 14.105 98 39 78 0 1366781824 12972224 8496704
15:53:58 17982 8192000000 10267604736 10733219840 1355705168 14.199 99 10 21 0 1371031056 13054752 2271136
15:55:16 42577 8192000000 10260351504 10724536320 1362359264 14.259 100 22 41 0 1376435040 9519328 4556448
15:57:03 52295 8192000000 10269808768 10711175168 1331616400 13.982 97 21 42 0 1344768352 8435424 4716528
15:58:57 43342 8192000000 10271383552 10726019072 1368191984 14.311 100 20 39 0 1379781808 7127072 4462752
16:00:40 68243 8192000000 10265524352 10725179392 1365163440 14.284 99 20 40 0 1377849936 8400912 4285584
16:02:57 69412 8192000000 10265548896 10737246208 1322686784 13.901 96 6 12 0 1337823296 13870528 1265984
16:05:07 20971 8192000000 10289366960 10741587968 1385257168 14.464 100 32 63 1 1398906320 6640720 7008432
16:06:33 38208 8192000000 10293638192 10757132288 1387649360 14.485 100 26 52 0 1408026784 14428704 5948720
16:08:12 25553 8192000000 10296349024 10746294272 1372480480 14.349 99 34 65 0 1390222432 10866256 6875696
16:10:01 45897 8192000000 10313135728 10764726272 1397934336 14.577 100 17 34 0 1410910912 9588208 3388368
16:12:06 46058 8192000000 10296484320 10751483904 1377834848 14.397 98 21 42 0 1391471152 9085472 4550832
16:13:54 21079 8192000000 10322370144 10767982592 1396194672 14.561 99 34 68 0 1414541856 11203440 7143744
16:15:16 140431 8192000000 10299202128 10759163904 1305080960 13.741 93 8 16 0 1321196496 14676336 1439200
16:18:37 59673 8192000000 10295814352 10742087680 1339222176 14.050 96 32 58 2 1354177728 7744960 7210592
16:21:00 58054 8192000000 10273941008 10735017984 1362480672 14.260 99 19 38 0 1374996704 8010096 4505936
16:23:00 34096 8192000000 10265740224 10724712448 1344476832 14.098 98 26 52 1 1360585856 10697664 5411360
16:24:39 54169 8192000000 10251910144 10715824128 1341315088 14.069 98 8 17 0 1350692368 7579136 1798144
16:26:37 55404 8192000000 10237113392 10691219456 1288999968 13.595 95 23 46 1 1303717952 10066304 4651680
16:29:12 59651 8192000000 10239281792 10705756160 1325049088 13.922 98 10 20 0 1338953968 11711744 2193136
16:31:14 56275 8192000000 10245839632 10699276288 1315977680 13.840 97 16 32 0 1332560064 12514992 4067392
16:33:11 51462 8192000000 10256734192 10705752064 1309193520 13.779 97 7 14 0 1318121488 7546672 1381296
16:35:30 47812 8192000000 10244774720 10694721536 1310382384 13.790 98 30 58 0 1329957168 13234768 6340016
16:37:36 38674 8192000000 10275791168 10714484736 1324154320 13.914 98 42 84 0 1345830304 12597536 9078448

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 13-03-2023 16:40:07 to 13-03-2023 16:40:22.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 186 v_add_invoice_to_stock 335544517
except 17 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 13-03-2023 16:40:23 to 13-03-2023 16:40:25.

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          247839
MON$OLDEST_ACTIVE               251396
MON$OLDEST_SNAPSHOT             251396
MON$NEXT_TRANSACTION            251396
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                       2039968
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.33666 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33666 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33666 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33666 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 ---
:134: 
:135: mscs-test-161.budzdorov.ru    Mon Mar 13 16:39:03 2023
:136:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:137: 
:138: 
:139: mscs-test-161.budzdorov.ru    Mon Mar 13 16:39:03 2023
:140:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:141: 
:142: 
:143: mscs-test-161.budzdorov.ru    Mon Mar 13 16:39:03 2023
:144:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:145: 
:146: 
:147: mscs-test-161.budzdorov.ru    Mon Mar 13 16:39:06 2023
:148:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:149: 
:150: 
:151: mscs-test-161.budzdorov.ru    Mon Mar 13 16:39:06 2023
:152:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:153: 
--- end of diff output ---

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

13.03.23 16:40:26. 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-03-13 16:40:29 No severe PSQL-related problems occured
13.03.23 16:40:30. End of report.