Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 31.12.2022 01: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 15349768 kB
MemAvailable 30380700 kB
Buffers 0 kB
Cached 16338036 kB
SwapCached 11596 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 2022-12-31 01:38:34
DTS_END                         2022-12-31 01:38:34.2890
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(131:22) ==> SP_CANCEL_ADDING_INVOICE(33:5) ==> SP_ADD_INVOICE_TO_STOCK(69:14) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/45362
TRN_ID                          240161
ATT_ID                          634
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: 9112.21 23032 1093465
customer order: creation 174.77 340 20972
customer order: refuse 14.73 344 1768
order to supplier: creation 46.36 1103 5563
order to supplier: removal 3.44 97112 413
invoice (draft): creation 46.63 3316 5596
invoice (draft): removal 50.56 699 6067
invoice accept: apply 41.99 19178 5039
invoice accept: cancel 45.80 838 5496
customer reserve: creation 3866.33 191 463960
customer reserve: removal 4521.71 58 542605
realization accept: apply 84.46 195 10135
realization accept: cancel 86.03 99 10323
payment from customer: creation 58.90 136 7068
payment from customer: removal 5.65 69 678
payment to supplier: creation 48.88 159 5865
payment to supplier: removal 3.75 77 450
service: total inventory turnovers 4.58 1202 550
service: total monetary turnovers 6.78 29 814
service: aggregate perf. data 0.83 25533 99
service: refresh index statistics 0.03 309969 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 31-12-2022 01:39:43 to 31-12-2022 01:39:45.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2578
TEST_TIME 2 2694
TEST_TIME 3 2476
TEST_TIME 4 4011
TEST_TIME 5 4607
TEST_TIME 6 4551
TEST_TIME 7 5066
TEST_TIME 8 4909
TEST_TIME 9 5286
TEST_TIME 10 5468
TEST_TIME 11 5405
TEST_TIME 12 5436
TEST_TIME 13 5813
TEST_TIME 14 5890
TEST_TIME 15 6317
TEST_TIME 16 6655
TEST_TIME 17 6758
TEST_TIME 18 7122
TEST_TIME 19 7386
TEST_TIME 20 7834
TEST_TIME 21 7892
TEST_TIME 22 7835
TEST_TIME 23 8715
TEST_TIME 24 8792
TEST_TIME 25 8840
TEST_TIME 26 9334
TEST_TIME 27 9618
TEST_TIME 28 10503
TEST_TIME 29 10503
TEST_TIME 30 10364
TEST_TIME 31 10281
TEST_TIME 32 10173
TEST_TIME 33 10427
TEST_TIME 34 10182
TEST_TIME 35 10032
TEST_TIME 36 9855
TEST_TIME 37 9744
TEST_TIME 38 9636
TEST_TIME 39 9581
TEST_TIME 40 9743
TEST_TIME 41 9687
TEST_TIME 42 9628
TEST_TIME 43 9558
TEST_TIME 44 9455
TEST_TIME 45 9483
TEST_TIME 46 9571
TEST_TIME 47 9513
TEST_TIME 48 9341
TEST_TIME 49 9422
TEST_TIME 50 9396
TEST_TIME 51 9447
TEST_TIME 52 9346
TEST_TIME 53 9358
TEST_TIME 54 9440
TEST_TIME 55 9497
TEST_TIME 56 9495
TEST_TIME 57 9471
TEST_TIME 58 9558
TEST_TIME 59 9599
TEST_TIME 60 9576
TEST_TIME 61 9514
TEST_TIME 62 9589
TEST_TIME 63 9629
TEST_TIME 64 9496
TEST_TIME 65 9433
TEST_TIME 66 9422
TEST_TIME 67 9398
TEST_TIME 68 9339
TEST_TIME 69 9275
TEST_TIME 70 9262
TEST_TIME 71 9159
TEST_TIME 72 9211
TEST_TIME 73 9173
TEST_TIME 74 9190
TEST_TIME 75 9152
TEST_TIME 76 9199
TEST_TIME 77 9173
TEST_TIME 78 9269
TEST_TIME 79 9272
TEST_TIME 80 9281
TEST_TIME 81 9249
TEST_TIME 82 9293
TEST_TIME 83 9347
TEST_TIME 84 9429
TEST_TIME 85 9401
TEST_TIME 86 9371
TEST_TIME 87 9306
TEST_TIME 88 9259
TEST_TIME 89 9251
TEST_TIME 90 9227
TEST_TIME 91 9176
TEST_TIME 92 9155
TEST_TIME 93 9202
TEST_TIME 94 9180
TEST_TIME 95 9153
TEST_TIME 96 9224
TEST_TIME 97 9263
TEST_TIME 98 9307
TEST_TIME 99 9284
TEST_TIME 100 9240
TEST_TIME 101 9325
TEST_TIME 102 9300
TEST_TIME 103 9299
TEST_TIME 104 9296
TEST_TIME 105 9305
TEST_TIME 106 9371
TEST_TIME 107 9339
TEST_TIME 108 9271
TEST_TIME 109 9234
TEST_TIME 110 9200
TEST_TIME 111 9189
TEST_TIME 112 9148
TEST_TIME 113 9142
TEST_TIME 114 9156
TEST_TIME 115 9121
TEST_TIME 116 9125
TEST_TIME 117 9170
TEST_TIME 118 9183
TEST_TIME 119 9170
TEST_TIME 120 9113

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 31-12-2022 01:39:47 to 31-12-2022 01:39:50.

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 1523970 1523970 0 0.00 0 26026 7 0 0
doc_list_biud 1523970 1523970 0 0.00 0 45589 26 0 0
sp_add_invoice_to_stock 5192 5039 153 2.94 34 206882 19179 0 153
sp_cancel_adding_invoice 5496 5496 0 0.00 5 74030 838 0 0
sp_cancel_client_order 1768 1768 0 0.00 4 9493 345 0 0
sp_cancel_customer_reserve 542605 542605 0 0.00 0 45590 59 0 0
sp_cancel_pay_from_customer 678 678 0 0.00 2 3800 69 0 0
sp_cancel_pay_to_supplier 450 450 0 0.00 2 4758 78 0 0
sp_cancel_supplier_invoice 6074 6067 7 0.11 2 19000 700 0 7
sp_cancel_supplier_order 413 413 0 0.00 5 1070527 97112 0 0
sp_cancel_write_off 10323 10323 0 0.00 1 10866 99 0 0
sp_client_order 20972 20972 0 0.00 3 45464 341 0 0
sp_customer_reserve 463960 463960 0 0.00 0 74862 191 0 0
sp_fill_shopping_cart 32129 32129 0 0.00 1 16289 38 0 0
sp_fill_shopping_cart_clo_res 463959 463959 0 0.00 0 13652 14 0 0
sp_get_clo_for_invoice 5038 5038 0 0.00 0 23890 344 0 0
sp_kill_cost_storno 16947 16947 0 0.00 0 10817 52 0 0
sp_kill_qstorno_ret_qs2qd 1102013 1102013 0 0.00 0 45586 31 0 0
sp_kill_qty_storno 581845 581845 0 0.00 0 45588 68 0 0
sp_lock_selected_doc 20899 20899 0 0.00 0 7151 4 0 0
sp_make_cost_storno 28106 28106 0 0.00 0 26021 68 0 0
sp_multiply_rows_for_pdistr 28106 28106 0 0.00 0 11066 29 0 0
sp_multiply_rows_for_qdistr 20972 20972 0 0.00 0 45342 155 0 0
sp_pay_from_customer 7068 7068 0 0.00 4 19242 136 0 0
sp_pay_to_supplier 5865 5865 0 0.00 5 21574 159 0 0
sp_payment_common 12933 12933 0 0.00 1 12796 41 0 0
sp_qd_handle_on_cancel_clo 1768 1768 0 0.00 0 9487 318 0 0
sp_qd_handle_on_invoice_upd_sts 10534 10534 0 0.00 0 26682 321 0 0
sp_qd_handle_on_reserve_upd_sts 10135 10135 0 0.00 0 16988 65 0 0
sp_reserve_write_off 10135 10135 0 0.00 6 22465 195 0 0
sp_supplier_invoice 5596 5596 0 0.00 6 101194 3316 0 0
sp_supplier_order 5563 5563 0 0.00 5 22454 1103 0 0
srv_aggregate_perf_data 99 99 0 0.00 2950 691016 25534 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 733 140673 48479 0 0
srv_find_qd_qs_mism 1045878 1045878 0 0.00 0 11794 6 0 0
srv_make_invnt_saldo 550 550 0 0.00 50 129373 1202 0 0
srv_make_money_saldo 814 814 0 0.00 1 2444 30 0 0
srv_recalc_idx_stat 4 4 0 0.00 142902 424903 309970 0 0
t$perf-abend:sp_add_invoice_to_stock 153 153 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5039 5039 0 0.00 0 45845 204 0 0
t$perf-norm:sp_cancel_adding_invoice 440 440 0 0.00 0 3220 101 0 0
t$perf-norm:sp_cancel_client_order 1768 1768 0 0.00 0 4566 15 0 0
t$perf-norm:sp_cancel_customer_reserve 628 628 0 0.00 0 1654 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 678 678 0 0.00 0 33850 65 0 0
t$perf-norm:sp_cancel_pay_to_supplier 450 450 0 0.00 0 4556 33 0 0
t$perf-norm:sp_cancel_supplier_invoice 377 377 0 0.00 0 408 10 0 0
t$perf-norm:sp_cancel_supplier_order 413 413 0 0.00 0 22595 1775 0 0
t$perf-norm:sp_cancel_write_off 973 973 0 0.00 0 1238 6 0 0
t$perf-norm:sp_client_order 20972 20972 0 0.00 0 5625 16 0 0
t$perf-norm:sp_customer_reserve 983 983 0 0.00 0 1726 8 0 0
t$perf-norm:sp_pay_from_customer 7068 7068 0 0.00 0 37031 15 0 0
t$perf-norm:sp_pay_to_supplier 5865 5865 0 0.00 0 4499 9 0 0
t$perf-norm:sp_reserve_write_off 10135 10135 0 0.00 0 8093 14 0 0
t$perf-norm:sp_supplier_invoice 5596 5596 0 0.00 0 29283 24 0 0
t$perf-norm:sp_supplier_order 5563 5563 0 0.00 0 10118 18 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 307 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 1198 22 0 0
t$perf-norm:srv_make_invnt_saldo 550 550 0 0.00 0 3010 39 0 0
t$perf-norm:srv_make_money_saldo 814 814 0 0.00 0 3947 23 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 10 4 0 0
v_add_invoice_to_stock 10380 10074 306 2.94 0 16491 22 0 306
v_all_customers 37804 37804 0 0.00 0 19 1 0 0
v_all_suppliers 30616 30616 0 0.00 0 39 1 0 0
v_all_wares 41944 41944 0 0.00 0 75 1 0 0
v_cancel_adding_invoice 880 880 0 0.00 0 2187 9 0 0
v_cancel_client_order 3536 3536 0 0.00 0 6133 7 0 0
v_cancel_customer_prepayment 1356 1356 0 0.00 0 769 5 0 0
v_cancel_customer_reserve 1256 1256 0 0.00 0 935 5 0 0
v_cancel_payment_to_supplier 900 900 0 0.00 0 319 3 0 0
v_cancel_supplier_invoice 768 754 14 1.82 0 1064 16 0 14
v_cancel_supplier_order 826 826 0 0.00 0 109 2 0 0
v_cancel_write_off 1946 1946 0 0.00 0 1404 13 0 0
v_max_id_clo_ord 5563 5563 0 0.00 0 7449 3 0 0
v_max_id_clo_res 983 983 0 0.00 0 3506 19 0 0
v_max_id_ord_sup 5594 5594 0 0.00 0 2470 3 0 0
v_max_non_paid_invoice 1714 1714 0 0.00 0 1660 4 0 0
v_max_non_paid_realizn 3861 3861 0 0.00 0 10629 11 0 0
v_min_id_clo_ord 5563 5563 0 0.00 0 9404 14 0 0
v_min_id_clo_res 983 983 0 0.00 0 1777 8 0 0
v_min_id_ord_sup 5594 5594 0 0.00 0 6068 10 0 0
v_min_non_paid_invoice 5865 5865 0 0.00 0 11049 30 0 0
v_min_non_paid_realizn 7068 7068 0 0.00 0 11055 32 0 0
v_our_firm 10554 10554 0 0.00 0 20 1 0 0
v_reserve_write_off 20269 20269 0 0.00 0 5842 6 0 0
x_lock_depdocs_on_canc_invoice 11186 11186 0 0.00 0 20036 140 0 0
x_lock_depdocs_on_canc_sup_ord 413 413 0 0.00 0 13192 371 0 0
x_make_qstorno_1000_1200 5563 5563 0 0.00 1 22415 1022 0 0
x_make_qstorno_1200_2000 5596 5596 0 0.00 2 96718 3176 0 0
x_make_qstorno_2100_3300 463960 463960 0 0.00 0 74860 175 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 31-12-2022 01:39:51 to 31-12-2022 01:39:55.

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
23:08:38 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:39 25010 8192000000 9893858112 10202894336 976573344 10.651 100 90 181 0 1006611408 5623664 24414400
23:11:08 17755 8192000000 9938125568 10258419712 1022352912 11.095 100 91 184 0 1053160352 7367520 23439920
23:12:32 21934 8192000000 9988360304 10322345984 1062971936 11.485 100 82 163 0 1092795824 9202064 20621824
23:13:59 12123 8192000000 10035498592 10381266944 1118100912 12.009 100 81 162 1 1148144368 10601344 19442112
23:15:18 7237 8192000000 10066084160 10430033920 1162580320 12.427 100 71 142 0 1191026448 11666384 16779744
23:16:28 4065 8192000000 10116238928 10487586816 1209259936 12.862 100 71 142 0 1237655232 12841168 15554128
23:17:35 3008 8192000000 10146921856 10524442624 1240500032 13.151 100 76 152 0 1270432192 13639408 16292752
23:18:45 2125 8192000000 10193776112 10574512128 1278121760 13.496 100 92 182 0 1312225328 14183152 19920416
23:19:49 1035 8192000000 10211981376 10597429248 1300962784 13.704 100 79 158 1 1331171536 12738544 17470208
23:20:53 1097 8192000000 10216771712 10613600256 1311978912 13.804 100 72 143 1 1339712384 11358336 16375136
23:21:54 6086 8192000000 10196837552 10611773440 1317236224 13.852 100 33 62 1 1330088896 5605504 7247168
23:23:03 5387 8192000000 10209077888 10626134016 1322383008 13.898 100 24 46 0 1338242240 10391168 5468064
23:24:13 10929 8192000000 10216610064 10632196096 1331239664 13.978 100 17 34 0 1340351120 5618416 3493040
23:25:28 46117 8192000000 10231916208 10650656768 1339163408 14.050 100 18 34 0 1348732400 4863216 4705776
23:27:19 8120 8192000000 10242036976 10662739968 1343920224 14.093 100 39 78 0 1360197712 7488912 8788576
23:28:29 5416 8192000000 10224992192 10646102016 1325711088 13.928 99 19 37 0 1335249888 5628464 3910336
23:29:35 53048 8192000000 10185712544 10597138432 1254283040 13.278 95 41 81 1 1269137600 5597472 9257088
23:31:29 1887 8192000000 10148392080 10543362048 1238667984 13.134 99 42 84 0 1257037520 9091856 9277680
23:32:34 6101 8192000000 10136896896 10537541632 1237458480 13.123 99 32 61 0 1250351520 5906432 6986608
23:33:42 12455 8192000000 10159135392 10550054912 1223407392 12.993 97 15 30 0 1231765440 4850976 3507072
23:34:54 9442 8192000000 10158063056 10569015296 1271598352 13.436 100 38 76 0 1287410416 7181296 8630768
23:36:06 6501 8192000000 10182855344 10592174080 1292621312 13.628 100 28 56 0 1304890896 6152064 6117520
23:37:12 6177 8192000000 10198064256 10612137984 1305164208 13.742 100 30 61 2 1317249024 5024576 7060240
23:38:19 9804 8192000000 10210034112 10629844992 1323914304 13.912 100 10 20 0 1330650832 4684592 2051936
23:39:37 54377 8192000000 10241731584 10663845888 1345344496 14.106 100 46 92 0 1363929360 8141776 10443088
23:42:02 107729 8192000000 10267007984 10691776512 1347020848 14.121 99 33 66 0 1367908016 13362592 7524576
23:44:54 11272 8192000000 10293884384 10714120192 1372691520 14.351 100 46 86 0 1399670976 17141792 9837664
23:46:15 8754 8192000000 10301060608 10723733504 1382270624 14.437 100 51 99 0 1414186752 20533696 11382432
23:47:29 5990 8192000000 10325453424 10745741312 1397036032 14.569 100 59 117 1 1435578752 25081728 13460992
23:48:41 28789 8192000000 10334551168 10766495744 1407347584 14.660 100 39 78 0 1438600224 21874656 9377984
23:50:21 2082 8192000000 10363600528 10778914816 1418553776 14.760 100 83 164 0 1471838704 32922896 20362032
23:51:32 10328 8192000000 10348030864 10775048192 1412423072 14.705 100 51 102 1 1459318928 33799056 13096800
23:52:47 5034 8192000000 10347667632 10774315008 1407232400 14.659 100 55 108 0 1456123216 35362240 13528576
23:54:01 9898 8192000000 10350636864 10775932928 1387200624 14.481 98 48 95 0 1441548240 42328704 12018912
23:55:19 3652 8192000000 10368652512 10782052352 1397253760 14.571 100 68 136 0 1466510928 52419824 16837344
23:56:26 729 8192000000 10364462048 10774077440 1396719136 14.566 100 80 160 0 1467625776 51336736 19569904
23:57:35 2154 8192000000 10342739888 10749227008 1374861200 14.371 100 86 168 1 1443923728 47811984 21250544
23:58:41 1935 8192000000 10344756960 10758451200 1379043056 14.408 99 62 123 2 1442450576 47940752 15466768
23:59:49 8358 8192000000 10331061696 10746142720 1372279680 14.347 100 66 131 0 1439266464 49142416 17844368
00:01:05 1761 8192000000 10355920160 10764337152 1378003712 14.399 100 83 161 0 1452426432 54801792 19620928
00:02:11 4393 8192000000 10331684496 10750750720 1381582688 14.431 100 60 116 0 1433393824 36612384 15198752
00:03:17 17309 8192000000 10318144784 10754723840 1371018720 14.336 99 26 52 0 1416742800 38449040 7275040
00:05:02 39597 8192000000 10283914304 10731532288 1345904416 14.111 99 18 36 0 1390933936 40118224 4911296
00:06:45 53846 8192000000 10254661744 10691993600 1311088608 13.796 97 8 16 0 1329729360 16647984 1992768
00:08:46 58318 8192000000 10291906192 10730725376 1358251344 14.222 99 18 37 0 1377252608 15388304 3612960
00:10:51 39672 8192000000 10291733408 10740862976 1373302064 14.357 99 28 56 0 1390288944 10745680 6241200
00:12:35 126437 8192000000 10282266192 10730344448 1334857584 14.011 96 21 43 0 1347143232 7354224 4931424
00:15:51 59344 8192000000 10292670768 10734292992 1331334192 13.979 96 21 42 0 1346342272 10236432 4771648
00:17:56 45100 8192000000 10288161056 10744889344 1372231040 14.347 100 35 69 0 1396587200 16675584 7680576
00:19:42 58375 8192000000 10291232592 10750832640 1385054224 14.462 100 8 16 0 1403422176 16471648 1896304
00:21:57 119575 8192000000 10296081616 10763943936 1382284544 14.437 99 21 41 0 1400900016 13778384 4837088
00:25:01 64406 8192000000 10310397952 10782785536 1404202368 14.632 100 21 42 1 1426155712 17406832 4546512
00:27:05 87537 8192000000 10310841312 10787278848 1405181808 14.641 100 9 18 0 1418679760 11385408 2112544
00:30:11 44287 8192000000 10324530560 10781085696 1387116576 14.480 98 27 54 0 1406163408 13232832 5814000
00:31:58 43233 8192000000 10303370304 10761216000 1384844736 14.460 99 14 28 0 1405095472 16601488 3649248
00:33:42 29110 8192000000 10317288736 10773098496 1405798496 14.647 100 14 28 1 1415819728 6752448 3268784
00:35:11 20461 8192000000 10328574256 10779435008 1392828736 14.531 99 36 73 1 1410652448 10132656 7691056
00:36:33 84930 8192000000 10310381904 10768674816 1367554784 14.305 97 15 30 0 1384154400 13039968 3559648
00:39:03 29600 8192000000 10292580096 10734772224 1327084704 13.941 95 34 68 0 1344645552 10070432 7490416
00:40:39 140673 8192000000 10273598256 10740928512 1333923344 14.003 97 21 42 0 1355151632 17172992 4055296
00:44:00 60017 8192000000 10264176480 10720301056 1320154160 13.878 97 29 58 0 1344193056 18034272 6004624
00:46:00 88910 8192000000 10243279904 10694193152 1317803776 13.857 97 8 16 0 1330901344 11412464 1685104
00:48:29 98459 8192000000 10271782624 10721026048 1341134112 14.068 98 17 34 0 1354173312 9159872 3879328
00:51:08 63338 8192000000 10265987040 10723926016 1342700512 14.082 98 9 18 0 1352945248 8418272 1826464
00:53:24 33828 8192000000 10263814992 10706767872 1343232784 14.087 99 36 70 0 1363019408 11587104 8199520
00:55:03 16814 8192000000 10257948176 10695389184 1332459424 13.989 99 41 82 0 1348875696 7956032 8460240
00:56:22 78041 8192000000 10272941088 10716160000 1344435424 14.097 99 23 46 0 1361569008 12141408 4992176
00:58:50 39007 8192000000 10286035056 10730725376 1353690944 14.181 99 23 46 0 1367349872 8679968 4978960
01:00:29 64627 8192000000 10271752128 10710200320 1304125744 13.733 96 25 50 0 1327729920 17670720 5933456
01:02:39 58447 8192000000 10269981072 10709438464 1301572384 13.710 96 8 16 1 1320176560 17115248 1488928
01:04:41 114395 8192000000 10252154640 10703740928 1341096608 14.067 99 42 82 2 1361680704 11303680 9280416
01:07:36 66393 8192000000 10254223264 10717884416 1342884512 14.083 99 5 10 0 1355512720 11465504 1162704
01:10:00 68326 8192000000 10276888272 10733662208 1371423216 14.340 100 11 22 0 1386378288 12698672 2256400
01:12:09 42500 8192000000 10302457760 10760466432 1391343584 14.518 100 17 32 0 1404025968 9236128 3446256
01:13:52 60627 8192000000 10307083872 10769096704 1375853344 14.379 98 16 30 0 1394267472 14866416 3547712
01:15:53 132602 8192000000 10314455152 10765832192 1357047072 14.211 97 11 22 0 1373313104 13855872 2410160
01:19:35 44628 8192000000 10316159696 10776514560 1387159840 14.481 99 27 54 1 1407701536 14277536 6264160
01:21:51 9841 8192000000 10315160080 10760273920 1387236352 14.481 99 43 86 0 1406639088 10347968 9054768
01:23:02 18051 8192000000 10309202304 10756087808 1356445648 14.205 97 44 86 1 1380685344 14684176 9555520
01:24:21 75563 8192000000 10274232528 10728992768 1310158432 13.788 94 18 36 2 1327649888 13082832 4408624
01:26:38 59340 8192000000 10274535584 10727161856 1328726560 13.956 96 15 30 0 1338440080 6847728 2865792
01:28:41 22180 8192000000 10286103664 10730598400 1358608160 14.225 99 43 84 1 1379936912 12270032 9058720
01:30:16 43870 8192000000 10271844864 10736685056 1370174528 14.329 100 9 18 0 1386397184 13904848 2317808
01:32:06 45423 8192000000 10253705168 10711302144 1338688352 14.046 98 19 36 1 1353267472 10529248 4049872
01:33:54 81659 8192000000 10272002336 10738454528 1343500208 14.089 98 16 32 1 1368552544 20874656 4177680
01:36:25 89723 8192000000 10251792944 10710241280 1287436208 13.581 94 12 22 0 1302940992 13360272 2144512

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 31-12-2022 01:39:57 to 31-12-2022 01:40:12.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 153 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 31-12-2022 01:40:13 to 31-12-2022 01:40:15.

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          240160
MON$OLDEST_ACTIVE               241549
MON$OLDEST_SNAPSHOT             241549
MON$NEXT_TRANSACTION            241549
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                       2027360
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.33650 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33650 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33650 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33650 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 ---
--- end of diff output ---

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

31.12.22 01:40:16. 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
2022-12-31 01:40:19 No severe PSQL-related problems occured
31.12.22 01:40:20. End of report.