Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 13.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 1320.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 15291904 kB
MemAvailable 30404288 kB
Buffers 0 kB
Cached 16405216 kB
SwapCached 3376 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-13 16:38:40
DTS_END                         2023-02-13 16:38:40.9720
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/52606
TRN_ID                          243129
ATT_ID                          721
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: 9339.37 18388 1120724
customer order: creation 177.19 351 21263
customer order: refuse 15.21 334 1825
order to supplier: creation 46.67 1185 5600
order to supplier: removal 3.65 89183 438
invoice (draft): creation 46.88 3270 5625
invoice (draft): removal 52.04 669 6245
invoice accept: apply 43.05 17815 5166
invoice accept: cancel 47.12 778 5654
customer reserve: creation 3984.53 177 478143
customer reserve: removal 4627.43 56 555292
realization accept: apply 86.66 158 10399
realization accept: cancel 77.78 106 9333
payment from customer: creation 60.06 103 7207
payment from customer: removal 5.80 52 696
payment to supplier: creation 49.44 121 5933
payment to supplier: removal 3.47 56 416
service: total inventory turnovers 5.21 997 625
service: total monetary turnovers 6.38 29 765
service: aggregate perf. data 0.79 26233 95
service: refresh index statistics 0.03 226082 4

Performance in TOTAL, chart


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

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3471
TEST_TIME 2 5044
TEST_TIME 3 5224
TEST_TIME 4 5384
TEST_TIME 5 5573
TEST_TIME 6 5506
TEST_TIME 7 5693
TEST_TIME 8 5705
TEST_TIME 9 5841
TEST_TIME 10 5733
TEST_TIME 11 5710
TEST_TIME 12 5774
TEST_TIME 13 5972
TEST_TIME 14 6214
TEST_TIME 15 6793
TEST_TIME 16 7603
TEST_TIME 17 7702
TEST_TIME 18 7831
TEST_TIME 19 7912
TEST_TIME 20 8359
TEST_TIME 21 8327
TEST_TIME 22 8456
TEST_TIME 23 8930
TEST_TIME 24 9425
TEST_TIME 25 9664
TEST_TIME 26 10076
TEST_TIME 27 10512
TEST_TIME 28 10442
TEST_TIME 29 10298
TEST_TIME 30 10312
TEST_TIME 31 10440
TEST_TIME 32 10334
TEST_TIME 33 10124
TEST_TIME 34 9974
TEST_TIME 35 9990
TEST_TIME 36 9931
TEST_TIME 37 9895
TEST_TIME 38 9901
TEST_TIME 39 9856
TEST_TIME 40 9948
TEST_TIME 41 9832
TEST_TIME 42 9725
TEST_TIME 43 9683
TEST_TIME 44 9628
TEST_TIME 45 9462
TEST_TIME 46 9325
TEST_TIME 47 9294
TEST_TIME 48 9281
TEST_TIME 49 9267
TEST_TIME 50 9421
TEST_TIME 51 9334
TEST_TIME 52 9323
TEST_TIME 53 9253
TEST_TIME 54 9378
TEST_TIME 55 9560
TEST_TIME 56 9609
TEST_TIME 57 9712
TEST_TIME 58 9622
TEST_TIME 59 9709
TEST_TIME 60 9756
TEST_TIME 61 9706
TEST_TIME 62 9677
TEST_TIME 63 9601
TEST_TIME 64 9569
TEST_TIME 65 9509
TEST_TIME 66 9525
TEST_TIME 67 9529
TEST_TIME 68 9465
TEST_TIME 69 9454
TEST_TIME 70 9385
TEST_TIME 71 9396
TEST_TIME 72 9434
TEST_TIME 73 9419
TEST_TIME 74 9407
TEST_TIME 75 9506
TEST_TIME 76 9482
TEST_TIME 77 9479
TEST_TIME 78 9546
TEST_TIME 79 9521
TEST_TIME 80 9578
TEST_TIME 81 9591
TEST_TIME 82 9606
TEST_TIME 83 9690
TEST_TIME 84 9707
TEST_TIME 85 9595
TEST_TIME 86 9552
TEST_TIME 87 9513
TEST_TIME 88 9522
TEST_TIME 89 9501
TEST_TIME 90 9444
TEST_TIME 91 9428
TEST_TIME 92 9387
TEST_TIME 93 9375
TEST_TIME 94 9403
TEST_TIME 95 9444
TEST_TIME 96 9459
TEST_TIME 97 9437
TEST_TIME 98 9491
TEST_TIME 99 9516
TEST_TIME 100 9495
TEST_TIME 101 9480
TEST_TIME 102 9556
TEST_TIME 103 9556
TEST_TIME 104 9545
TEST_TIME 105 9585
TEST_TIME 106 9597
TEST_TIME 107 9561
TEST_TIME 108 9506
TEST_TIME 109 9496
TEST_TIME 110 9485
TEST_TIME 111 9459
TEST_TIME 112 9432
TEST_TIME 113 9425
TEST_TIME 114 9390
TEST_TIME 115 9380
TEST_TIME 116 9367
TEST_TIME 117 9371
TEST_TIME 118 9386
TEST_TIME 119 9386
TEST_TIME 120 9342

Performance per minute, chart


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

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 1564521 1564521 0 0.00 0 17603 6 0 0
doc_list_biud 1564519 1564519 0 0.00 0 27246 25 0 0
sp_add_invoice_to_stock 5275 5166 109 2.06 40 171132 17816 0 109
sp_cancel_adding_invoice 5654 5654 0 0.00 5 64646 779 0 0
sp_cancel_client_order 1825 1825 0 0.00 3 18884 335 0 0
sp_cancel_customer_reserve 555292 555292 0 0.00 0 27247 57 0 0
sp_cancel_pay_from_customer 696 696 0 0.00 2 1369 53 0 0
sp_cancel_pay_to_supplier 416 416 0 0.00 2 2020 57 0 0
sp_cancel_supplier_invoice 6258 6245 13 0.20 2 14088 670 0 13
sp_cancel_supplier_order 438 438 0 0.00 10 961771 89183 0 0
sp_cancel_write_off 9333 9333 0 0.00 1 11894 106 0 0
sp_client_order 21263 21263 0 0.00 3 52162 352 0 0
sp_customer_reserve 478143 478143 0 0.00 0 46611 178 0 0
sp_fill_shopping_cart 32467 32467 0 0.00 1 20884 34 0 0
sp_fill_shopping_cart_clo_res 478138 478138 0 0.00 0 15216 13 0 0
sp_get_clo_for_invoice 5158 5158 0 0.00 0 20863 256 0 0
sp_kill_cost_storno 16099 16099 0 0.00 0 4514 49 0 0
sp_kill_qstorno_ret_qs2qd 1126600 1126600 0 0.00 0 26780 30 0 0
sp_kill_qty_storno 594343 594343 0 0.00 0 27245 65 0 0
sp_lock_selected_doc 21438 21438 0 0.00 0 7238 5 0 0
sp_make_cost_storno 28697 28697 0 0.00 0 14229 48 0 0
sp_multiply_rows_for_pdistr 28696 28696 0 0.00 0 6536 26 0 0
sp_multiply_rows_for_qdistr 21263 21263 0 0.00 0 48731 166 0 0
sp_pay_from_customer 7207 7207 0 0.00 4 12447 103 0 0
sp_pay_to_supplier 5933 5933 0 0.00 4 14033 122 0 0
sp_payment_common 13140 13140 0 0.00 2 14021 29 0 0
sp_qd_handle_on_cancel_clo 1825 1825 0 0.00 0 18875 305 0 0
sp_qd_handle_on_invoice_upd_sts 10811 10811 0 0.00 0 24813 288 0 0
sp_qd_handle_on_reserve_upd_sts 10399 10399 0 0.00 0 14623 47 0 0
sp_reserve_write_off 10399 10399 0 0.00 6 14647 159 0 0
sp_supplier_invoice 5625 5625 0 0.00 6 105689 3270 0 0
sp_supplier_order 5600 5600 0 0.00 5 39378 1186 0 0
srv_aggregate_perf_data 95 95 0 0.00 2946 629781 26234 0 0
srv_fill_mon_cache_memory 68 68 0 0.00 1261 111939 39380 0 0
srv_find_qd_qs_mism 1072314 1072314 0 0.00 0 17417 6 0 0
srv_make_invnt_saldo 625 625 0 0.00 35 44960 998 0 0
srv_make_money_saldo 765 765 0 0.00 1 1616 30 0 0
srv_recalc_idx_stat 4 4 0 0.00 102484 352429 226083 0 0
t$perf-abend:sp_add_invoice_to_stock 109 109 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5166 5166 0 0.00 0 10740 164 0 0
t$perf-norm:sp_cancel_adding_invoice 420 420 0 0.00 0 8160 123 0 0
t$perf-norm:sp_cancel_client_order 1825 1825 0 0.00 0 8859 21 0 0
t$perf-norm:sp_cancel_customer_reserve 693 693 0 0.00 0 4293 26 0 0
t$perf-norm:sp_cancel_pay_from_customer 696 696 0 0.00 0 67 1 0 0
t$perf-norm:sp_cancel_pay_to_supplier 416 416 0 0.00 0 1057 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 377 377 0 0.00 0 1895 21 0 0
t$perf-norm:sp_cancel_supplier_order 438 438 0 0.00 0 23279 1579 0 0
t$perf-norm:sp_cancel_write_off 1019 1019 0 0.00 0 1021 6 0 0
t$perf-norm:sp_client_order 21263 21263 0 0.00 0 10878 19 0 0
t$perf-norm:sp_customer_reserve 1009 1009 0 0.00 0 316 3 0 0
t$perf-norm:sp_pay_from_customer 7207 7207 0 0.00 0 15871 15 0 0
t$perf-norm:sp_pay_to_supplier 5933 5933 0 0.00 0 11764 16 0 0
t$perf-norm:sp_reserve_write_off 10399 10399 0 0.00 0 12622 12 0 0
t$perf-norm:sp_supplier_invoice 5625 5625 0 0.00 0 11209 19 0 0
t$perf-norm:sp_supplier_order 5600 5600 0 0.00 0 7236 16 0 0
t$perf-norm:srv_aggregate_perf_data 95 95 0 0.00 0 858 15 0 0
t$perf-norm:srv_fill_mon_cache_memory 68 68 0 0.00 0 58 1 0 0
t$perf-norm:srv_make_invnt_saldo 625 625 0 0.00 0 1568 24 0 0
t$perf-norm:srv_make_money_saldo 765 765 0 0.00 0 1107 16 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 2 0 0
v_add_invoice_to_stock 10528 10310 218 2.07 0 11630 21 0 218
v_all_customers 38302 38302 0 0.00 0 26 1 0 0
v_all_suppliers 30982 30982 0 0.00 0 30 1 0 0
v_all_wares 42526 42526 0 0.00 0 24 1 0 0
v_cancel_adding_invoice 840 840 0 0.00 0 623 6 0 0
v_cancel_client_order 3650 3650 0 0.00 0 5027 7 0 0
v_cancel_customer_prepayment 1392 1392 0 0.00 0 713 4 0 0
v_cancel_customer_reserve 1386 1386 0 0.00 0 2055 5 0 0
v_cancel_payment_to_supplier 832 832 0 0.00 0 1968 8 0 0
v_cancel_supplier_invoice 780 754 26 3.33 0 372 13 0 26
v_cancel_supplier_order 876 876 0 0.00 0 3356 9 0 0
v_cancel_write_off 2038 2038 0 0.00 0 2979 16 0 0
v_max_id_clo_ord 5585 5585 0 0.00 0 16253 5 0 0
v_max_id_clo_res 1009 1009 0 0.00 0 1847 14 0 0
v_max_id_ord_sup 5616 5616 0 0.00 0 6477 5 0 0
v_max_non_paid_invoice 1643 1643 0 0.00 0 543 3 0 0
v_max_non_paid_realizn 3934 3934 0 0.00 0 2030 5 0 0
v_min_id_clo_ord 5585 5585 0 0.00 0 1633 4 0 0
v_min_id_clo_res 1009 1009 0 0.00 0 2153 5 0 0
v_min_id_ord_sup 5616 5616 0 0.00 0 10806 6 0 0
v_min_non_paid_invoice 5933 5933 0 0.00 0 14015 22 0 0
v_min_non_paid_realizn 7207 7207 0 0.00 0 12431 19 0 0
v_our_firm 10770 10770 0 0.00 0 24 1 0 0
v_reserve_write_off 20798 20798 0 0.00 0 5916 8 0 0
x_lock_depdocs_on_canc_invoice 11522 11522 0 0.00 0 12177 109 0 0
x_lock_depdocs_on_canc_sup_ord 438 438 0 0.00 0 29574 388 0 0
x_make_qstorno_1000_1200 5600 5600 0 0.00 1 39306 1113 0 0
x_make_qstorno_1200_2000 5625 5625 0 0.00 3 104267 3150 0 0
x_make_qstorno_2100_3300 478143 478143 0 0.00 0 38828 162 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 13-02-2023 16:40:02 to 13-02-2023 16:40:06.

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:44 8 8192000000 8809482032 8824332288 14187216 0.172 4 3 4 0 14372480 78160 107104
14:09:45 26688 8192000000 9907129168 10222366720 990251600 10.784 100 84 168 0 1019825664 6890304 22683760
14:11:25 17146 8192000000 9964681760 10290855936 1042622112 11.290 100 92 184 0 1075299264 8973216 23703936
14:12:45 17436 8192000000 9991031936 10322767872 1068962848 11.542 100 86 172 0 1101232592 10578880 21690864
14:14:08 9310 8192000000 10025477568 10373533696 1112353632 11.955 100 73 146 1 1141316080 11573456 17388992
14:15:28 5721 8192000000 10097139216 10452684800 1175948704 12.552 100 83 164 1 1208420528 13721840 18749984
14:16:37 2418 8192000000 10129384240 10500616192 1219099632 12.953 100 78 153 0 1248823472 12844080 16879760
14:17:41 2353 8192000000 10167430768 10544840704 1256553616 13.298 100 83 164 0 1285839584 11074624 18211344
14:18:46 2001 8192000000 10199972896 10580504576 1280643296 13.519 100 80 160 0 1311998224 14160000 17194928
14:19:49 20029 8192000000 10206655520 10598170624 1301270160 13.707 100 61 122 0 1324897584 10348544 13278880
14:21:13 34731 8192000000 10186830688 10606198784 1308077888 13.769 100 27 54 1 1321474768 7028176 6368704
14:22:49 6471 8192000000 10194948320 10614009856 1315658736 13.837 100 24 48 1 1326430976 5655728 5116512
14:23:57 11290 8192000000 10215630688 10631823360 1321037456 13.886 100 14 28 0 1329618176 5798080 2782640
14:25:12 12602 8192000000 10208829200 10627596288 1315128032 13.833 99 17 34 0 1324946544 5479616 4338896
14:26:25 20629 8192000000 10225874160 10643673088 1331641568 13.982 100 38 77 0 1346300064 5255728 9402768
14:27:47 4414 8192000000 10197674624 10610053120 1291044384 13.614 97 26 53 0 1302926960 5759952 6122624
14:28:54 6969 8192000000 10199424240 10617634816 1312856800 13.812 100 17 34 0 1322544288 5818592 3868896
14:30:02 13491 8192000000 10142142144 10537353216 1141883152 12.233 89 8 16 0 1148300512 4749728 1667632
14:31:16 22046 8192000000 10150015392 10556956672 1209869776 12.868 95 24 46 1 1220881104 5863744 5147584
14:32:42 3522 8192000000 10153590976 10554765312 1261012096 13.339 100 27 55 1 1272826944 5192864 6621984
14:33:47 10859 8192000000 10165597888 10575167488 1276008032 13.477 100 26 52 0 1287780256 5322576 6449648
14:34:59 12314 8192000000 10181436720 10591653888 1291309152 13.616 100 24 48 0 1302320992 5910720 5101120
14:36:13 22395 8192000000 10181764016 10598322176 1295744816 13.657 100 27 54 1 1308303952 5820944 6738192
14:37:36 5577 8192000000 10203705488 10615459840 1306579648 13.755 100 37 75 0 1322571936 8037856 7954432
14:38:46 7796 8192000000 10229199952 10639851520 1327846464 13.948 100 48 96 2 1344430528 5440848 11143216
14:39:57 2921 8192000000 10240033968 10645004288 1329395840 13.962 99 65 128 1 1353699200 10001344 14302016
14:41:04 16276 8192000000 10254791040 10677248000 1353259376 14.177 100 36 72 0 1369852336 8772928 7820032
14:42:24 4169 8192000000 10280368880 10696736768 1366286208 14.294 100 55 109 0 1389002720 10559312 12157200
14:43:41 10348 8192000000 10285445584 10709020672 1376530528 14.386 100 38 76 1 1396823504 11745872 8547104
14:44:54 11974 8192000000 10294186000 10710749184 1376869968 14.389 99 58 114 0 1407509072 17626432 13012672
14:46:17 33584 8192000000 10322451472 10747760640 1394404368 14.545 100 48 94 3 1422761248 17275856 11081024
14:47:56 20890 8192000000 10304447504 10730582016 1383573216 14.448 99 37 73 0 1409898800 17186992 9138592
14:49:41 3771 8192000000 10332111280 10752667648 1404239472 14.633 100 52 104 2 1438744992 22205968 12299552
14:50:52 6029 8192000000 10339715168 10754768896 1402862880 14.620 100 57 113 2 1443189168 26620960 13705328
14:52:02 6276 8192000000 10344039936 10762432512 1407911584 14.665 100 71 140 1 1454419888 29340496 17167808
14:53:13 2174 8192000000 10322299488 10735632384 1373362912 14.357 98 64 126 0 1426188064 37501872 15323280
14:54:19 2239 8192000000 10325551520 10739564544 1384684960 14.458 100 78 155 2 1433498928 29509600 19304368
14:55:25 3324 8192000000 10330597632 10740908032 1384443984 14.456 99 79 155 0 1430622272 28172640 18005648
14:56:30 4982 8192000000 10328609072 10737840128 1385787696 14.468 100 68 135 0 1432379216 30601296 15990224
14:57:40 3437 8192000000 10332275264 10736721920 1374635200 14.369 100 84 166 1 1434415952 38854160 20926592
14:58:47 5612 8192000000 10317323024 10735038464 1379894480 14.416 100 52 104 0 1427329568 34409760 13025328
14:59:56 1260 8192000000 10295948176 10686455808 1330600960 13.973 98 80 158 2 1389459872 38609888 20249024
15:01:02 1607 8192000000 10310084480 10713157632 1353738400 14.181 99 61 119 0 1411498688 41834528 15925760
15:02:08 20509 8192000000 10302774912 10727854080 1343005200 14.084 98 36 69 0 1390603440 38073104 9525136
15:03:31 19233 8192000000 10288825104 10714697728 1350911392 14.156 99 34 67 0 1385616272 26151584 8553296
15:04:57 42775 8192000000 10255170240 10703396864 1349832640 14.146 100 10 21 0 1370914896 18553968 2528288
15:06:41 33436 8192000000 10278045136 10717560832 1364114768 14.274 100 24 52 0 1382300928 12569696 5616464
15:08:24 40754 8192000000 10276508112 10710958080 1343772272 14.091 98 34 69 0 1371565088 20040576 7752240
15:10:12 82769 8192000000 10278239232 10719043584 1359587888 14.234 99 17 34 0 1376691024 13158096 3945040
15:13:05 24342 8192000000 10293152368 10733883392 1374798992 14.370 100 42 84 1 1400654496 15464336 10391168
15:14:33 20160 8192000000 10304960160 10740731904 1384573584 14.457 100 35 68 0 1407455088 14138464 8743040
15:15:54 53901 8192000000 10294908704 10749259776 1392298992 14.526 100 32 64 0 1415371456 15167776 7904688
15:17:49 55746 8192000000 10290493632 10746814464 1366611328 14.297 98 19 38 1 1386540976 15055536 4874112
15:19:55 33073 8192000000 10290655760 10749038592 1396543520 14.564 100 9 16 0 1407062256 8800016 1718720
15:22:15 37796 8192000000 10284900208 10746441728 1396011888 14.559 100 18 36 0 1406954640 7265360 3677392
15:24:04 47754 8192000000 10297665248 10760466432 1390336096 14.509 99 9 18 0 1406713600 14335584 2041920
15:25:53 66052 8192000000 10315246720 10781425664 1410875680 14.692 100 14 26 0 1434935184 20947712 3111792
15:28:00 85917 8192000000 10312770688 10767011840 1395098320 14.551 99 7 14 0 1410987568 14508624 1380624
15:30:54 45078 8192000000 10345656176 10799841280 1433031296 14.888 100 22 44 1 1455022064 15982528 6008240
15:32:59 45244 8192000000 10330921824 10799439872 1403035888 14.622 99 13 26 0 1428818144 22548848 3233408
15:36:00 31640 8192000000 10330344928 10772692992 1388363104 14.491 98 25 50 0 1409276352 15356064 5557184
15:37:34 68635 8192000000 10294164832 10758926336 1326618656 13.937 94 21 42 0 1344662288 13653760 4389872
15:39:42 28410 8192000000 10284710272 10709708800 1321352160 13.889 96 52 101 2 1347954992 15218352 11384480
15:41:13 70284 8192000000 10274638656 10710786048 1323858256 13.912 96 49 98 2 1343143696 8251840 11033600
15:43:24 5471 8192000000 10250618976 10701422592 1347050288 14.121 99 16 32 0 1361965456 11436288 3478880
15:44:30 54438 8192000000 10237259200 10702532608 1332126928 13.986 99 13 27 0 1349659888 14199872 3333088
15:46:25 45905 8192000000 10268992304 10718642176 1373179008 14.356 100 19 39 0 1385532784 8685408 3668368
15:48:18 111939 8192000000 10255585184 10720083968 1313053648 13.814 96 7 14 0 1326837584 12288224 1495712
15:51:10 34870 8192000000 10243756704 10672140288 1268991312 13.412 94 31 62 0 1285788816 9763024 7034480
15:52:47 56605 8192000000 10242797104 10699177984 1333438448 13.998 99 19 36 1 1349826256 11985408 4402400
15:55:25 45761 8192000000 10269872736 10710216704 1334372560 14.007 99 17 34 0 1350436176 12495664 3567952
15:57:11 52421 8192000000 10253641152 10692866048 1311762288 13.802 97 25 50 0 1327102656 9838480 5501888
15:59:05 39200 8192000000 10280326320 10712809472 1340400928 14.061 99 29 58 0 1357021968 10511824 6109216
16:00:48 79201 8192000000 10275259904 10723475456 1325504048 13.927 97 17 34 0 1341234016 12419792 3310176
16:03:07 98026 8192000000 10242338640 10682183680 1317175872 13.851 97 17 32 1 1328721968 8454032 3092064
16:05:48 32716 8192000000 10269041648 10715480064 1357463744 14.215 100 15 30 0 1371198224 10726960 3007520
16:07:30 50121 8192000000 10259575712 10716102656 1362718704 14.262 100 12 22 0 1374066080 8409184 2938192
16:09:21 46651 8192000000 10274586160 10730766336 1379060640 14.408 100 12 23 1 1391663936 9719280 2884016
16:11:32 89546 8192000000 10293102480 10752598016 1373324576 14.357 99 16 32 0 1388508624 11468928 3715120
16:14:03 65957 8192000000 10297844960 10753646592 1382212720 14.436 99 33 67 0 1402889840 13271360 7405760
16:16:09 63118 8192000000 10309182720 10765897728 1398707888 14.583 100 10 20 0 1416016880 15094848 2214144
16:18:15 16062 8192000000 10312676528 10761261056 1398549568 14.582 100 31 62 0 1413291056 7816896 6924592
16:19:34 70832 8192000000 10303622560 10769440768 1388626496 14.494 99 16 32 0 1405295024 13202448 3466080
16:21:49 74348 8192000000 10326163312 10777665536 1391970400 14.523 99 27 54 0 1409466400 11739888 5756112
16:24:05 65844 8192000000 10298669360 10748780544 1347471200 14.125 96 15 30 0 1361140128 10667712 3001216
16:26:12 80419 8192000000 10265450240 10714546176 1297502336 13.673 93 17 34 2 1307735504 6421504 3811664
16:28:33 19870 8192000000 10290085728 10742095872 1386712720 14.477 100 23 47 0 1401424000 9319344 5391936
16:29:53 68109 8192000000 10247225616 10705731584 1330711824 13.974 97 7 13 0 1341257504 9122512 1423168
16:32:17 88742 8192000000 10239884928 10690396160 1316701088 13.847 97 12 24 1 1327728560 8013664 3013808
16:34:46 52782 8192000000 10237544368 10681061376 1295503424 13.654 96 24 46 1 1309504608 8571488 5429696
16:36:39 41289 8192000000 10246422496 10693062656 1307381520 13.762 97 23 47 1 1327587680 15324624 4881536
16:38:22 25314 8192000000 9267201696 9549512704 421258448 4.890 31 12 24 0 435385264 11275984 2850832

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 13-02-2023 16:40:08 to 13-02-2023 16:40:24.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 109 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

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

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          244562
MON$OLDEST_ACTIVE               244563
MON$OLDEST_SNAPSHOT             244563
MON$NEXT_TRANSACTION            244563
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                       2029472
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.33662 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33662 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33662 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33662 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 ---
:230: 
:231: mscs-test-161.budzdorov.ru    Mon Feb 13 16:39:08 2023
:232:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:233: 
--- end of diff output ---

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

13.02.23 16:40:28. 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-13 16:40:30 No severe PSQL-related problems occured
13.02.23 16:40:32. End of report.