Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 14.02.2023 04:37
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 15148564 kB
MemAvailable 30340764 kB
Buffers 20 kB
Cached 16469316 kB
SwapCached 3388 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 4.0.3
DB_NAME                         /home/bases/oltp40-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/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/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=3400
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/oltp40-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-oltp40
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-14 04:37:27
DTS_END                         2023-02-14 04:37:27.1290
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_ADDING_INVOICE(33:5) ==> SP_ADD_INVOICE_TO_STOCK(141:26) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/46960
TRN_ID                          225278
ATT_ID                          623
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: 8748.22 20837 1049786
customer order: creation 181.48 476 21778
customer order: refuse 15.32 420 1838
order to supplier: creation 46.95 1259 5634
order to supplier: removal 3.17 153251 380
invoice (draft): creation 46.62 3035 5594
invoice (draft): removal 46.46 1034 5575
invoice accept: apply 42.48 24415 5097
invoice accept: cancel 41.95 1208 5034
customer reserve: creation 3878.39 237 465407
customer reserve: removal 4149.13 93 497895
realization accept: apply 85.63 254 10276
realization accept: cancel 76.53 179 9183
payment from customer: creation 61.22 159 7346
payment from customer: removal 5.94 121 713
payment to supplier: creation 51.27 173 6152
payment to supplier: removal 3.60 107 432
service: total inventory turnovers 4.02 3487 482
service: total monetary turnovers 7.06 450 847
service: aggregate perf. data 0.98 13795 117
service: refresh index statistics 0.05 212596 6

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 14-02-2023 04:38:17 to 14-02-2023 04:38:19.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9382
TEST_TIME 2 7982
TEST_TIME 3 6003
TEST_TIME 4 5964
TEST_TIME 5 5806
TEST_TIME 6 5963
TEST_TIME 7 5903
TEST_TIME 8 6635
TEST_TIME 9 6564
TEST_TIME 10 6665
TEST_TIME 11 6724
TEST_TIME 12 7026
TEST_TIME 13 7152
TEST_TIME 14 7240
TEST_TIME 15 7318
TEST_TIME 16 7278
TEST_TIME 17 7555
TEST_TIME 18 7567
TEST_TIME 19 8205
TEST_TIME 20 8091
TEST_TIME 21 8350
TEST_TIME 22 8667
TEST_TIME 23 9316
TEST_TIME 24 9738
TEST_TIME 25 9810
TEST_TIME 26 9953
TEST_TIME 27 9768
TEST_TIME 28 10320
TEST_TIME 29 10320
TEST_TIME 30 10489
TEST_TIME 31 10547
TEST_TIME 32 10817
TEST_TIME 33 10892
TEST_TIME 34 10781
TEST_TIME 35 10497
TEST_TIME 36 10552
TEST_TIME 37 10336
TEST_TIME 38 10286
TEST_TIME 39 10258
TEST_TIME 40 10208
TEST_TIME 41 10025
TEST_TIME 42 9962
TEST_TIME 43 9810
TEST_TIME 44 9690
TEST_TIME 45 9512
TEST_TIME 46 9342
TEST_TIME 47 9284
TEST_TIME 48 9371
TEST_TIME 49 9344
TEST_TIME 50 9303
TEST_TIME 51 9240
TEST_TIME 52 9330
TEST_TIME 53 9262
TEST_TIME 54 9284
TEST_TIME 55 9452
TEST_TIME 56 9370
TEST_TIME 57 9389
TEST_TIME 58 9469
TEST_TIME 59 9442
TEST_TIME 60 9387
TEST_TIME 61 9442
TEST_TIME 62 9324
TEST_TIME 63 9237
TEST_TIME 64 9175
TEST_TIME 65 9133
TEST_TIME 66 9061
TEST_TIME 67 9103
TEST_TIME 68 9066
TEST_TIME 69 9045
TEST_TIME 70 9003
TEST_TIME 71 8983
TEST_TIME 72 9072
TEST_TIME 73 9079
TEST_TIME 74 9113
TEST_TIME 75 9207
TEST_TIME 76 9195
TEST_TIME 77 9149
TEST_TIME 78 9307
TEST_TIME 79 9322
TEST_TIME 80 9294
TEST_TIME 81 9324
TEST_TIME 82 9243
TEST_TIME 83 9165
TEST_TIME 84 9204
TEST_TIME 85 9147
TEST_TIME 86 9122
TEST_TIME 87 9099
TEST_TIME 88 9090
TEST_TIME 89 9023
TEST_TIME 90 9054
TEST_TIME 91 9062
TEST_TIME 92 9047
TEST_TIME 93 9047
TEST_TIME 94 9014
TEST_TIME 95 9055
TEST_TIME 96 9045
TEST_TIME 97 9085
TEST_TIME 98 9153
TEST_TIME 99 9139
TEST_TIME 100 9178
TEST_TIME 101 9171
TEST_TIME 102 9200
TEST_TIME 103 9150
TEST_TIME 104 9085
TEST_TIME 105 9061
TEST_TIME 106 9018
TEST_TIME 107 8952
TEST_TIME 108 8929
TEST_TIME 109 8882
TEST_TIME 110 8853
TEST_TIME 111 8819
TEST_TIME 112 8799
TEST_TIME 113 8772
TEST_TIME 114 8768
TEST_TIME 115 8743
TEST_TIME 116 8767
TEST_TIME 117 8794
TEST_TIME 118 8830
TEST_TIME 119 8829
TEST_TIME 120 8780

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 14-02-2023 04:38:21 to 14-02-2023 04:38:24.

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 1479946 1479946 0 0.00 0 53395 12 0 0
doc_list_biud 1479925 1479925 0 0.00 0 61973 38 0 0
sp_add_invoice_to_stock 5187 5097 90 1.73 68 279038 24416 0 90
sp_cancel_adding_invoice 5034 5034 0 0.00 4 110965 1209 0 0
sp_cancel_client_order 1838 1838 0 0.00 6 15201 421 0 0
sp_cancel_customer_reserve 497895 497895 0 0.00 1 51793 93 0 0
sp_cancel_pay_from_customer 713 713 0 0.00 4 8853 122 0 0
sp_cancel_pay_to_supplier 432 432 0 0.00 4 2529 107 0 0
sp_cancel_supplier_invoice 5587 5575 12 0.21 3 50251 1035 0 12
sp_cancel_supplier_order 380 380 0 0.00 13 1482992 153251 0 0
sp_cancel_write_off 9183 9183 0 0.00 2 10551 179 0 0
sp_client_order 21778 21778 0 0.00 5 17811 476 0 0
sp_customer_reserve 465407 465407 0 0.00 0 94614 238 0 0
sp_fill_shopping_cart 32992 32992 0 0.00 1 20027 79 0 0
sp_fill_shopping_cart_clo_res 465367 465367 0 0.00 0 26638 18 0 0
sp_get_clo_for_invoice 5043 5043 0 0.00 0 33301 307 0 0
sp_kill_cost_storno 15362 15362 0 0.00 0 53379 70 0 0
sp_kill_qstorno_ret_qs2qd 1010928 1010928 0 0.00 0 51743 42 0 0
sp_kill_qty_storno 535223 535223 0 0.00 0 61972 96 0 0
sp_lock_selected_doc 21180 21180 0 0.00 0 12229 7 0 0
sp_make_cost_storno 28817 28817 0 0.00 0 11125 68 0 0
sp_multiply_rows_for_pdistr 28817 28817 0 0.00 0 25577 33 0 0
sp_multiply_rows_for_qdistr 21775 21775 0 0.00 0 17518 211 0 0
sp_pay_from_customer 7346 7346 0 0.00 5 25639 159 0 0
sp_pay_to_supplier 6152 6152 0 0.00 6 11968 173 0 0
sp_payment_common 13498 13498 0 0.00 2 5599 36 0 0
sp_qd_handle_on_cancel_clo 1838 1838 0 0.00 0 15148 331 0 0
sp_qd_handle_on_invoice_upd_sts 10076 10076 0 0.00 0 61971 466 0 0
sp_qd_handle_on_reserve_upd_sts 10276 10276 0 0.00 0 12134 55 0 0
sp_reserve_write_off 10276 10276 0 0.00 8 17821 254 0 0
sp_supplier_invoice 5594 5594 0 0.00 9 134556 3035 0 0
sp_supplier_order 5634 5634 0 0.00 7 37608 1259 0 0
srv_aggregate_perf_data 117 117 0 0.00 2443 97284 13795 0 0
srv_fill_mon_cache_memory 71 71 0 0.00 728 135133 35863 0 0
srv_find_qd_qs_mism 1000278 1000278 0 0.00 0 11783 10 0 0
srv_make_invnt_saldo 482 482 0 0.00 26 58765 3487 0 0
srv_make_money_saldo 847 847 0 0.00 1 55538 451 0 0
srv_recalc_idx_stat 6 6 0 0.00 38903 497005 212596 0 0
t$perf-abend:sp_add_invoice_to_stock 90 90 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5100 5100 0 0.00 0 54825 796 0 0
t$perf-norm:sp_cancel_adding_invoice 367 367 0 0.00 1 6303 453 0 0
t$perf-norm:sp_cancel_client_order 1838 1838 0 0.00 0 1398 25 0 0
t$perf-norm:sp_cancel_customer_reserve 691 691 0 0.00 0 2063 28 0 0
t$perf-norm:sp_cancel_pay_from_customer 713 713 0 0.00 0 11719 37 0 0
t$perf-norm:sp_cancel_pay_to_supplier 432 432 0 0.00 0 2798 19 0 0
t$perf-norm:sp_cancel_supplier_invoice 417 417 0 0.00 0 653 27 0 0
t$perf-norm:sp_cancel_supplier_order 380 380 0 0.00 1 89198 6011 0 0
t$perf-norm:sp_cancel_write_off 1029 1029 0 0.00 0 2459 25 0 0
t$perf-norm:sp_client_order 21780 21780 0 0.00 0 10697 33 0 0
t$perf-norm:sp_customer_reserve 961 961 0 0.00 0 4721 17 0 0
t$perf-norm:sp_pay_from_customer 7346 7346 0 0.00 0 12699 18 0 0
t$perf-norm:sp_pay_to_supplier 6152 6152 0 0.00 0 2084 16 0 0
t$perf-norm:sp_reserve_write_off 10276 10276 0 0.00 0 13149 20 0 0
t$perf-norm:sp_supplier_invoice 5594 5594 0 0.00 0 4644 27 0 0
t$perf-norm:sp_supplier_order 5634 5634 0 0.00 0 3883 27 0 0
t$perf-norm:srv_aggregate_perf_data 117 117 0 0.00 0 291 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 71 71 0 0.00 0 809 14 0 0
t$perf-norm:srv_make_invnt_saldo 482 482 0 0.00 0 1063 10 0 0
t$perf-norm:srv_make_money_saldo 847 847 0 0.00 0 670 4 0 0
t$perf-norm:srv_recalc_idx_stat 6 6 0 0.00 5 153 50 0 0
v_add_invoice_to_stock 10254 10074 180 1.75 0 7411 26 0 180
v_all_customers 39424 39424 0 0.00 0 106 3 0 0
v_all_suppliers 31409 31409 0 0.00 0 114 4 0 0
v_all_wares 43544 43544 0 0.00 0 235 4 0 0
v_cancel_adding_invoice 734 734 0 0.00 0 7897 24 0 0
v_cancel_client_order 3676 3676 0 0.00 0 8366 20 0 0
v_cancel_customer_prepayment 1426 1426 0 0.00 0 8777 25 0 0
v_cancel_customer_reserve 1382 1382 0 0.00 0 8358 25 0 0
v_cancel_payment_to_supplier 864 864 0 0.00 0 700 10 0 0
v_cancel_supplier_invoice 858 834 24 2.79 0 3294 25 0 24
v_cancel_supplier_order 760 760 0 0.00 0 7265 20 0 0
v_cancel_write_off 2058 2058 0 0.00 0 1819 22 0 0
v_max_id_clo_ord 5631 5631 0 0.00 0 1083 4 0 0
v_max_id_clo_res 961 961 0 0.00 0 1841 18 0 0
v_max_id_ord_sup 5589 5589 0 0.00 0 4726 9 0 0
v_max_non_paid_invoice 1667 1667 0 0.00 0 501 7 0 0
v_max_non_paid_realizn 3978 3978 0 0.00 0 532 6 0 0
v_min_id_clo_ord 5631 5631 0 0.00 0 3878 10 0 0
v_min_id_clo_res 961 961 0 0.00 0 4263 16 0 0
v_min_id_ord_sup 5589 5589 0 0.00 0 3974 10 0 0
v_min_non_paid_invoice 6151 6151 0 0.00 0 4653 12 0 0
v_min_non_paid_realizn 7346 7346 0 0.00 0 5592 12 0 0
v_our_firm 10856 10856 0 0.00 0 57 4 0 0
v_reserve_write_off 20552 20552 0 0.00 0 17652 16 0 0
x_lock_depdocs_on_canc_invoice 10192 10192 0 0.00 0 12402 112 0 0
x_lock_depdocs_on_canc_sup_ord 380 380 0 0.00 0 6256 322 0 0
x_make_qstorno_1000_1200 5634 5634 0 0.00 2 37539 1111 0 0
x_make_qstorno_1200_2000 5594 5594 0 0.00 4 134421 2801 0 0
x_make_qstorno_2100_3300 465405 465405 0 0.00 0 94591 209 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 14-02-2023 04:38:25 to 14-02-2023 04:38:30.

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
02:07:31 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
02:08:31 27687 8192000000 9730372944 10032943104 810013456 8.998 100 93 186 0 839861888 7536336 22312096
02:10:07 22708 8192000000 9774924560 10085396480 848554560 9.386 100 91 182 1 884690000 14026224 22109216
02:11:39 9599 8192000000 9816783440 10136985600 877273216 9.673 100 92 183 0 925119360 26717952 21128192
02:13:05 5850 8192000000 9858848080 10193293312 913112976 10.028 100 82 154 0 972712688 42911392 16688320
02:14:13 3884 8192000000 9915554400 10258403328 948008544 10.372 100 76 148 0 1024996352 60463856 16523952
02:15:21 2041 8192000000 9956578048 10309980160 996201760 10.842 100 87 167 0 1076471808 63289648 16980400
02:16:28 1183 8192000000 10009109648 10373087232 1023778848 11.108 100 93 182 0 1130665408 88960384 17926176
02:17:30 1211 8192000000 10030106576 10400415744 1044110576 11.304 100 97 191 2 1145685152 82940688 18633888
02:18:33 1095 8192000000 10030688304 10403160064 1051043552 11.371 100 96 188 1 1152230720 81344528 19842640
02:19:35 9735 8192000000 10030310160 10402775040 1060744176 11.464 100 92 173 3 1143901680 66863632 16293872
02:20:46 2270 8192000000 10036067152 10416295936 1061982720 11.475 100 74 139 1 1160016752 84291696 13742336
02:21:49 1082 8192000000 10030188592 10407137280 1071615376 11.568 100 96 180 3 1146022480 55901680 18505424
02:22:51 1079 8192000000 10045499728 10427826176 1072045696 11.572 100 90 170 3 1168578192 79604400 16928096
02:23:53 1120 8192000000 10065426080 10450726912 1078907408 11.637 100 96 179 4 1188316464 91121456 18287600
02:24:54 1046 8192000000 10053972032 10442072064 1083706848 11.683 100 88 175 0 1177243040 75638544 17897648
02:25:56 1180 8192000000 10055859696 10445123584 1093655152 11.777 100 94 183 0 1180759248 69151952 17952144
02:26:58 2092 8192000000 10047366480 10441584640 1095897456 11.799 100 72 139 2 1173196976 62468240 14831280
02:28:03 1111 8192000000 10045496144 10435117056 1092082448 11.762 100 96 171 2 1167490880 56801344 18607088
02:29:05 1083 8192000000 10067412560 10454487040 1097770352 11.816 100 96 179 1 1189933728 73750656 18412720
02:30:07 848 8192000000 10050700864 10441129984 1092564880 11.767 100 88 163 0 1172819808 63406768 16848160
02:31:09 1138 8192000000 9999601856 10381553664 1060814640 11.464 99 81 157 2 1121431344 42841968 17774736
02:32:11 3706 8192000000 10019406208 10404147200 1073523376 11.586 100 69 129 1 1146729056 59053152 14152528
02:33:16 2657 8192000000 10009842880 10397319168 1059457664 11.451 99 67 130 2 1133920240 61310368 13152208
02:34:19 1290 8192000000 9995456640 10369720320 1051002464 11.370 100 95 178 2 1121031792 52194640 17834688
02:35:21 1019 8192000000 10011494256 10383458304 1041350080 11.278 100 91 176 2 1127176224 68087536 17738608
02:36:24 920 8192000000 9959892592 10333048832 1021559824 11.087 99 91 165 7 1086960352 45780368 19620160
02:37:26 1331 8192000000 10009749088 10379804672 1036168960 11.228 100 94 165 2 1127409424 75213248 16027216
02:38:35 1873 8192000000 9995216224 10376245248 1049896272 11.360 100 85 169 1 1122141280 54958432 17286576
02:39:42 1209 8192000000 10048498656 10431586304 1073600288 11.586 100 94 178 0 1169902384 77741728 18560368
02:40:48 7620 8192000000 10045518528 10432860160 1081179632 11.659 99 66 121 0 1165040528 72443408 11417488
02:42:00 1783 8192000000 10111628208 10499190784 1107401712 11.908 100 84 162 1 1234268928 109070288 17796928
02:43:04 13126 8192000000 10186024544 10585231360 1119412192 12.021 100 64 123 0 1296732080 164175504 13144384
02:44:26 3542 8192000000 10208336992 10602631168 1131823824 12.139 100 85 166 0 1318276048 169352032 17100192
02:45:36 1519 8192000000 10222648192 10631860224 1143273536 12.246 100 92 177 3 1336993664 173362400 20357728
02:46:42 2779 8192000000 10248762832 10655154176 1150358032 12.313 100 84 165 0 1366241344 196206832 19676480
02:47:51 895 8192000000 10279265664 10682159104 1162522016 12.427 100 92 176 2 1391289376 209509120 19258240
02:48:58 782 8192000000 10321180096 10732519424 1160356400 12.407 100 95 188 1 1433078768 251833168 20889200
02:50:02 721 8192000000 10341670048 10755919872 1176480208 12.557 100 94 178 2 1455659072 258224960 20953904
02:51:05 991 8192000000 10387950544 10788421632 1179205632 12.583 100 88 170 0 1496460960 298736800 18518528
02:52:19 1509 8192000000 10382357216 10794512384 1178567040 12.577 100 87 161 2 1487276704 291095200 17614464
02:53:23 1355 8192000000 10433369632 10852806656 1187866224 12.663 100 91 181 0 1542610032 333305200 21438608
02:54:29 1357 8192000000 10458056480 10867904512 1186346064 12.649 100 96 191 1 1560596976 351877056 22373856
02:55:34 949 8192000000 10456378512 10887376896 1172696624 12.522 100 98 195 2 1558737536 363158448 22882464
02:56:37 2145 8192000000 10463558976 10908377088 1181788464 12.607 100 73 142 0 1571165696 371842640 17534592
02:57:47 3855 8192000000 10431866384 10874761216 1174506768 12.539 100 64 122 0 1537072256 347506336 15059152
02:58:55 2240 8192000000 10394452720 10841071616 1160287312 12.406 99 70 128 1 1498800960 323294368 15219280
03:00:02 7397 8192000000 10344174272 10832814080 1146707728 12.279 99 40 80 0 1462672880 305694784 10270368
03:01:12 15891 8192000000 10258552176 10748284928 1159471504 12.398 100 36 70 1 1389800992 221507776 8821712
03:02:38 10101 8192000000 10248170720 10691973120 1141226704 12.227 97 47 91 0 1337506336 186213296 10066336
03:03:48 23115 8192000000 10266936464 10705358848 1140979424 12.225 99 29 56 0 1355817888 208055296 6783168
03:05:13 13070 8192000000 10222812352 10698027008 1134942240 12.168 98 37 70 1 1319420128 175902944 8574944
03:06:28 27574 8192000000 10197212160 10655563776 1121214080 12.038 98 18 36 0 1301270224 175607136 4449008
03:08:05 46330 8192000000 10194397840 10651521024 1142880000 12.243 100 22 42 0 1315594048 167852352 4861696
03:09:52 37379 8192000000 10076697760 10503684096 1076292416 11.612 94 22 39 2 1153100448 73145040 3662992
03:11:37 97825 8192000000 10095024304 10528485376 1110653712 11.939 99 41 68 2 1193214704 74405280 8155712
03:14:15 70683 8192000000 10060865616 10517725184 1082855184 11.675 97 36 72 1 1140443952 50079744 7509024
03:16:42 62370 8192000000 10064072112 10555535360 1111190272 11.944 99 9 18 0 1165191360 51955088 2046000
03:18:46 47083 8192000000 10035881168 10509197312 1124088480 12.066 100 11 22 0 1163072176 36574096 2409600
03:20:33 23453 8192000000 10063289456 10503634944 1128371904 12.106 100 52 104 1 1163703392 24581360 10750128
03:21:59 135129 8192000000 10102981920 10555658240 1133407984 12.153 100 24 49 0 1222010048 82623616 5978448
03:25:18 58243 8192000000 10062730016 10557665280 1134891984 12.167 100 10 19 0 1182937648 46326112 1719552
03:27:37 47888 8192000000 10113406576 10575503360 1131411008 12.135 99 20 38 1 1192626000 57324224 3890768
03:29:39 35784 8192000000 10119691104 10576633856 1137923552 12.196 99 17 34 0 1262162896 119961568 4277776
03:31:22 37631 8192000000 10157054784 10621911040 1145560944 12.268 100 33 64 0 1260620048 107955616 7103488
03:33:12 46247 8192000000 10102008000 10538356736 1119168768 12.019 99 25 50 0 1170401680 45205808 6027104
03:35:02 44447 8192000000 10135488512 10581983232 1125426640 12.078 99 23 44 0 1202868832 72586896 4855296
03:36:54 29341 8192000000 10130836912 10557239296 1120663808 12.033 99 49 97 0 1219845408 89399328 9782272
03:38:35 83343 8192000000 10074260304 10510159872 1132606192 12.146 100 37 74 1 1174008800 33509264 7893344
03:41:00 61171 8192000000 10116817552 10588770304 1149258560 12.303 100 35 70 0 1236402672 78969296 8174816
03:43:13 56590 8192000000 10106126768 10568495104 1134389024 12.163 99 15 26 1 1191929792 55066608 2474160
03:45:10 39357 8192000000 10089764736 10532155392 1120597648 12.033 98 40 74 0 1161283232 32803216 7882368
03:46:52 24483 8192000000 10119337008 10568527872 1143070832 12.244 100 25 51 0 1209296480 60509088 5716560
03:48:24 48298 8192000000 10178850720 10628939776 1114185344 11.972 97 33 66 0 1225251184 103877904 7187936
03:50:14 74647 8192000000 10118600128 10572701696 1087849504 11.722 96 16 33 0 1197846960 106154112 3843344
03:52:31 52078 8192000000 10101866704 10552197120 1122819584 12.054 99 40 77 0 1187004160 56331728 7852848
03:54:27 22629 8192000000 10187642208 10623139840 1137255504 12.190 100 45 90 0 1272520432 125943184 9321744
03:55:51 86045 8192000000 10042756944 10492026880 1043996048 11.303 93 23 47 0 1070497344 21618480 4882816
03:58:18 45486 8192000000 10058831632 10514923520 1097938272 11.818 98 27 54 0 1166611776 62936080 5737424
04:00:07 105113 8192000000 10045899264 10504200192 1072789920 11.579 96 30 60 0 1151909632 72359472 6760240
04:03:24 67690 8192000000 10081491888 10545381376 1098907152 11.827 98 17 34 0 1176284416 73963520 3413744
04:05:36 32793 8192000000 10085024448 10556518400 1110121600 11.934 99 31 61 1 1197533744 80726272 6685872
04:07:14 79442 8192000000 10073934240 10546561024 1099845680 11.836 98 13 26 0 1174759120 72124032 2789408
04:09:43 47052 8192000000 10110778000 10554298368 1115995520 11.989 99 25 49 0 1195318352 74652976 4669856
04:11:31 30158 8192000000 10137594976 10588229632 1117213568 12.001 99 29 58 0 1213525664 89821776 6490320
04:13:01 52089 8192000000 10133586208 10573643776 1110977104 11.942 98 31 61 0 1208620496 90967312 6676080
04:14:54 46472 8192000000 10077350912 10550812672 1094871152 11.789 97 25 50 0 1211649584 111252608 5525824
04:16:43 54932 8192000000 10104001072 10547331072 1118708912 12.015 99 42 82 1 1194620928 67527360 8384656
04:18:44 58056 8192000000 10112651936 10582437888 1118228544 12.010 99 31 62 0 1186677296 62491808 5956944
04:20:45 105737 8192000000 10068195680 10517331968 1125880864 12.083 99 21 42 0 1184562400 54394176 4287360
04:23:38 36704 8192000000 10026892912 10502119424 1103563712 11.871 98 1 2 0 1126652848 23053728 35408
04:25:15 64120 8192000000 10040312304 10488299520 1102914624 11.865 98 10 20 0 1134035344 28751104 2369616
04:27:19 59323 8192000000 10030907424 10485448704 1097354064 11.813 98 5 10 0 1135708320 37374816 979440
04:30:05 25302 8192000000 10073129568 10514714624 1106259248 11.897 99 31 61 0 1162502752 49827936 6415568
04:31:37 32083 8192000000 10122111504 10565627904 1115841104 11.988 99 29 58 0 1203889104 82091984 5956016
04:33:10 64638 8192000000 10131409136 10590588928 1104546448 11.881 98 41 82 0 1238460960 124701952 9212560
04:35:34 15530 8192000000 10062828976 10483503104 1081327904 11.660 97 43 86 0 1123609728 33871312 8410512
04:36:50 6096 8192000000 10106601312 10522398720 1124345376 12.068 100 64 125 1 1199926384 62813664 12767344

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 14-02-2023 04:38:31 to 14-02-2023 04:38:48.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 90 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 14-02-2023 04:38:49 to 14-02-2023 04:38:51.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          221140
MON$OLDEST_ACTIVE               225579
MON$OLDEST_SNAPSHOT             225579
MON$NEXT_TRANSACTION            225579
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-30 07:30:08.6030 GMT
MON$PAGES                       2038496
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             784
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2899 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2899 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2899 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2899 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.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 ---
:301: 
:302: mscs-test-161.budzdorov.ru    Tue Feb 14 04:37:31 2023
:303:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:304: 
--- end of diff output ---

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

14.02.23 04:38:52. There are 3 logs in /var/tmp/logs-oltp40 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-14 04:38:55 No severe PSQL-related problems occured
14.02.23 04:38:56. End of report.