Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 15.02.2023 16:44
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 15211000 kB
MemAvailable 30430104 kB
Buffers 0 kB
Cached 16517208 kB
SwapCached 3348 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-15 16:42:40
DTS_END                         2023-02-15 16:42:40.5460
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/55350
TRN_ID                          250096
ATT_ID                          698
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: 9490.56 19059 1138867
customer order: creation 185.33 341 22239
customer order: refuse 15.91 365 1909
order to supplier: creation 49.80 1203 5976
order to supplier: removal 3.54 99400 425
invoice (draft): creation 49.53 3129 5944
invoice (draft): removal 53.42 805 6410
invoice accept: apply 45.21 17772 5425
invoice accept: cancel 47.23 718 5667
customer reserve: creation 4113.38 179 493606
customer reserve: removal 4615.08 59 553809
realization accept: apply 89.30 182 10716
realization accept: cancel 85.82 102 10298
payment from customer: creation 62.53 125 7504
payment from customer: removal 6.13 67 736
payment to supplier: creation 51.83 139 6219
payment to supplier: removal 3.65 74 438
service: total inventory turnovers 5.20 1017 624
service: total monetary turnovers 6.84 37 821
service: aggregate perf. data 0.81 20789 97
service: refresh index statistics 0.03 234674 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 15-02-2023 16:44:34 to 15-02-2023 16:44:36.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4231
TEST_TIME 2 3188
TEST_TIME 3 4882
TEST_TIME 4 4824
TEST_TIME 5 4711
TEST_TIME 6 4700
TEST_TIME 7 5068
TEST_TIME 8 5367
TEST_TIME 9 5593
TEST_TIME 10 5749
TEST_TIME 11 6106
TEST_TIME 12 6384
TEST_TIME 13 6546
TEST_TIME 14 6597
TEST_TIME 15 6974
TEST_TIME 16 7034
TEST_TIME 17 6979
TEST_TIME 18 6959
TEST_TIME 19 6841
TEST_TIME 20 6965
TEST_TIME 21 6941
TEST_TIME 22 7147
TEST_TIME 23 7365
TEST_TIME 24 7700
TEST_TIME 25 7910
TEST_TIME 26 8168
TEST_TIME 27 8324
TEST_TIME 28 8853
TEST_TIME 29 9563
TEST_TIME 30 9558
TEST_TIME 31 10133
TEST_TIME 32 9993
TEST_TIME 33 9983
TEST_TIME 34 9982
TEST_TIME 35 10173
TEST_TIME 36 10048
TEST_TIME 37 10040
TEST_TIME 38 9936
TEST_TIME 39 9795
TEST_TIME 40 9954
TEST_TIME 41 9964
TEST_TIME 42 9947
TEST_TIME 43 9787
TEST_TIME 44 9611
TEST_TIME 45 9593
TEST_TIME 46 9470
TEST_TIME 47 9459
TEST_TIME 48 9386
TEST_TIME 49 9414
TEST_TIME 50 9355
TEST_TIME 51 9431
TEST_TIME 52 9524
TEST_TIME 53 9521
TEST_TIME 54 9407
TEST_TIME 55 9274
TEST_TIME 56 9266
TEST_TIME 57 9289
TEST_TIME 58 9244
TEST_TIME 59 9223
TEST_TIME 60 9209
TEST_TIME 61 9283
TEST_TIME 62 9291
TEST_TIME 63 9285
TEST_TIME 64 9439
TEST_TIME 65 9479
TEST_TIME 66 9428
TEST_TIME 67 9475
TEST_TIME 68 9508
TEST_TIME 69 9555
TEST_TIME 70 9622
TEST_TIME 71 9671
TEST_TIME 72 9725
TEST_TIME 73 9694
TEST_TIME 74 9656
TEST_TIME 75 9599
TEST_TIME 76 9561
TEST_TIME 77 9490
TEST_TIME 78 9457
TEST_TIME 79 9584
TEST_TIME 80 9536
TEST_TIME 81 9512
TEST_TIME 82 9418
TEST_TIME 83 9431
TEST_TIME 84 9431
TEST_TIME 85 9402
TEST_TIME 86 9408
TEST_TIME 87 9377
TEST_TIME 88 9566
TEST_TIME 89 9513
TEST_TIME 90 9568
TEST_TIME 91 9562
TEST_TIME 92 9609
TEST_TIME 93 9607
TEST_TIME 94 9636
TEST_TIME 95 9708
TEST_TIME 96 9684
TEST_TIME 97 9645
TEST_TIME 98 9658
TEST_TIME 99 9642
TEST_TIME 100 9600
TEST_TIME 101 9574
TEST_TIME 102 9559
TEST_TIME 103 9534
TEST_TIME 104 9496
TEST_TIME 105 9448
TEST_TIME 106 9398
TEST_TIME 107 9413
TEST_TIME 108 9415
TEST_TIME 109 9434
TEST_TIME 110 9429
TEST_TIME 111 9436
TEST_TIME 112 9459
TEST_TIME 113 9486
TEST_TIME 114 9513
TEST_TIME 115 9488
TEST_TIME 116 9539
TEST_TIME 117 9526
TEST_TIME 118 9539
TEST_TIME 119 9555
TEST_TIME 120 9492

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 15-02-2023 16:44:38 to 15-02-2023 16:44:41.

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 1597967 1597967 0 0.00 0 48206 6 0 0
doc_list_biud 1597967 1597967 0 0.00 0 23624 26 0 0
sp_add_invoice_to_stock 5526 5425 101 1.82 52 180626 17772 0 101
sp_cancel_adding_invoice 5667 5667 0 0.00 4 55495 718 0 0
sp_cancel_client_order 1909 1909 0 0.00 3 19752 366 0 0
sp_cancel_customer_reserve 553809 553809 0 0.00 0 27782 59 0 0
sp_cancel_pay_from_customer 736 736 0 0.00 2 3460 68 0 0
sp_cancel_pay_to_supplier 438 438 0 0.00 3 2209 75 0 0
sp_cancel_supplier_invoice 6416 6410 6 0.09 1 18679 806 0 6
sp_cancel_supplier_order 425 425 0 0.00 8 1085597 99401 0 0
sp_cancel_write_off 10298 10298 0 0.00 1 21442 103 0 0
sp_client_order 22239 22239 0 0.00 3 25248 341 0 0
sp_customer_reserve 493606 493606 0 0.00 0 53925 180 0 0
sp_fill_shopping_cart 34159 34159 0 0.00 1 12861 36 0 0
sp_fill_shopping_cart_clo_res 493603 493603 0 0.00 0 12114 14 0 0
sp_get_clo_for_invoice 5421 5421 0 0.00 0 19966 258 0 0
sp_kill_cost_storno 17139 17139 0 0.00 0 20566 57 0 0
sp_kill_qstorno_ret_qs2qd 1124751 1124751 0 0.00 0 23610 32 0 0
sp_kill_qty_storno 594655 594655 0 0.00 0 23624 69 0 0
sp_lock_selected_doc 22297 22297 0 0.00 0 6038 5 0 0
sp_make_cost_storno 29860 29860 0 0.00 0 48201 71 0 0
sp_multiply_rows_for_pdistr 29860 29860 0 0.00 0 19426 32 0 0
sp_multiply_rows_for_qdistr 22239 22239 0 0.00 0 12612 151 0 0
sp_pay_from_customer 7504 7504 0 0.00 4 17409 125 0 0
sp_pay_to_supplier 6219 6219 0 0.00 5 48213 140 0 0
sp_payment_common 13723 13723 0 0.00 1 17245 33 0 0
sp_qd_handle_on_cancel_clo 1909 1909 0 0.00 0 19743 336 0 0
sp_qd_handle_on_invoice_upd_sts 11088 11088 0 0.00 0 18564 305 0 0
sp_qd_handle_on_reserve_upd_sts 10716 10716 0 0.00 0 12258 52 0 0
sp_reserve_write_off 10716 10716 0 0.00 6 21373 182 0 0
sp_supplier_invoice 5944 5944 0 0.00 6 94399 3129 0 0
sp_supplier_order 5976 5976 0 0.00 4 72514 1203 0 0
srv_aggregate_perf_data 97 97 0 0.00 3332 324029 20790 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 2527 154811 41757 0 0
srv_find_qd_qs_mism 1088863 1088863 0 0.00 0 12518 5 0 0
srv_make_invnt_saldo 624 624 0 0.00 29 62396 1017 0 0
srv_make_money_saldo 821 821 0 0.00 1 8108 38 0 0
srv_recalc_idx_stat 4 4 0 0.00 56375 311796 234675 0 0
t$perf-abend:sp_add_invoice_to_stock 101 101 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5425 5425 0 0.00 0 11807 153 0 0
t$perf-norm:sp_cancel_adding_invoice 409 409 0 0.00 0 6441 111 0 0
t$perf-norm:sp_cancel_client_order 1909 1909 0 0.00 0 4079 15 0 0
t$perf-norm:sp_cancel_customer_reserve 756 756 0 0.00 0 2760 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 736 736 0 0.00 0 4154 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 438 438 0 0.00 0 9509 24 0 0
t$perf-norm:sp_cancel_supplier_invoice 456 456 0 0.00 0 1072 14 0 0
t$perf-norm:sp_cancel_supplier_order 425 425 0 0.00 0 24506 1657 0 0
t$perf-norm:sp_cancel_write_off 1031 1031 0 0.00 0 601 4 0 0
t$perf-norm:sp_client_order 22239 22239 0 0.00 0 15681 18 0 0
t$perf-norm:sp_customer_reserve 1079 1079 0 0.00 0 15886 35 0 0
t$perf-norm:sp_pay_from_customer 7504 7504 0 0.00 0 8972 12 0 0
t$perf-norm:sp_pay_to_supplier 6219 6219 0 0.00 0 4772 12 0 0
t$perf-norm:sp_reserve_write_off 10716 10716 0 0.00 0 13991 11 0 0
t$perf-norm:sp_supplier_invoice 5944 5944 0 0.00 0 7191 22 0 0
t$perf-norm:sp_supplier_order 5976 5976 0 0.00 0 4294 16 0 0
t$perf-norm:srv_aggregate_perf_data 97 97 0 0.00 0 849 15 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 72 2 0 0
t$perf-norm:srv_make_invnt_saldo 624 624 0 0.00 0 1928 28 0 0
t$perf-norm:srv_make_money_saldo 821 821 0 0.00 0 2121 25 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 51 18 0 0
v_add_invoice_to_stock 11044 10842 202 1.82 0 3235 17 0 202
v_all_customers 40284 40284 0 0.00 0 88 1 0 0
v_all_suppliers 32918 32918 0 0.00 0 86 1 0 0
v_all_wares 44478 44478 0 0.00 0 92 1 0 0
v_cancel_adding_invoice 818 818 0 0.00 0 287 6 0 0
v_cancel_client_order 3818 3818 0 0.00 0 2548 4 0 0
v_cancel_customer_prepayment 1472 1472 0 0.00 0 927 4 0 0
v_cancel_customer_reserve 1512 1512 0 0.00 0 1386 4 0 0
v_cancel_payment_to_supplier 876 876 0 0.00 0 956 7 0 0
v_cancel_supplier_invoice 924 912 12 1.29 0 4324 20 0 12
v_cancel_supplier_order 850 850 0 0.00 0 1541 5 0 0
v_cancel_write_off 2062 2062 0 0.00 0 1540 14 0 0
v_max_id_clo_ord 5976 5976 0 0.00 0 1523 1 0 0
v_max_id_clo_res 1079 1079 0 0.00 0 1661 11 0 0
v_max_id_ord_sup 5944 5944 0 0.00 0 6883 6 0 0
v_max_non_paid_invoice 1680 1680 0 0.00 0 459 3 0 0
v_max_non_paid_realizn 4050 4050 0 0.00 0 17238 11 0 0
v_min_id_clo_ord 5976 5976 0 0.00 0 6250 13 0 0
v_min_id_clo_res 1079 1079 0 0.00 0 5810 15 0 0
v_min_id_ord_sup 5944 5944 0 0.00 0 4603 6 0 0
v_min_non_paid_invoice 6219 6219 0 0.00 0 13742 18 0 0
v_min_non_paid_realizn 7504 7504 0 0.00 0 16633 28 0 0
v_our_firm 11102 11102 0 0.00 0 24 1 0 0
v_reserve_write_off 21432 21432 0 0.00 0 3734 5 0 0
x_lock_depdocs_on_canc_invoice 11621 11621 0 0.00 0 23882 120 0 0
x_lock_depdocs_on_canc_sup_ord 425 425 0 0.00 0 6376 335 0 0
x_make_qstorno_1000_1200 5976 5976 0 0.00 1 72469 1123 0 0
x_make_qstorno_1200_2000 5944 5944 0 0.00 3 94258 3001 0 0
x_make_qstorno_2100_3300 493606 493606 0 0.00 0 51660 163 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 15-02-2023 16:44:42 to 15-02-2023 16:44:46.

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:12:44 9 8192000000 8807259040 8821579776 11601712 0.141 3 3 4 0 11785616 76800 107104
14:13:45 23429 8192000000 9907415456 10216759296 986648800 10.749 100 91 182 0 1016793648 5714656 24430192
14:15:18 15407 8192000000 9950813584 10274410496 1034516544 11.212 100 94 188 0 1067449696 8525600 24407552
14:16:39 16072 8192000000 9979666816 10314997760 1064026288 11.495 100 83 166 0 1095090848 10091296 20973264
14:18:03 5391 8192000000 10029699360 10377203712 1122542032 12.051 100 86 172 0 1154368688 11459408 20367248
14:19:12 7000 8192000000 10073082128 10432040960 1160255280 12.406 100 80 158 1 1191550944 12989664 18306000
14:20:33 3895 8192000000 10130617520 10500620288 1221958640 12.980 100 72 142 0 1249446688 10953024 16535024
14:21:38 2583 8192000000 10162969968 10542424064 1249969952 13.238 100 83 161 0 1280941200 13095440 17875808
14:22:43 3898 8192000000 10180449456 10563084288 1275908912 13.476 100 84 166 0 1308123280 14384320 17830048
14:23:48 961 8192000000 10219987664 10607652864 1308930512 13.776 100 88 171 0 1340083936 11069360 20084064
14:24:51 7161 8192000000 10224700336 10617503744 1312957072 13.813 100 72 144 1 1340781728 11227152 16597504
14:26:02 7640 8192000000 10199400352 10612133888 1314139952 13.824 100 25 50 1 1327758112 8320656 5297504
14:27:13 4061 8192000000 10213381104 10624765952 1323477888 13.908 100 39 76 1 1338756896 7102112 8176896
14:28:20 8427 8192000000 10208817680 10630193152 1327827312 13.948 100 20 40 0 1338713232 6724448 4161472
14:29:30 6340 8192000000 10216431168 10637578240 1332317424 13.988 100 24 46 0 1347714576 10236624 5160528
14:30:37 34599 8192000000 10233331744 10639015936 1325894976 13.930 99 54 110 0 1344709984 5716800 13098208
14:32:13 3581 8192000000 10239627232 10655961088 1334682560 14.009 100 27 52 0 1350327392 9281824 6363008
14:33:22 12120 8192000000 10205163024 10616569856 1292319280 13.625 98 22 43 0 1303360960 5846864 5194816
14:34:35 25929 8192000000 10177099696 10593021952 1236920480 13.118 95 19 38 0 1246788800 5223456 4644864
14:36:02 7471 8192000000 10159567936 10558623744 1213679520 12.903 95 28 56 0 1226259792 5828864 6751408
14:37:13 11138 8192000000 10144258560 10546720768 1241808400 13.163 99 16 32 0 1250807456 5629696 3369360
14:38:25 60360 8192000000 10156726624 10568966144 1260224416 13.332 99 28 57 2 1271497888 4984176 6289296
14:40:33 7701 8192000000 10190993808 10603855872 1301234768 13.706 100 21 42 0 1312516848 6045616 5236464
14:41:43 12211 8192000000 10196180880 10612621312 1310737952 13.793 100 20 40 0 1321412560 5901248 4773360
14:42:58 116385 8192000000 10251702848 10670419968 1345573712 14.108 100 61 122 0 1368074704 8553648 13947344
14:46:10 10891 8192000000 10257560352 10682970112 1360936224 14.246 100 26 52 0 1374527168 7433280 6157664
14:47:26 17883 8192000000 10279328304 10704429056 1375406096 14.375 100 50 96 1 1396827840 10408848 11012896
14:48:56 18045 8192000000 10284661872 10705596416 1377528048 14.394 100 45 90 0 1401848160 13222256 11097856
14:50:21 4946 8192000000 10291272288 10710798336 1376280160 14.383 100 47 94 0 1404026256 15624576 12121520
14:51:32 7295 8192000000 10312737856 10733170688 1388114128 14.489 100 55 108 0 1419992320 19130032 12748160
14:52:46 5512 8192000000 10321566496 10740617216 1393733360 14.539 100 64 125 0 1429847536 20755504 15358672
14:53:57 6525 8192000000 10321591152 10750504960 1398881152 14.585 100 47 93 1 1434101120 23972928 11247040
14:55:12 3919 8192000000 10346065328 10771435520 1411603648 14.698 100 59 115 1 1453531984 26576384 15351952
14:56:24 6533 8192000000 10340483408 10770309120 1408925856 14.674 100 56 112 1 1454208704 31316240 13966608
14:57:46 3339 8192000000 10324154416 10744569856 1390572448 14.511 100 60 120 2 1433763760 28670160 14521152
14:58:59 3370 8192000000 10340505536 10759802880 1391384656 14.518 99 65 131 1 1440086576 32142016 16559904
15:00:10 4068 8192000000 10336494208 10760294400 1398532944 14.582 100 49 99 0 1446290944 35462080 12295920
15:01:21 7452 8192000000 10328328608 10740789248 1373382160 14.357 99 50 100 0 1423967920 37488672 13097088
15:02:38 3075 8192000000 10344167760 10739499008 1369958704 14.327 100 86 171 2 1441783968 50579168 21246096
15:03:47 2525 8192000000 10344984000 10751991808 1383342672 14.446 100 72 144 0 1450668416 48659360 18666384
15:04:54 5620 8192000000 10353466672 10773143552 1389577808 14.502 99 58 116 0 1456857088 51816064 15463216
15:06:13 10705 8192000000 10346279712 10771038208 1386935968 14.479 100 43 86 1 1450520160 51091696 12492496
15:07:26 11665 8192000000 10337994144 10753806336 1356003072 14.201 98 48 98 1 1419080128 49799184 13277872
15:09:01 10918 8192000000 10308397104 10736852992 1341350960 14.070 98 32 64 0 1407063904 56010480 9702464
15:10:19 14574 8192000000 10325119760 10754072576 1367061008 14.301 100 32 64 1 1436040816 59452944 9526864
15:11:41 21819 8192000000 10286656112 10718760960 1344156720 14.095 100 28 56 0 1382662416 31245056 7260640
15:13:06 32020 8192000000 10276713520 10728345600 1350113264 14.148 99 16 33 0 1378962016 24805296 4043456
15:14:38 26691 8192000000 10281505056 10705547264 1351173296 14.158 99 46 86 2 1367351296 6409744 9768256
15:16:20 62754 8192000000 10288595312 10746335232 1362427712 14.259 99 7 14 0 1386627504 22480336 1719456
15:18:28 70519 8192000000 10303325344 10750619648 1393347904 14.536 100 23 46 0 1407950160 9733760 4868496
15:20:46 28918 8192000000 10304046688 10745610240 1370328880 14.330 98 14 26 0 1383805104 10663440 2812784
15:22:15 49844 8192000000 10293173888 10743447552 1375500528 14.376 99 15 30 1 1389812272 11045904 3265840
15:24:06 154811 8192000000 10308118192 10754076672 1371213072 14.338 98 31 55 2 1387294608 9130112 6951424
15:27:52 53595 8192000000 10314527328 10763640832 1390561216 14.511 99 38 73 0 1413036912 14173456 8302240
15:29:48 45743 8192000000 10318914432 10775515136 1405405104 14.643 100 17 34 0 1421987056 12702656 3879296
15:31:42 21602 8192000000 10338240960 10794770432 1416243280 14.739 100 34 67 0 1448867280 24916096 7707904
15:33:08 32118 8192000000 10311996544 10774700032 1386308016 14.473 98 19 38 1 1407476720 16730080 4438624
15:34:45 34677 8192000000 10293555824 10756587520 1400337024 14.598 100 6 12 0 1408695824 6877360 1481440
15:36:20 59094 8192000000 10298893504 10761502720 1401785680 14.611 100 17 34 0 1413958640 8336176 3836784
15:38:19 44152 8192000000 10296532112 10761986048 1393012528 14.533 100 24 46 2 1408872208 9821872 6037808
15:40:05 52803 8192000000 10257134112 10723348480 1305440816 13.745 94 8 16 0 1317953504 11241168 1271520
15:41:59 53471 8192000000 10267917856 10720002048 1340637328 14.063 97 17 34 0 1352743008 8230768 3874912
15:44:11 19013 8192000000 10291226608 10738114560 1357892976 14.218 98 35 69 0 1375907424 10914272 7100176
15:45:40 53306 8192000000 10267696800 10719858688 1297237152 13.670 95 17 34 0 1319879760 18534048 4108560
15:47:35 53591 8192000000 10249444064 10698936320 1304717616 13.738 96 29 58 0 1323090016 11857600 6514800
15:49:30 34494 8192000000 10288051328 10739478528 1374458128 14.367 100 27 54 0 1394382416 14172256 5752032
15:51:09 59547 8192000000 10274779952 10720231424 1331744864 13.983 97 21 42 0 1346478000 10168464 4564672
15:53:12 95196 8192000000 10283687552 10742738944 1339201072 14.050 98 17 34 1 1361233488 18105984 3926432
15:55:47 70451 8192000000 10282129056 10730614784 1347105488 14.121 98 35 70 2 1370490496 15767888 7617120
15:58:14 40259 8192000000 10270258080 10723106816 1344439168 14.097 98 30 61 0 1360586480 9610512 6536800
16:01:19 145064 8192000000 10274686160 10743304192 1332344496 13.988 98 13 26 0 1354064864 18579424 3140944
16:05:13 57954 8192000000 10273191952 10741854208 1364975040 14.282 99 18 36 0 1382727552 13472560 4279952
16:07:24 23822 8192000000 10264408944 10717323264 1362541632 14.260 99 22 44 0 1378478272 11121984 4814656
16:08:48 54695 8192000000 10298091936 10751766528 1374382016 14.366 99 27 54 0 1393252240 12587248 6282976
16:11:16 49241 8192000000 10298408928 10754723840 1390617056 14.511 100 21 42 0 1405421552 9914336 4890160
16:13:07 62011 8192000000 10309672208 10767679488 1388333552 14.491 99 24 48 0 1406305808 12764768 5207488
16:15:09 89925 8192000000 10311994864 10779422720 1360808640 14.245 97 11 22 0 1379749264 16675104 2265520
16:17:49 55516 8192000000 10304152416 10749976576 1349307824 14.141 96 25 52 0 1366006656 11093952 5604880
16:19:45 19947 8192000000 10336123600 10775822336 1403744896 14.628 100 65 128 0 1432483152 14761376 13976880
16:21:10 92625 8192000000 10295168976 10768809984 1398986944 14.586 100 16 32 0 1411721600 9263184 3471472
16:23:43 40832 8192000000 10286527776 10753896448 1377596432 14.395 98 29 60 0 1390523088 6357984 6568672
16:25:26 74192 8192000000 10281511888 10747899904 1358575264 14.225 97 11 23 0 1367182768 6036096 2571408
16:27:50 43927 8192000000 10278548720 10743177216 1367919792 14.308 98 14 28 1 1379108624 8137408 3051424
16:29:48 47058 8192000000 10304851968 10753650688 1374545088 14.368 99 22 43 0 1389661200 9403584 5712528
16:31:46 82256 8192000000 10274173648 10734542848 1293281536 13.634 94 12 24 0 1307203120 11107648 2813936
16:34:16 88846 8192000000 10271609712 10722549760 1302605648 13.719 95 24 49 1 1325122976 16730800 5786528
16:36:48 25574 8192000000 10287691264 10731380736 1342978544 14.084 98 33 67 0 1359380992 9237440 7165008
16:38:18 66630 8192000000 10275554672 10727575552 1337248864 14.033 98 20 39 0 1353074656 11154576 4671216
16:40:25 48216 8192000000 10259866000 10708537344 1324131648 13.914 98 23 46 0 1338950432 10068640 4750144

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 15-02-2023 16:44:48 to 15-02-2023 16:45:03.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 101 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 15-02-2023 16:45:04 to 15-02-2023 16:45:06.

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          249823
MON$OLDEST_ACTIVE               250250
MON$OLDEST_SNAPSHOT             250250
MON$NEXT_TRANSACTION            250250
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                       2040544
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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Wed Feb 15 16:42:59 2023
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Wed Feb 15 16:43:09 2023
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:13: 
:14: 
:15: mscs-test-161.budzdorov.ru    Wed Feb 15 16:43:12 2023
:16:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:17: 
--- end of diff output ---

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

15.02.23 16:45:07. 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-15 16:45:10 No severe PSQL-related problems occured
15.02.23 16:45:11. End of report.