Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 30.12.2022 16:38
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 14787032 kB
MemAvailable 30383000 kB
Buffers 0 kB
Cached 16876960 kB
SwapCached 11600 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-30 16:38:33
DTS_END                         2022-12-30 16:38:33.1180
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/39394
TRN_ID                          249225
ATT_ID                          658
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: 10040.49 22188 1204859
customer order: creation 181.54 338 21785
customer order: refuse 15.56 343 1867
order to supplier: creation 48.66 1301 5839
order to supplier: removal 3.42 116706 410
invoice (draft): creation 48.48 3240 5817
invoice (draft): removal 57.93 785 6952
invoice accept: apply 44.18 16620 5301
invoice accept: cancel 52.27 814 6272
customer reserve: creation 4155.72 161 498686
customer reserve: removal 5109.12 62 613094
realization accept: apply 88.52 182 10622
realization accept: cancel 96.96 104 11635
payment from customer: creation 63.21 114 7585
payment from customer: removal 6.46 67 775
payment to supplier: creation 52.02 126 6242
payment to supplier: removal 3.81 76 457
service: total inventory turnovers 5.03 1248 604
service: total monetary turnovers 6.78 55 814
service: aggregate perf. data 0.82 25381 98
service: refresh index statistics 0.03 276028 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 30-12-2022 16:39:14 to 30-12-2022 16:39:16.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4490
TEST_TIME 2 4509
TEST_TIME 3 4178
TEST_TIME 4 3636
TEST_TIME 5 4264
TEST_TIME 6 4842
TEST_TIME 7 5119
TEST_TIME 8 4934
TEST_TIME 9 5668
TEST_TIME 10 5767
TEST_TIME 11 6287
TEST_TIME 12 6627
TEST_TIME 13 6606
TEST_TIME 14 6981
TEST_TIME 15 7161
TEST_TIME 16 7175
TEST_TIME 17 7211
TEST_TIME 18 7314
TEST_TIME 19 7508
TEST_TIME 20 7978
TEST_TIME 21 8075
TEST_TIME 22 8347
TEST_TIME 23 8458
TEST_TIME 24 8446
TEST_TIME 25 8535
TEST_TIME 26 8902
TEST_TIME 27 8867
TEST_TIME 28 9468
TEST_TIME 29 9317
TEST_TIME 30 9796
TEST_TIME 31 11487
TEST_TIME 32 11394
TEST_TIME 33 11433
TEST_TIME 34 11423
TEST_TIME 35 11323
TEST_TIME 36 11333
TEST_TIME 37 11419
TEST_TIME 38 11419
TEST_TIME 39 11231
TEST_TIME 40 11289
TEST_TIME 41 11244
TEST_TIME 42 11004
TEST_TIME 43 10959
TEST_TIME 44 10952
TEST_TIME 45 10858
TEST_TIME 46 10862
TEST_TIME 47 10758
TEST_TIME 48 10631
TEST_TIME 49 10647
TEST_TIME 50 10539
TEST_TIME 51 10581
TEST_TIME 52 10639
TEST_TIME 53 10608
TEST_TIME 54 10525
TEST_TIME 55 10483
TEST_TIME 56 10380
TEST_TIME 57 10363
TEST_TIME 58 10306
TEST_TIME 59 10260
TEST_TIME 60 10213
TEST_TIME 61 10145
TEST_TIME 62 10160
TEST_TIME 63 10170
TEST_TIME 64 10176
TEST_TIME 65 10239
TEST_TIME 66 10258
TEST_TIME 67 10194
TEST_TIME 68 10286
TEST_TIME 69 10216
TEST_TIME 70 10281
TEST_TIME 71 10282
TEST_TIME 72 10281
TEST_TIME 73 10354
TEST_TIME 74 10362
TEST_TIME 75 10336
TEST_TIME 76 10224
TEST_TIME 77 10186
TEST_TIME 78 10123
TEST_TIME 79 10123
TEST_TIME 80 10105
TEST_TIME 81 10046
TEST_TIME 82 10002
TEST_TIME 83 10023
TEST_TIME 84 9952
TEST_TIME 85 9978
TEST_TIME 86 9976
TEST_TIME 87 9956
TEST_TIME 88 9959
TEST_TIME 89 10003
TEST_TIME 90 10019
TEST_TIME 91 10043
TEST_TIME 92 10053
TEST_TIME 93 10090
TEST_TIME 94 10106
TEST_TIME 95 10221
TEST_TIME 96 10179
TEST_TIME 97 10274
TEST_TIME 98 10293
TEST_TIME 99 10252
TEST_TIME 100 10175
TEST_TIME 101 10159
TEST_TIME 102 10108
TEST_TIME 103 10072
TEST_TIME 104 10118
TEST_TIME 105 10095
TEST_TIME 106 10044
TEST_TIME 107 10053
TEST_TIME 108 9998
TEST_TIME 109 9921
TEST_TIME 110 10001
TEST_TIME 111 9976
TEST_TIME 112 9976
TEST_TIME 113 10009
TEST_TIME 114 10037
TEST_TIME 115 10045
TEST_TIME 116 10046
TEST_TIME 117 10020
TEST_TIME 118 10040
TEST_TIME 119 10062
TEST_TIME 120 10043

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 30-12-2022 16:39:18 to 30-12-2022 16:39:21.

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 1665958 1665958 0 0.00 0 16129 6 0 0
doc_list_biud 1665956 1665956 0 0.00 0 22003 29 0 0
sp_add_invoice_to_stock 5419 5301 118 2.17 37 164157 16621 0 118
sp_cancel_adding_invoice 6272 6272 0 0.00 4 58551 814 0 0
sp_cancel_client_order 1867 1867 0 0.00 4 16611 343 0 0
sp_cancel_customer_reserve 613094 613094 0 0.00 0 22006 62 0 0
sp_cancel_pay_from_customer 775 775 0 0.00 2 4823 68 0 0
sp_cancel_pay_to_supplier 457 457 0 0.00 3 5189 77 0 0
sp_cancel_supplier_invoice 6966 6952 14 0.20 1 16601 785 0 14
sp_cancel_supplier_order 410 410 0 0.00 11 1059954 116707 0 0
sp_cancel_write_off 11635 11635 0 0.00 1 10760 105 0 0
sp_client_order 21785 21785 0 0.00 3 31291 338 0 0
sp_customer_reserve 498686 498686 0 0.00 0 55700 162 0 0
sp_fill_shopping_cart 33438 33438 0 0.00 1 16164 36 0 0
sp_fill_shopping_cart_clo_res 498678 498678 0 0.00 0 11175 12 0 0
sp_get_clo_for_invoice 5292 5292 0 0.00 0 21782 246 0 0
sp_kill_cost_storno 19139 19139 0 0.00 0 7412 52 0 0
sp_kill_qstorno_ret_qs2qd 1245185 1245185 0 0.00 0 21906 33 0 0
sp_kill_qty_storno 656144 656144 0 0.00 0 22003 72 0 0
sp_lock_selected_doc 22050 22050 0 0.00 0 5791 6 0 0
sp_make_cost_storno 29741 29741 0 0.00 0 15693 52 0 0
sp_multiply_rows_for_pdistr 29741 29741 0 0.00 0 14703 27 0 0
sp_multiply_rows_for_qdistr 21785 21785 0 0.00 0 31283 137 0 0
sp_pay_from_customer 7585 7585 0 0.00 4 16132 114 0 0
sp_pay_to_supplier 6242 6242 0 0.00 4 15742 126 0 0
sp_payment_common 13827 13827 0 0.00 1 14177 27 0 0
sp_qd_handle_on_cancel_clo 1867 1867 0 0.00 0 16604 318 0 0
sp_qd_handle_on_invoice_upd_sts 11564 11564 0 0.00 0 15747 319 0 0
sp_qd_handle_on_reserve_upd_sts 10622 10622 0 0.00 0 16219 63 0 0
sp_reserve_write_off 10622 10622 0 0.00 6 16371 182 0 0
sp_supplier_invoice 5817 5817 0 0.00 5 91103 3241 0 0
sp_supplier_order 5839 5839 0 0.00 5 29224 1302 0 0
srv_aggregate_perf_data 98 98 0 0.00 2624 686451 25381 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 1367 112712 40468 0 0
srv_find_qd_qs_mism 1153524 1153524 0 0.00 0 9973 5 0 0
srv_make_invnt_saldo 604 604 0 0.00 45 115591 1248 0 0
srv_make_money_saldo 814 814 0 0.00 0 17962 56 0 0
srv_recalc_idx_stat 4 4 0 0.00 121621 468586 276028 0 0
t$perf-abend:sp_add_invoice_to_stock 118 118 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5301 5301 0 0.00 0 26521 172 0 0
t$perf-norm:sp_cancel_adding_invoice 440 440 0 0.00 0 2288 77 0 0
t$perf-norm:sp_cancel_client_order 1867 1867 0 0.00 0 2306 12 0 0
t$perf-norm:sp_cancel_customer_reserve 708 708 0 0.00 0 842 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 775 775 0 0.00 0 753 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 457 457 0 0.00 0 8359 34 0 0
t$perf-norm:sp_cancel_supplier_invoice 428 428 0 0.00 0 587 10 0 0
t$perf-norm:sp_cancel_supplier_order 410 410 0 0.00 0 28005 1657 0 0
t$perf-norm:sp_cancel_write_off 1052 1052 0 0.00 0 2067 13 0 0
t$perf-norm:sp_client_order 21785 21785 0 0.00 0 11758 20 0 0
t$perf-norm:sp_customer_reserve 1066 1066 0 0.00 0 1811 6 0 0
t$perf-norm:sp_pay_from_customer 7585 7585 0 0.00 0 9905 12 0 0
t$perf-norm:sp_pay_to_supplier 6242 6242 0 0.00 0 8291 8 0 0
t$perf-norm:sp_reserve_write_off 10622 10622 0 0.00 0 8243 16 0 0
t$perf-norm:sp_supplier_invoice 5817 5817 0 0.00 0 7976 19 0 0
t$perf-norm:sp_supplier_order 5839 5839 0 0.00 0 6409 13 0 0
t$perf-norm:srv_aggregate_perf_data 98 98 0 0.00 0 193 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 12331 187 0 0
t$perf-norm:srv_make_invnt_saldo 604 604 0 0.00 0 1051 22 0 0
t$perf-norm:srv_make_money_saldo 814 814 0 0.00 0 1156 19 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 348 89 0 0
v_add_invoice_to_stock 10816 10580 236 2.18 0 6874 22 0 236
v_all_customers 39452 39452 0 0.00 0 30 1 0 0
v_all_suppliers 32380 32380 0 0.00 0 83 1 0 0
v_all_wares 43570 43570 0 0.00 0 25 1 0 0
v_cancel_adding_invoice 880 880 0 0.00 0 2006 10 0 0
v_cancel_client_order 3734 3734 0 0.00 0 4142 6 0 0
v_cancel_customer_prepayment 1550 1550 0 0.00 0 4816 9 0 0
v_cancel_customer_reserve 1416 1416 0 0.00 0 3714 8 0 0
v_cancel_payment_to_supplier 914 914 0 0.00 0 2034 10 0 0
v_cancel_supplier_invoice 884 856 28 3.16 0 2642 17 0 28
v_cancel_supplier_order 820 820 0 0.00 0 4053 17 0 0
v_cancel_write_off 2104 2104 0 0.00 0 3525 19 0 0
v_max_id_clo_ord 5838 5838 0 0.00 0 5788 3 0 0
v_max_id_clo_res 1066 1066 0 0.00 0 1232 10 0 0
v_max_id_ord_sup 5815 5815 0 0.00 0 4487 5 0 0
v_max_non_paid_invoice 1705 1705 0 0.00 0 874 3 0 0
v_max_non_paid_realizn 4014 4014 0 0.00 0 3619 5 0 0
v_min_id_clo_ord 5838 5838 0 0.00 0 9581 10 0 0
v_min_id_clo_res 1066 1066 0 0.00 0 4213 9 0 0
v_min_id_ord_sup 5815 5815 0 0.00 0 5430 6 0 0
v_min_non_paid_invoice 6242 6242 0 0.00 0 14164 20 0 0
v_min_non_paid_realizn 7585 7585 0 0.00 0 14168 20 0 0
v_our_firm 11260 11260 0 0.00 0 22 1 0 0
v_reserve_write_off 21244 21244 0 0.00 0 4816 7 0 0
x_lock_depdocs_on_canc_invoice 12796 12796 0 0.00 0 22710 119 0 0
x_lock_depdocs_on_canc_sup_ord 410 410 0 0.00 0 26763 434 0 0
x_make_qstorno_1000_1200 5839 5839 0 0.00 1 29213 1217 0 0
x_make_qstorno_1200_2000 5817 5817 0 0.00 3 91071 3120 0 0
x_make_qstorno_2100_3300 498686 498686 0 0.00 0 55699 147 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 30-12-2022 16:39:22 to 30-12-2022 16:39:26.

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:36 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:09:38 29597 8192000000 9902908720 10214817792 981845152 10.702 100 89 178 0 1011788928 6078832 23864944
14:11:27 23036 8192000000 9955926208 10279018496 1034607376 11.213 100 89 178 0 1065790016 8207600 22975040
14:12:56 10839 8192000000 9995424976 10328993792 1077857360 11.627 100 91 181 1 1109859008 10052080 21949568
14:14:13 13619 8192000000 10037298272 10388799488 1123881344 12.064 100 79 158 0 1153707424 11246192 18579888
14:15:31 3564 8192000000 10090023328 10447110144 1169779808 12.495 100 82 162 1 1200916160 12511504 18624848
14:16:39 3898 8192000000 10125774416 10496290816 1216239728 12.927 100 72 143 0 1244209312 12553712 15415872
14:17:45 2025 8192000000 10160039600 10534473728 1247137552 13.212 100 79 156 0 1278002272 13507984 17356736
14:18:49 1240 8192000000 10184797792 10563330048 1265143664 13.377 100 85 168 2 1298366688 15251984 17971040
14:19:52 1410 8192000000 10208933376 10595221504 1299121664 13.687 100 77 154 0 1328984992 12731792 17131536
14:20:55 3079 8192000000 10222916240 10615541760 1312691728 13.810 100 76 151 0 1342726784 12800688 17234368
14:22:00 10876 8192000000 10209011152 10614640640 1315135632 13.833 100 40 80 0 1331370016 6998304 9236080
14:23:14 5375 8192000000 10211911920 10622672896 1323012656 13.904 100 30 60 0 1336449024 6978256 6458112
14:24:20 13046 8192000000 10214789776 10633433088 1331407504 13.980 100 13 26 0 1340188768 6063184 2718080
14:25:39 11980 8192000000 10221331136 10642280448 1338646496 14.045 100 18 36 0 1348426496 5318928 4461072
14:26:52 15224 8192000000 10254715072 10658820096 1343858672 14.092 100 75 152 0 1368887536 5546208 19482656
14:28:08 4800 8192000000 10215234592 10634588160 1314703888 13.829 99 28 56 2 1326614464 5408544 6502032
14:29:15 4915 8192000000 10184824736 10593562624 1275692928 13.474 98 28 57 0 1288837760 6633872 6510960
14:30:20 11724 8192000000 10161199792 10550464512 1224861104 13.007 96 39 68 1 1238992688 4277104 9854480
14:31:33 5792 8192000000 10164121488 10568339456 1261278432 13.342 99 31 59 1 1273002528 5061200 6662896
14:32:41 2867 8192000000 10142019888 10533355520 1228268800 13.038 98 36 72 0 1243664832 7325248 8070784
14:33:46 8323 8192000000 10147561440 10551652352 1248776128 13.227 99 29 58 0 1262020400 5564256 7680016
14:34:55 17054 8192000000 10178407664 10589966336 1288327904 13.589 100 22 44 0 1300439456 5694512 6417040
14:36:13 15974 8192000000 10185315312 10599370752 1296753632 13.666 100 28 57 0 1308877968 5717632 6406704
14:37:32 8793 8192000000 10197353984 10611265536 1306184096 13.751 100 17 34 0 1314619856 4917792 3517968
14:38:45 18308 8192000000 10238278288 10645622784 1330527984 13.972 100 46 91 1 1347066416 6152736 10385696
14:40:16 13908 8192000000 10266599120 10678251520 1356709952 14.208 100 52 105 0 1379622608 10490992 12421664
14:41:36 104769 8192000000 10288592816 10712862720 1374451728 14.367 100 44 88 1 1405851152 21078864 10320560
14:44:29 10107 8192000000 10291771680 10715291648 1375450752 14.376 100 41 82 0 1404689184 19781280 9457152
14:45:43 12984 8192000000 10311637152 10739867648 1386331552 14.473 100 37 73 0 1421728288 26533392 8863344
14:47:02 3036 8192000000 10331399264 10742915072 1388022800 14.488 100 76 150 4 1434521600 28525744 17973056
14:48:11 10655 8192000000 10329779392 10752876544 1399736192 14.593 100 67 134 2 1443858848 27461488 16661168
14:49:26 11039 8192000000 10321686352 10751168512 1376137920 14.382 98 48 96 0 1421695056 33687216 11869920
14:50:43 14216 8192000000 10327341552 10757169152 1402259280 14.615 100 41 78 0 1439414064 27794928 9359856
14:52:02 1960 8192000000 10346079344 10776178688 1410275920 14.686 100 60 119 2 1455217968 29494704 15447344
14:53:31 2774 8192000000 10347995696 10767523840 1400896752 14.603 100 68 136 1 1449134928 31983648 16254528
14:54:38 1366 8192000000 10352977744 10764193792 1396291088 14.562 100 79 158 1 1457110240 41611280 19207872
14:55:43 6875 8192000000 10338917488 10746548224 1377868736 14.397 100 76 147 0 1442178400 45276448 19033216
14:56:54 1928 8192000000 10353678624 10760966144 1385837072 14.469 100 67 132 1 1457572272 55359344 16375856
14:58:01 2443 8192000000 10368984688 10783875072 1398218592 14.579 100 72 144 0 1472889936 56315616 18355728
14:59:13 3096 8192000000 10351286336 10761936896 1386860576 14.478 100 79 158 2 1458185856 49952416 21372864
15:00:22 1823 8192000000 10370971744 10773823488 1390046848 14.506 100 76 149 2 1468373056 58907216 19418992
15:01:29 9942 8192000000 10364556944 10784997376 1400672096 14.601 100 44 88 0 1468415584 55513808 12229680
15:02:42 6183 8192000000 10389429600 10809749504 1402564704 14.618 99 56 112 1 1487608048 70031376 15011968
15:03:54 6356 8192000000 10380666224 10801762304 1401971840 14.613 100 44 88 0 1484150832 69440976 12738016
15:05:06 15997 8192000000 10366130992 10802065408 1398047840 14.578 100 39 78 0 1471123248 62308496 10766912
15:06:31 9480 8192000000 10352528544 10812153856 1390541216 14.511 100 33 66 0 1475585936 75489280 9555440
15:07:54 12624 8192000000 10335272928 10773975040 1349574208 14.144 100 24 47 0 1436699856 79459872 7665776
15:09:08 30153 8192000000 10315366304 10776080384 1364372096 14.277 100 16 32 0 1429920000 60947088 4600816
15:10:39 78398 8192000000 10295514752 10753777664 1370363920 14.330 99 4 8 0 1384699024 13240608 1094496
15:13:09 65943 8192000000 10313636224 10760261632 1359834880 14.236 97 14 28 1 1376586624 14374816 2376928
15:15:17 94105 8192000000 10318311408 10774753280 1390057120 14.506 99 13 27 0 1409139504 15872272 3210112
15:18:24 88059 8192000000 10277453504 10734481408 1326464912 13.935 95 13 26 0 1339312432 10207856 2639664
15:20:56 14090 8192000000 10308827520 10746085376 1376755776 14.388 99 71 138 3 1398849456 5702544 16391136
15:22:11 61079 8192000000 10307133856 10765496320 1394730640 14.548 100 26 52 0 1410856880 10716016 5410224
15:24:14 11963 8192000000 10296410176 10748940288 1391792416 14.522 100 51 101 3 1411313312 7895456 11625440
15:25:40 47353 8192000000 10310129440 10750857216 1390504000 14.510 100 28 54 0 1403852208 7436240 5911968
15:27:30 40122 8192000000 10298598672 10754326528 1367918672 14.308 98 28 56 0 1389696592 14831376 6946544
15:29:10 25618 8192000000 10294135984 10739240960 1380187296 14.418 100 49 96 1 1406158336 14235296 11735744
15:30:51 38090 8192000000 10285141120 10745683968 1382796528 14.442 100 11 22 1 1396103424 10830480 2476416
15:32:32 29660 8192000000 10272023184 10725638144 1342862144 14.083 97 25 50 0 1359215152 10413024 5939984
15:34:04 45096 8192000000 10283700240 10735185920 1342030624 14.076 97 12 23 1 1359115264 14405200 2679440
15:36:01 89688 8192000000 10261426752 10728955904 1362690448 14.262 99 16 32 0 1378973600 12350960 3932192
15:38:33 42728 8192000000 10261621280 10719707136 1352080928 14.166 99 18 34 1 1367579680 11986016 3512736
15:40:16 78943 8192000000 10255603680 10729414656 1314141392 13.824 96 7 14 0 1334576688 19085280 1350016
15:42:36 31175 8192000000 10284455408 10737152000 1357749408 14.217 98 20 41 0 1378699536 16343136 4606992
15:44:33 15680 8192000000 10267592944 10701832192 1332620736 13.991 99 52 104 0 1358896752 13744048 12531968
15:45:50 45936 8192000000 10258999520 10712154112 1344380112 14.097 99 24 48 0 1360470480 10543776 5546592
15:47:43 43894 8192000000 10257020048 10711056384 1337149856 14.032 99 20 41 0 1357727344 15980496 4596992
15:49:31 39406 8192000000 10266193664 10720514048 1351596432 14.162 99 11 22 0 1365734128 11612176 2525520
15:51:12 28300 8192000000 10274619872 10726105088 1332507216 13.990 98 25 50 0 1357687296 19639440 5540640
15:52:42 100804 8192000000 10243472096 10699542528 1316288512 13.843 97 10 20 0 1329534432 10954800 2291120
15:55:47 74776 8192000000 10262526736 10712260608 1350025584 14.148 99 34 69 0 1366288336 8685568 7577184
15:58:03 54606 8192000000 10257607376 10716778496 1361307312 14.249 100 14 28 0 1372554208 8530992 2715904
16:00:03 58767 8192000000 10278039056 10736340992 1381768960 14.432 100 11 22 1 1394674016 10072704 2832352
16:02:04 74526 8192000000 10270597696 10736058368 1373329216 14.357 100 12 24 0 1386223520 10192160 2702144
16:05:15 32803 8192000000 10284946768 10728767488 1348013968 14.130 98 29 58 1 1367235728 12527920 6693840
16:06:50 61543 8192000000 10285484592 10738040832 1369790656 14.325 100 21 42 0 1388834208 14611520 4432032
16:08:53 52912 8192000000 10291569552 10745528320 1377619376 14.395 99 10 20 0 1389910320 10246912 2044032
16:10:46 53580 8192000000 10306562848 10751447040 1360424288 14.241 98 36 70 2 1378193312 10227936 7541088
16:12:41 92352 8192000000 10273573792 10733563904 1275148048 13.469 92 11 22 0 1297373232 19886048 2339136
16:15:14 63643 8192000000 10287600752 10748383232 1348134992 14.131 98 5 10 0 1366599712 17535408 929312
16:17:27 112712 8192000000 10259774432 10707685376 1327225824 13.942 96 21 42 2 1342433024 10177728 5029472
16:20:25 36416 8192000000 10267442352 10728726528 1361124832 14.247 100 10 20 0 1375526384 12332448 2069104
16:22:03 45962 8192000000 10259746464 10725457920 1350916192 14.156 98 22 44 0 1367448448 11379232 5153024
16:24:35 76447 8192000000 10264684768 10730102784 1358847920 14.227 99 15 30 0 1371240816 9441216 2951680
16:26:52 99924 8192000000 10288215376 10751709184 1368920608 14.317 99 13 26 0 1386023664 13944992 3158064
16:29:32 52108 8192000000 10285985040 10742059008 1357179888 14.212 98 18 36 0 1377134192 15973760 3980544
16:31:24 93245 8192000000 10238714560 10695380992 1246778112 13.209 91 16 32 0 1268054240 17688928 3587200
16:34:01 43915 8192000000 10269039104 10708324352 1315109072 13.832 97 41 83 1 1334257824 10150352 8998400
16:35:45 22954 8192000000 10259779120 10696224768 1321613456 13.891 97 40 80 0 1341224832 10729024 8882352
16:37:11 39956 8192000000 10270908832 10720776192 1354483808 14.188 100 15 30 0 1373993888 16177008 3333072

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 30-12-2022 16:39:28 to 30-12-2022 16:39:44.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 118 v_add_invoice_to_stock 335544517
except 14 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 30-12-2022 16:39:45 to 30-12-2022 16:39:47.

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          248950
MON$OLDEST_ACTIVE               250602
MON$OLDEST_SNAPSHOT             250602
MON$NEXT_TRANSACTION            250602
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                       2088608
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 ---
:1416: 
:1417: mscs-test-161.budzdorov.ru    Fri Dec 30 16:38:34 2022
:1418:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1419: 
:1420: 
:1421: mscs-test-161.budzdorov.ru    Fri Dec 30 16:38:35 2022
:1422:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1423: 
:1424: 
:1425: mscs-test-161.budzdorov.ru    Fri Dec 30 16:38:35 2022
:1426:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1427: 
:1428: 
:1429: mscs-test-161.budzdorov.ru    Fri Dec 30 16:38:37 2022
:1430:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1431: 
--- end of diff output ---

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

30.12.22 16:39:48. 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-30 16:39:50 No severe PSQL-related problems occured
30.12.22 16:39:52. End of report.