Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 24.02.2023 22: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 15467604 kB
MemAvailable 30426660 kB
Buffers 0 kB
Cached 16264988 kB
SwapCached 3140 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 3.0.11
DB_NAME                         /home/bases/oltp30-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3333
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp30-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp30
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2023-02-24 22:38:39
DTS_END                         2023-02-24 22:38:39.1380
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_PAY_FROM_CUSTOMER(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/56246
TRN_ID                          231343
ATT_ID                          694
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: 9169.86 23679 1100383
customer order: creation 162.89 350 19547
customer order: refuse 13.42 341 1610
order to supplier: creation 43.90 1231 5268
order to supplier: removal 3.46 101900 415
invoice (draft): creation 43.93 3225 5272
invoice (draft): removal 53.88 750 6466
invoice accept: apply 39.33 17809 4720
invoice accept: cancel 48.37 770 5804
customer reserve: creation 3708.34 174 445001
customer reserve: removal 4767.42 58 572090
realization accept: apply 78.70 169 9444
realization accept: cancel 82.15 105 9858
payment from customer: creation 57.33 125 6880
payment from customer: removal 5.50 80 660
payment to supplier: creation 47.12 135 5654
payment to supplier: removal 3.13 109 376
service: total inventory turnovers 4.04 1074 485
service: total monetary turnovers 6.14 62 737
service: aggregate perf. data 0.77 22853 92
service: refresh index statistics 0.03 322250 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 24-02-2023 22:40:03 to 24-02-2023 22:40:05.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3482
TEST_TIME 2 4733
TEST_TIME 3 4894
TEST_TIME 4 5223
TEST_TIME 5 5041
TEST_TIME 6 5406
TEST_TIME 7 5280
TEST_TIME 8 5707
TEST_TIME 9 6055
TEST_TIME 10 5999
TEST_TIME 11 6230
TEST_TIME 12 6419
TEST_TIME 13 6553
TEST_TIME 14 6341
TEST_TIME 15 6557
TEST_TIME 16 7009
TEST_TIME 17 7071
TEST_TIME 18 7051
TEST_TIME 19 7918
TEST_TIME 20 7872
TEST_TIME 21 7849
TEST_TIME 22 8033
TEST_TIME 23 8238
TEST_TIME 24 8686
TEST_TIME 25 8943
TEST_TIME 26 10518
TEST_TIME 27 10597
TEST_TIME 28 10465
TEST_TIME 29 10161
TEST_TIME 30 10150
TEST_TIME 31 10214
TEST_TIME 32 10071
TEST_TIME 33 9853
TEST_TIME 34 9738
TEST_TIME 35 9796
TEST_TIME 36 9624
TEST_TIME 37 9595
TEST_TIME 38 9467
TEST_TIME 39 9345
TEST_TIME 40 9344
TEST_TIME 41 9316
TEST_TIME 42 9173
TEST_TIME 43 9169
TEST_TIME 44 9182
TEST_TIME 45 9069
TEST_TIME 46 9083
TEST_TIME 47 9159
TEST_TIME 48 9160
TEST_TIME 49 9215
TEST_TIME 50 9315
TEST_TIME 51 9328
TEST_TIME 52 9302
TEST_TIME 53 9370
TEST_TIME 54 9333
TEST_TIME 55 9351
TEST_TIME 56 9484
TEST_TIME 57 9518
TEST_TIME 58 9406
TEST_TIME 59 9371
TEST_TIME 60 9257
TEST_TIME 61 9222
TEST_TIME 62 9319
TEST_TIME 63 9258
TEST_TIME 64 9185
TEST_TIME 65 9119
TEST_TIME 66 9206
TEST_TIME 67 9227
TEST_TIME 68 9223
TEST_TIME 69 9201
TEST_TIME 70 9280
TEST_TIME 71 9295
TEST_TIME 72 9268
TEST_TIME 73 9350
TEST_TIME 74 9441
TEST_TIME 75 9427
TEST_TIME 76 9385
TEST_TIME 77 9535
TEST_TIME 78 9556
TEST_TIME 79 9586
TEST_TIME 80 9500
TEST_TIME 81 9409
TEST_TIME 82 9429
TEST_TIME 83 9383
TEST_TIME 84 9388
TEST_TIME 85 9373
TEST_TIME 86 9333
TEST_TIME 87 9287
TEST_TIME 88 9282
TEST_TIME 89 9270
TEST_TIME 90 9251
TEST_TIME 91 9220
TEST_TIME 92 9172
TEST_TIME 93 9208
TEST_TIME 94 9179
TEST_TIME 95 9174
TEST_TIME 96 9216
TEST_TIME 97 9194
TEST_TIME 98 9228
TEST_TIME 99 9254
TEST_TIME 100 9300
TEST_TIME 101 9298
TEST_TIME 102 9284
TEST_TIME 103 9319
TEST_TIME 104 9395
TEST_TIME 105 9369
TEST_TIME 106 9415
TEST_TIME 107 9457
TEST_TIME 108 9411
TEST_TIME 109 9416
TEST_TIME 110 9355
TEST_TIME 111 9355
TEST_TIME 112 9341
TEST_TIME 113 9304
TEST_TIME 114 9280
TEST_TIME 115 9264
TEST_TIME 116 9204
TEST_TIME 117 9193
TEST_TIME 118 9231
TEST_TIME 119 9209
TEST_TIME 120 9177

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 24-02-2023 22:40:07 to 24-02-2023 22:40:10.

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 1512913 1512913 0 0.00 0 24444 6 0 0
doc_list_biud 1512913 1512913 0 0.00 0 25519 27 0 0
sp_add_invoice_to_stock 4844 4720 124 2.55 44 164417 17809 0 124
sp_cancel_adding_invoice 5804 5804 0 0.00 2 89943 770 0 0
sp_cancel_client_order 1610 1610 0 0.00 4 8699 341 0 0
sp_cancel_customer_reserve 572090 572090 0 0.00 0 25523 58 0 0
sp_cancel_pay_from_customer 660 660 0 0.00 2 7946 81 0 0
sp_cancel_pay_to_supplier 376 376 0 0.00 3 6092 110 0 0
sp_cancel_supplier_invoice 6481 6466 15 0.23 2 15635 750 0 15
sp_cancel_supplier_order 415 415 0 0.00 7 1078962 101900 0 0
sp_cancel_write_off 9858 9858 0 0.00 1 13473 105 0 0
sp_client_order 19547 19547 0 0.00 3 31204 351 0 0
sp_customer_reserve 445001 445001 0 0.00 0 87357 174 0 0
sp_fill_shopping_cart 30084 30084 0 0.00 1 17776 39 0 0
sp_fill_shopping_cart_clo_res 444991 444991 0 0.00 0 14400 13 0 0
sp_get_clo_for_invoice 4709 4709 0 0.00 0 19969 324 0 0
sp_kill_cost_storno 16698 16698 0 0.00 0 9145 53 0 0
sp_kill_qstorno_ret_qs2qd 1160919 1160919 0 0.00 0 25496 31 0 0
sp_kill_qty_storno 610396 610396 0 0.00 0 25519 67 0 0
sp_lock_selected_doc 19585 19585 0 0.00 0 6708 5 0 0
sp_make_cost_storno 26687 26687 0 0.00 0 13611 57 0 0
sp_multiply_rows_for_pdistr 26687 26687 0 0.00 0 9589 27 0 0
sp_multiply_rows_for_qdistr 19547 19547 0 0.00 0 17012 147 0 0
sp_pay_from_customer 6880 6880 0 0.00 4 16304 126 0 0
sp_pay_to_supplier 5654 5654 0 0.00 4 18068 135 0 0
sp_payment_common 12534 12534 0 0.00 1 13006 34 0 0
sp_qd_handle_on_cancel_clo 1610 1610 0 0.00 0 8691 306 0 0
sp_qd_handle_on_invoice_upd_sts 10513 10513 0 0.00 0 24418 307 0 0
sp_qd_handle_on_reserve_upd_sts 9444 9444 0 0.00 0 16702 54 0 0
sp_reserve_write_off 9444 9444 0 0.00 6 17352 170 0 0
sp_supplier_invoice 5272 5272 0 0.00 4 80344 3226 0 0
sp_supplier_order 5268 5268 0 0.00 4 31568 1231 0 0
srv_aggregate_perf_data 93 92 1 1.07 2712 576409 22853 0 1
srv_fill_mon_cache_memory 62 62 0 0.00 1314 148316 47651 0 0
srv_find_qd_qs_mism 1054872 1054872 0 0.00 0 24442 5 0 0
srv_make_invnt_saldo 485 485 0 0.00 20 35323 1075 0 0
srv_make_money_saldo 737 737 0 0.00 1 15934 62 0 0
srv_recalc_idx_stat 4 4 0 0.00 122591 519700 322251 0 0
t$perf-abend:sp_add_invoice_to_stock 124 124 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 15 15 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4720 4720 0 0.00 0 22604 185 0 0
t$perf-norm:sp_cancel_adding_invoice 368 368 0 0.00 0 11802 152 0 0
t$perf-norm:sp_cancel_client_order 1610 1610 0 0.00 0 17667 33 0 0
t$perf-norm:sp_cancel_customer_reserve 666 666 0 0.00 0 2953 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 660 660 0 0.00 0 5981 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 376 376 0 0.00 0 2241 23 0 0
t$perf-norm:sp_cancel_supplier_invoice 375 375 0 0.00 0 1670 16 0 0
t$perf-norm:sp_cancel_supplier_order 415 415 0 0.00 0 25091 1578 0 0
t$perf-norm:sp_cancel_write_off 962 962 0 0.00 0 7241 27 0 0
t$perf-norm:sp_client_order 19547 19547 0 0.00 0 11946 19 0 0
t$perf-norm:sp_customer_reserve 906 906 0 0.00 0 5700 22 0 0
t$perf-norm:sp_pay_from_customer 6880 6880 0 0.00 0 10854 14 0 0
t$perf-norm:sp_pay_to_supplier 5654 5654 0 0.00 0 11920 16 0 0
t$perf-norm:sp_reserve_write_off 9444 9444 0 0.00 0 8143 15 0 0
t$perf-norm:sp_supplier_invoice 5272 5272 0 0.00 0 11238 24 0 0
t$perf-norm:sp_supplier_order 5268 5268 0 0.00 0 11569 20 0 0
t$perf-norm:srv_aggregate_perf_data 92 92 0 0.00 0 384 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 70 2 0 0
t$perf-norm:srv_make_invnt_saldo 485 485 0 0.00 0 6570 37 0 0
t$perf-norm:srv_make_money_saldo 737 737 0 0.00 0 5667 23 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 9 4 0 0
v_add_invoice_to_stock 9666 9418 248 2.56 0 7012 16 0 248
v_all_customers 36012 36012 0 0.00 0 20 0 0 0
v_all_suppliers 29438 29438 0 0.00 0 21 1 0 0
v_all_wares 39094 39094 0 0.00 0 51 1 0 0
v_cancel_adding_invoice 736 736 0 0.00 0 319 6 0 0
v_cancel_client_order 3220 3220 0 0.00 0 6450 8 0 0
v_cancel_customer_prepayment 1320 1320 0 0.00 0 1038 5 0 0
v_cancel_customer_reserve 1332 1332 0 0.00 0 6042 7 0 0
v_cancel_payment_to_supplier 752 752 0 0.00 0 6041 11 0 0
v_cancel_supplier_invoice 780 750 30 3.84 0 200 11 0 30
v_cancel_supplier_order 830 830 0 0.00 0 422 4 0 0
v_cancel_write_off 1924 1924 0 0.00 0 5193 15 0 0
v_max_id_clo_ord 5268 5268 0 0.00 0 378 1 0 0
v_max_id_clo_res 906 906 0 0.00 0 7021 28 0 0
v_max_id_ord_sup 5269 5269 0 0.00 0 5744 5 0 0
v_max_non_paid_invoice 1472 1472 0 0.00 0 667 2 0 0
v_max_non_paid_realizn 3647 3647 0 0.00 0 6589 4 0 0
v_min_id_clo_ord 5268 5268 0 0.00 0 2257 5 0 0
v_min_id_clo_res 906 906 0 0.00 0 1022 2 0 0
v_min_id_ord_sup 5269 5269 0 0.00 0 8684 8 0 0
v_min_non_paid_invoice 5654 5654 0 0.00 0 12995 28 0 0
v_min_non_paid_realizn 6880 6880 0 0.00 0 9125 25 0 0
v_our_firm 9548 9548 0 0.00 0 14 1 0 0
v_reserve_write_off 18888 18888 0 0.00 0 6450 7 0 0
x_lock_depdocs_on_canc_invoice 11895 11895 0 0.00 0 20256 125 0 0
x_lock_depdocs_on_canc_sup_ord 415 415 0 0.00 0 19618 357 0 0
x_make_qstorno_1000_1200 5268 5268 0 0.00 1 31561 1164 0 0
x_make_qstorno_1200_2000 5272 5272 0 0.00 2 77104 3077 0 0
x_make_qstorno_2100_3300 445001 445001 0 0.00 0 87356 159 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 24-02-2023 22:40:11 to 24-02-2023 22:40:15.

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
20:08:43 3 8192000000 8805170880 8818429952 10259808 0.125 3 2 3 0 10402272 67504 74960
20:09:43 29491 8192000000 9909851248 10220281856 992828448 10.809 100 93 186 0 1024236032 6714816 24692768
20:11:29 11196 8192000000 9947747392 10268979200 1027250672 11.142 100 88 176 0 1058841312 9166928 22423712
20:12:52 15583 8192000000 9981349024 10314010624 1065480544 11.509 100 81 162 0 1095534320 9755872 20297904
20:14:10 8058 8192000000 10024498304 10371371008 1115842976 11.988 100 83 166 0 1146128112 10469360 19815776
20:15:29 4359 8192000000 10079465600 10438615040 1167610144 12.474 100 79 158 1 1198327792 12174160 18543488
20:16:37 4105 8192000000 10135158176 10500214784 1216963616 12.934 100 79 158 1 1247163600 12818000 17381984
20:17:42 2094 8192000000 10167452032 10543489024 1251235760 13.250 100 86 171 0 1281769712 11960144 18573808
20:18:46 1046 8192000000 10196462032 10581745664 1281460560 13.526 100 82 164 1 1313303200 13219504 18623136
20:19:48 20646 8192000000 10208100832 10605256704 1304946080 13.740 100 69 136 1 1331685232 11871072 14868080
20:21:10 4580 8192000000 10194412240 10608693248 1313067216 13.814 100 29 58 0 1326296912 7314800 5914896
20:22:16 9247 8192000000 10199367488 10621427712 1318648288 13.864 100 18 36 0 1328328224 5833168 3846768
20:23:26 23875 8192000000 10211450864 10636640256 1326323232 13.934 100 27 54 0 1338354544 5450272 6581040
20:24:51 5939 8192000000 10220700720 10643398656 1332209776 13.987 100 27 54 0 1345411920 6644448 6557696
20:25:59 8073 8192000000 10228510416 10648629248 1336647584 14.027 100 23 47 0 1347044032 5635376 4761072
20:27:08 18160 8192000000 10235647824 10651455488 1325395104 13.926 99 39 79 0 1339842640 4898144 9549392
20:29:04 8541 8192000000 10206489072 10622652416 1295353840 13.653 98 27 55 0 1306964064 5772608 5837616
20:30:13 7049 8192000000 10171010368 10578243584 1248521536 13.225 97 28 56 0 1265345648 10562496 6261616
20:31:23 10539 8192000000 10132739376 10529853440 1180169440 12.592 94 22 44 0 1194819408 8846112 5803856
20:32:35 55115 8192000000 10082490112 10476589056 1161785328 12.420 93 33 66 1 1174778384 5393184 7599872
20:34:31 3644 8192000000 10160951920 10565148672 1267916304 13.403 100 27 54 0 1280729344 7128288 5684752
20:35:35 7586 8192000000 10178529696 10588807168 1289345376 13.598 100 21 42 0 1299215536 5370352 4499808
20:36:44 10972 8192000000 10189456176 10602586112 1300469968 13.700 100 20 39 0 1310273760 5291040 4512752
20:37:57 25178 8192000000 10208427856 10623873024 1317314608 13.852 100 36 72 0 1331898688 5768928 8815152
20:39:29 18348 8192000000 10243928512 10657705984 1339557312 14.053 100 52 100 2 1363560416 13018320 10984784
20:40:51 9110 8192000000 10259698576 10670964736 1353433920 14.178 100 43 86 0 1372274080 8745200 10094960
20:42:05 9506 8192000000 10276134464 10696163328 1369529152 14.323 100 45 89 0 1393989952 14385072 10075728
20:43:19 24524 8192000000 10305112096 10721292288 1384017200 14.452 100 41 82 0 1409947600 16238000 9692400
20:45:04 15516 8192000000 10310045440 10730299392 1391456144 14.519 100 49 98 0 1423147264 19588464 12102656
20:46:24 16787 8192000000 10324299856 10751524864 1401314400 14.607 100 47 94 0 1436534736 23478448 11741888
20:47:45 8978 8192000000 10336275904 10763988992 1415082224 14.729 100 54 106 2 1452159408 24429424 12647760
20:48:58 4751 8192000000 10348834784 10768388096 1412321376 14.705 100 66 132 1 1459693056 30991344 16380336
20:50:08 2929 8192000000 10351875472 10767073280 1402025680 14.613 99 72 143 2 1453332192 34247488 17059024
20:51:42 3821 8192000000 10352973408 10774405120 1409833584 14.682 100 64 128 0 1461010224 35294320 15882320
20:53:02 3660 8192000000 10369123552 10789101568 1409562608 14.680 99 63 126 0 1474472784 47583808 17326368
20:54:08 1314 8192000000 10358423264 10769399808 1398663488 14.583 99 71 139 4 1462571440 46821504 17086448
20:55:15 1873 8192000000 10391774752 10810142720 1429104192 14.853 100 68 136 1 1497487376 51311056 17072128
20:56:24 2617 8192000000 10392016112 10809794560 1419763280 14.771 100 66 130 0 1493150080 56686096 16700704
20:57:37 4303 8192000000 10374601520 10796867584 1392211680 14.526 99 62 125 0 1470375072 62186688 15976704
20:58:46 10109 8192000000 10322271792 10747027456 1346808976 14.119 97 39 80 1 1412887744 55561440 10517328
21:00:03 16898 8192000000 10350882672 10781360128 1389336736 14.500 100 44 88 2 1459960816 58128768 12495312
21:01:29 31532 8192000000 10321380720 10760110080 1350015264 14.148 98 26 52 0 1419734224 61763696 7955264
21:03:03 26854 8192000000 10306867648 10747351040 1349767568 14.145 100 22 44 0 1412515360 56337600 6410192
21:04:52 43034 8192000000 10261204864 10705080320 1347309088 14.123 100 13 26 1 1385930672 34333984 4287600
21:06:49 43017 8192000000 10271362592 10715860992 1361648992 14.252 100 17 33 0 1376438256 10912192 3877072
21:08:33 57302 8192000000 10275682352 10717941760 1335124112 14.013 97 21 40 1 1354330368 14756080 4450176
21:10:32 148316 8192000000 10282747232 10736709632 1329329728 13.961 96 10 20 0 1346793184 14792624 2670832
21:14:03 52686 8192000000 10303586464 10745991168 1376199952 14.383 100 17 35 0 1400427584 20524384 3703248
21:15:57 54450 8192000000 10274458064 10734465024 1374891152 14.371 100 14 28 0 1388239968 10373472 2975344
21:18:02 52214 8192000000 10276467504 10737250304 1367900656 14.308 99 20 39 0 1384592000 11824320 4867024
21:20:09 69755 8192000000 10277226800 10738970624 1360460768 14.241 98 7 14 0 1373637072 11523232 1653072
21:22:42 75368 8192000000 10277434960 10728099840 1340404400 14.061 97 17 34 0 1357468448 13098464 3965584
21:24:58 52245 8192000000 10298396944 10752655360 1371197792 14.338 99 24 48 0 1395283104 18642208 5443104
21:27:41 70929 8192000000 10307147088 10766520320 1385176544 14.463 99 28 56 0 1409033296 17786864 6069888
21:29:59 42358 8192000000 10307427152 10751561728 1389347264 14.500 99 19 37 0 1402377344 8995904 4034176
21:31:44 103265 8192000000 10301512208 10761711616 1345222720 14.104 96 17 34 0 1367561664 18634240 3704704
21:34:38 61569 8192000000 10300025776 10752831488 1381071904 14.426 99 15 28 0 1402517296 18269984 3175408
21:36:40 113035 8192000000 10296200544 10747990016 1377035312 14.390 99 24 46 0 1392103248 10098400 4969536
21:39:34 49544 8192000000 10299081152 10765406208 1395707776 14.557 99 26 52 0 1412896400 11554832 5633792
21:41:25 32469 8192000000 10295251344 10772467712 1401894736 14.612 100 5 10 0 1413740112 10901056 944320
21:42:59 59211 8192000000 10316544832 10774032384 1382526560 14.439 98 14 29 0 1394957008 9480624 2949824
21:44:58 94847 8192000000 10299219840 10763104256 1352686560 14.172 96 11 22 0 1366416960 11146096 2584304
21:47:34 41295 8192000000 10301505424 10752241664 1376221056 14.383 98 38 74 0 1398026416 13504240 8301120
21:49:28 81004 8192000000 10324904304 10789388288 1385965472 14.470 98 26 52 0 1410245296 18533792 5746032
21:51:52 42749 8192000000 10270180512 10723274752 1336929856 14.030 96 16 32 0 1351647856 11185840 3532160
21:53:35 32974 8192000000 10313188480 10760867840 1389301552 14.500 100 26 51 1 1410380176 14718208 6360416
21:55:10 100471 8192000000 10264879568 10721824768 1251538464 13.252 91 8 16 0 1267381664 14100944 1742256
21:57:50 86023 8192000000 10247114400 10693521408 1301282048 13.707 96 14 28 0 1321620144 16920064 3418032
22:00:41 44897 8192000000 10263174832 10718855168 1344415984 14.097 99 18 36 0 1361911184 13678208 3816992
22:02:27 65401 8192000000 10250500288 10705547264 1330491840 13.972 98 4 8 0 1339259872 7707552 1060480
22:04:32 48917 8192000000 10265783856 10713223168 1360470464 14.242 100 13 26 1 1370805360 7757776 2577120
22:06:37 41046 8192000000 10257630736 10710228992 1358858656 14.227 100 12 24 0 1369319952 7769840 2691456
22:08:24 55765 8192000000 10270058752 10722488320 1352430592 14.169 98 29 60 1 1367483104 8629104 6423408
22:10:28 67489 8192000000 10260390944 10722680832 1345272304 14.105 98 13 26 1 1356383296 8300192 2810800
22:12:40 43413 8192000000 10290669200 10735185920 1375216768 14.374 100 26 52 0 1390259616 8361088 6681760
22:14:24 47001 8192000000 10297597600 10746068992 1389578128 14.502 100 43 86 0 1408855664 10063632 9213904
22:16:12 41329 8192000000 10292384768 10748301312 1369284624 14.321 99 22 44 0 1386115728 12095840 4735264
22:17:55 69706 8192000000 10294903520 10752716800 1375284288 14.374 99 22 44 0 1392969696 12518304 5167104
22:20:06 41058 8192000000 10296167984 10741645312 1321266784 13.888 95 25 51 0 1337200944 10258064 5676096
22:21:52 89816 8192000000 10284109120 10744410112 1347111472 14.121 97 5 10 0 1363385936 15353824 920640
22:24:31 83893 8192000000 10280515136 10738167808 1346177376 14.113 98 6 12 0 1366592688 19029232 1386080
22:27:01 130347 8192000000 10292866608 10746400768 1383386768 14.447 100 23 46 0 1401886256 13057152 5442336
22:30:14 69254 8192000000 10272787312 10734596096 1357874784 14.218 98 12 24 0 1366960608 6300416 2785408
22:33:10 50719 8192000000 10254540240 10709975040 1336660640 14.027 97 14 27 0 1346748896 6906128 3182128
22:35:05 38843 8192000000 10269037792 10725654528 1351205600 14.158 98 15 30 0 1364006816 9715456 3085760
22:36:53 51824 8192000000 10286452608 10748641280 1377118720 14.391 100 8 16 0 1390325728 11506720 1700288

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 24-02-2023 22:40:17 to 24-02-2023 22:40:32.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 124 v_add_invoice_to_stock 335544517
except 15 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 24-02-2023 22:40:33 to 24-02-2023 22:40:35.

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          229329
MON$OLDEST_ACTIVE               231468
MON$OLDEST_SNAPSHOT             231468
MON$NEXT_TRANSACTION            231468
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                       2010944
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.33665 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33665 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33665 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33665 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 ---
:556: 
:557: mscs-test-161.budzdorov.ru    Fri Feb 24 22:38:40 2023
:558:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:559: 
:560: 
:561: mscs-test-161.budzdorov.ru    Fri Feb 24 22:38:45 2023
:562:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:563: 
--- end of diff output ---

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

24.02.23 22:40:36. There are 3 logs in /var/tmp/logs-oltp30 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2023-02-24 22:40:39 No severe PSQL-related problems occured
24.02.23 22:40:40. End of report.