Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 13.04.2022 01:39
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16569088 kB
MemAvailable 31557360 kB
Buffers 0 kB
Cached 15176748 kB
SwapCached 2688 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.10
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-04-13 01:38:13
DTS_END                         2022-04-13 01:38:13.6880
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/56564
TRN_ID                          238051
ATT_ID                          683
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: 9503.59 20153 1140431
customer order: creation 174.22 342 20906
customer order: refuse 14.71 370 1765
order to supplier: creation 46.63 1259 5595
order to supplier: removal 3.38 109618 406
invoice (draft): creation 46.75 3314 5610
invoice (draft): removal 54.28 785 6514
invoice accept: apply 42.54 17448 5105
invoice accept: cancel 48.83 765 5860
customer reserve: creation 3928.79 172 471455
customer reserve: removal 4843.45 60 581214
realization accept: apply 83.99 182 10079
realization accept: cancel 87.17 120 10460
payment from customer: creation 59.33 131 7119
payment from customer: removal 5.85 77 702
payment to supplier: creation 48.12 129 5774
payment to supplier: removal 3.60 73 432
service: total inventory turnovers 4.81 1072 577
service: total monetary turnovers 6.25 27 750
service: aggregate perf. data 0.87 21102 104
service: refresh index statistics 0.03 246008 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 13-04-2022 01:40:01 to 13-04-2022 01:40:03.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3935
TEST_TIME 2 5568
TEST_TIME 3 4861
TEST_TIME 4 4479
TEST_TIME 5 4653
TEST_TIME 6 4378
TEST_TIME 7 4652
TEST_TIME 8 5081
TEST_TIME 9 5294
TEST_TIME 10 6424
TEST_TIME 11 6661
TEST_TIME 12 6910
TEST_TIME 13 6978
TEST_TIME 14 6931
TEST_TIME 15 7091
TEST_TIME 16 7434
TEST_TIME 17 7781
TEST_TIME 18 7821
TEST_TIME 19 7950
TEST_TIME 20 8284
TEST_TIME 21 8548
TEST_TIME 22 8972
TEST_TIME 23 9126
TEST_TIME 24 9164
TEST_TIME 25 9198
TEST_TIME 26 9182
TEST_TIME 27 9462
TEST_TIME 28 10115
TEST_TIME 29 10975
TEST_TIME 30 10871
TEST_TIME 31 10838
TEST_TIME 32 10886
TEST_TIME 33 10918
TEST_TIME 34 10868
TEST_TIME 35 10648
TEST_TIME 36 10463
TEST_TIME 37 10440
TEST_TIME 38 10218
TEST_TIME 39 10288
TEST_TIME 40 10414
TEST_TIME 41 10256
TEST_TIME 42 10105
TEST_TIME 43 10051
TEST_TIME 44 10072
TEST_TIME 45 10096
TEST_TIME 46 10159
TEST_TIME 47 10200
TEST_TIME 48 10133
TEST_TIME 49 10014
TEST_TIME 50 9864
TEST_TIME 51 9781
TEST_TIME 52 9765
TEST_TIME 53 9883
TEST_TIME 54 9948
TEST_TIME 55 9906
TEST_TIME 56 9813
TEST_TIME 57 9985
TEST_TIME 58 10026
TEST_TIME 59 10001
TEST_TIME 60 9995
TEST_TIME 61 9979
TEST_TIME 62 10069
TEST_TIME 63 10144
TEST_TIME 64 10165
TEST_TIME 65 10152
TEST_TIME 66 10212
TEST_TIME 67 10097
TEST_TIME 68 10028
TEST_TIME 69 10004
TEST_TIME 70 9892
TEST_TIME 71 9832
TEST_TIME 72 9875
TEST_TIME 73 9782
TEST_TIME 74 9765
TEST_TIME 75 9709
TEST_TIME 76 9662
TEST_TIME 77 9698
TEST_TIME 78 9714
TEST_TIME 79 9758
TEST_TIME 80 9790
TEST_TIME 81 9824
TEST_TIME 82 9805
TEST_TIME 83 9836
TEST_TIME 84 9856
TEST_TIME 85 9854
TEST_TIME 86 9932
TEST_TIME 87 9955
TEST_TIME 88 9932
TEST_TIME 89 9929
TEST_TIME 90 9890
TEST_TIME 91 9844
TEST_TIME 92 9836
TEST_TIME 93 9807
TEST_TIME 94 9768
TEST_TIME 95 9802
TEST_TIME 96 9780
TEST_TIME 97 9754
TEST_TIME 98 9698
TEST_TIME 99 9662
TEST_TIME 100 9616
TEST_TIME 101 9598
TEST_TIME 102 9591
TEST_TIME 103 9577
TEST_TIME 104 9606
TEST_TIME 105 9709
TEST_TIME 106 9673
TEST_TIME 107 9693
TEST_TIME 108 9722
TEST_TIME 109 9725
TEST_TIME 110 9717
TEST_TIME 111 9748
TEST_TIME 112 9744
TEST_TIME 113 9718
TEST_TIME 114 9745
TEST_TIME 115 9670
TEST_TIME 116 9659
TEST_TIME 117 9624
TEST_TIME 118 9613
TEST_TIME 119 9578
TEST_TIME 120 9510

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 13-04-2022 01:40:04 to 13-04-2022 01:40:08.

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 1576939 1576939 0 0.00 0 38845 6 0 0
doc_list_biud 1576939 1576939 0 0.00 0 70313 28 0 0
sp_add_invoice_to_stock 5201 5105 96 1.84 25 172871 17448 0 96
sp_cancel_adding_invoice 5860 5860 0 0.00 5 53148 766 0 0
sp_cancel_client_order 1765 1765 0 0.00 4 13959 370 0 0
sp_cancel_customer_reserve 581214 581214 0 0.00 1 23026 60 0 0
sp_cancel_pay_from_customer 702 702 0 0.00 2 8717 77 0 0
sp_cancel_pay_to_supplier 432 432 0 0.00 3 2279 74 0 0
sp_cancel_supplier_invoice 6525 6514 11 0.16 1 70318 786 0 11
sp_cancel_supplier_order 406 406 0 0.00 10 1025328 109618 0 0
sp_cancel_write_off 10460 10460 0 0.00 1 15670 120 0 0
sp_client_order 20906 20906 0 0.00 3 18794 342 0 0
sp_customer_reserve 471455 471455 0 0.00 0 39329 172 0 0
sp_fill_shopping_cart 32106 32106 0 0.00 1 26901 39 0 0
sp_fill_shopping_cart_clo_res 471448 471448 0 0.00 0 20084 12 0 0
sp_get_clo_for_invoice 5098 5098 0 0.00 0 28698 288 0 0
sp_kill_cost_storno 17454 17454 0 0.00 0 11761 56 0 0
sp_kill_qstorno_ret_qs2qd 1179808 1179808 0 0.00 0 70310 33 0 0
sp_kill_qty_storno 621396 621396 0 0.00 0 70312 70 0 0
sp_lock_selected_doc 20997 20997 0 0.00 0 9654 8 0 0
sp_make_cost_storno 28070 28070 0 0.00 0 38776 53 0 0
sp_multiply_rows_for_pdistr 28070 28070 0 0.00 0 12250 27 0 0
sp_multiply_rows_for_qdistr 20906 20906 0 0.00 0 11009 150 0 0
sp_pay_from_customer 7119 7119 0 0.00 4 21974 131 0 0
sp_pay_to_supplier 5774 5774 0 0.00 5 39524 129 0 0
sp_payment_common 12893 12893 0 0.00 1 21934 36 0 0
sp_qd_handle_on_cancel_clo 1765 1765 0 0.00 0 13951 326 0 0
sp_qd_handle_on_invoice_upd_sts 10958 10958 0 0.00 0 30358 338 0 0
sp_qd_handle_on_reserve_upd_sts 10079 10079 0 0.00 0 17670 54 0 0
sp_reserve_write_off 10079 10079 0 0.00 6 17683 182 0 0
sp_supplier_invoice 5610 5610 0 0.00 6 99523 3315 0 0
sp_supplier_order 5595 5595 0 0.00 5 67160 1260 0 0
srv_aggregate_perf_data 104 104 0 0.00 2930 435886 21103 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1669 120410 40348 0 0
srv_find_qd_qs_mism 1092159 1092159 0 0.00 0 13525 5 0 0
srv_make_invnt_saldo 577 577 0 0.00 46 49962 1072 0 0
srv_make_money_saldo 750 750 0 0.00 0 3394 27 0 0
srv_recalc_idx_stat 4 4 0 0.00 116500 401139 246009 0 0
t$perf-abend:sp_add_invoice_to_stock 96 96 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5106 5106 0 0.00 0 17339 192 0 0
t$perf-norm:sp_cancel_adding_invoice 396 396 0 0.00 0 3369 111 0 0
t$perf-norm:sp_cancel_client_order 1765 1765 0 0.00 0 4755 19 0 0
t$perf-norm:sp_cancel_customer_reserve 680 680 0 0.00 0 3208 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 702 702 0 0.00 0 3248 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 432 432 0 0.00 0 2765 18 0 0
t$perf-norm:sp_cancel_supplier_invoice 432 432 0 0.00 0 3155 19 0 0
t$perf-norm:sp_cancel_supplier_order 406 406 0 0.00 0 26207 1735 0 0
t$perf-norm:sp_cancel_write_off 1007 1007 0 0.00 0 3372 14 0 0
t$perf-norm:sp_client_order 20906 20906 0 0.00 0 10189 24 0 0
t$perf-norm:sp_customer_reserve 1045 1045 0 0.00 0 3490 12 0 0
t$perf-norm:sp_pay_from_customer 7119 7119 0 0.00 0 9471 17 0 0
t$perf-norm:sp_pay_to_supplier 5774 5774 0 0.00 0 9775 16 0 0
t$perf-norm:sp_reserve_write_off 10079 10079 0 0.00 0 9734 17 0 0
t$perf-norm:sp_supplier_invoice 5610 5610 0 0.00 0 7123 21 0 0
t$perf-norm:sp_supplier_order 5595 5595 0 0.00 0 9542 22 0 0
t$perf-norm:srv_aggregate_perf_data 104 104 0 0.00 0 558 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 192 8 0 0
t$perf-norm:srv_make_invnt_saldo 577 577 0 0.00 0 5241 34 0 0
t$perf-norm:srv_make_money_saldo 750 750 0 0.00 0 1329 13 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 9 4 0 0
v_add_invoice_to_stock 10388 10196 192 1.84 0 11366 28 0 192
v_all_customers 38372 38372 0 0.00 0 117 1 0 0
v_all_suppliers 30724 30724 0 0.00 0 19 1 0 0
v_all_wares 41812 41812 0 0.00 0 19 1 0 0
v_cancel_adding_invoice 792 792 0 0.00 0 8624 25 0 0
v_cancel_client_order 3530 3530 0 0.00 0 5945 14 0 0
v_cancel_customer_prepayment 1404 1404 0 0.00 0 8696 18 0 0
v_cancel_customer_reserve 1360 1360 0 0.00 0 6715 17 0 0
v_cancel_payment_to_supplier 864 864 0 0.00 0 2075 5 0 0
v_cancel_supplier_invoice 886 864 22 2.48 0 970 13 0 22
v_cancel_supplier_order 812 812 0 0.00 0 1006 4 0 0
v_cancel_write_off 2014 2014 0 0.00 0 5127 18 0 0
v_max_id_clo_ord 5595 5595 0 0.00 0 5912 4 0 0
v_max_id_clo_res 1045 1045 0 0.00 0 2469 15 0 0
v_max_id_ord_sup 5603 5603 0 0.00 0 4121 3 0 0
v_max_non_paid_invoice 1610 1610 0 0.00 0 1153 4 0 0
v_max_non_paid_realizn 3724 3724 0 0.00 0 16317 10 0 0
v_min_id_clo_ord 5595 5595 0 0.00 0 8604 12 0 0
v_min_id_clo_res 1045 1045 0 0.00 0 2341 9 0 0
v_min_id_ord_sup 5603 5603 0 0.00 0 8380 10 0 0
v_min_non_paid_invoice 5774 5774 0 0.00 0 10624 20 0 0
v_min_non_paid_realizn 7119 7119 0 0.00 0 21930 31 0 0
v_our_firm 10230 10230 0 0.00 0 13 1 0 0
v_reserve_write_off 20158 20158 0 0.00 0 11426 11 0 0
x_lock_depdocs_on_canc_invoice 11942 11942 0 0.00 0 20867 113 0 0
x_lock_depdocs_on_canc_sup_ord 406 406 0 0.00 0 16496 378 0 0
x_make_qstorno_1000_1200 5595 5595 0 0.00 1 67154 1164 0 0
x_make_qstorno_1200_2000 5610 5610 0 0.00 3 84545 3188 0 0
x_make_qstorno_2100_3300 471455 471455 0 0.00 0 39328 157 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 13-04-2022 01:40:09 to 13-04-2022 01:40:13.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
23:08:17 9 8192000000 8801920288 8814366720 7670864 0.093 3 1 2 0 7771824 58176 42784
23:09:19 20725 8192000000 9908285072 10219098112 993919136 10.820 100 89 177 1 1022799008 5896960 22982912
23:10:55 26893 8192000000 9954534048 10278166528 1036404752 11.230 100 88 176 0 1068015056 8912224 22698080
23:12:26 22158 8192000000 9990212304 10323685376 1070049520 11.553 100 85 170 1 1101007712 9416448 21541744
23:13:54 15624 8192000000 10032710400 10378948608 1119478432 12.022 100 78 154 0 1148670768 10371152 18821184
23:15:19 9257 8192000000 10077440656 10435690496 1164875360 12.449 100 70 139 3 1192364560 11675600 15813600
23:16:31 6340 8192000000 10126045920 10491854848 1211741104 12.885 100 84 170 0 1243319872 13222480 18356288
23:17:40 5890 8192000000 10148677264 10523987968 1242174320 13.166 100 77 154 1 1273834144 15052944 16606880
23:18:49 2545 8192000000 10188680544 10569887744 1273796544 13.456 100 91 181 1 1307738896 13641120 20301232
23:19:54 1897 8192000000 10206133680 10586673152 1286384880 13.571 100 74 150 0 1315742256 12829920 16527456
23:20:59 1647 8192000000 10225305296 10607919104 1303273520 13.725 100 91 184 0 1337828752 12912448 21642784
23:22:03 6959 8192000000 10198726064 10605223936 1309027008 13.777 100 38 76 0 1325592752 8235152 8330592
23:23:13 4903 8192000000 10212290016 10626056192 1322244496 13.897 100 31 63 3 1338929120 10140112 6544512
23:24:21 9524 8192000000 10212043776 10627035136 1324556176 13.918 100 23 45 2 1335713264 6318384 4838704
23:25:34 60408 8192000000 10226102688 10645708800 1331708208 13.983 100 21 42 2 1341611040 5531312 4371520
23:27:39 6191 8192000000 10234429616 10650071040 1333882976 14.002 100 35 71 0 1348580624 5993360 8704288
23:28:47 3665 8192000000 10216347952 10631245824 1321416624 13.890 100 32 64 2 1334874608 6464032 6993952
23:29:54 55584 8192000000 10182949216 10591395840 1223384912 12.993 94 31 62 1 1235723168 4864832 7473424
23:31:54 4788 8192000000 10156207408 10555510784 1226464368 13.021 97 37 74 1 1246151952 12000176 7687408
23:33:00 9684 8192000000 10113595936 10508206080 1170531728 12.502 94 30 60 2 1182969184 4925712 7511744
23:34:13 9351 8192000000 10162130560 10566139904 1264679792 13.373 100 21 42 0 1274444896 4918096 4847008
23:35:25 56676 8192000000 10168003440 10580496384 1276453888 13.481 100 34 68 1 1290551808 6505488 7592432
23:37:22 6867 8192000000 10183339904 10592059392 1288051168 13.586 100 36 72 1 1302967600 6776160 8140272
23:38:31 34259 8192000000 10219674032 10625564672 1312142736 13.806 100 47 95 5 1328680208 5852432 10685040
23:40:11 10570 8192000000 10249444080 10658377728 1335082192 14.013 100 54 106 4 1360678368 13603248 11992928
23:41:26 11490 8192000000 10263981664 10678312960 1348493456 14.134 100 45 90 2 1372136704 13521440 10121808
23:43:08 26386 8192000000 10255980688 10687307776 1352573584 14.171 100 20 40 0 1371282608 14089808 4619216
23:44:37 35562 8192000000 10278732656 10705039360 1365453184 14.286 100 38 76 0 1395089200 20042528 9593488
23:46:15 4193 8192000000 10304472688 10723000320 1381234336 14.428 100 56 113 0 1414731536 19613856 13883344
23:47:23 6853 8192000000 10310675120 10732990464 1384447008 14.456 100 56 111 2 1422225536 23799312 13979216
23:48:38 7584 8192000000 10319266464 10739175424 1389474928 14.501 100 55 109 0 1425270800 23146064 12649808
23:49:50 7454 8192000000 10315124944 10736705536 1382292896 14.437 100 58 114 1 1421117664 25341248 13483520
23:51:04 2341 8192000000 10311908928 10716667904 1367185008 14.302 100 73 142 2 1412580912 28391680 17004224
23:52:14 4649 8192000000 10327745088 10745253888 1387433008 14.483 100 55 110 0 1431506592 30280416 13793168
23:53:22 6073 8192000000 10348876592 10760392704 1397768256 14.575 100 74 148 0 1450183216 34163504 18251456
23:54:33 1667 8192000000 10357502944 10767872000 1397988640 14.577 100 85 168 1 1459394208 40580400 20825168
23:55:40 5024 8192000000 10343194592 10755620864 1374750032 14.370 99 66 133 1 1429642448 38250016 16642400
23:56:59 6835 8192000000 10340033568 10752897024 1383280032 14.446 100 61 122 3 1443271184 43847696 16143456
23:58:14 4239 8192000000 10358722832 10773528576 1392962624 14.532 100 75 150 1 1461941008 49704528 19273856
23:59:21 11882 8192000000 10336508592 10754875392 1362572416 14.260 99 58 115 2 1433133344 54350592 16210336
00:00:48 6022 8192000000 10332856000 10759389184 1374808720 14.370 100 37 74 1 1434984032 49864656 10310656
00:02:09 22960 8192000000 10316496016 10756595712 1355086640 14.193 98 33 66 0 1412112928 47867200 9159088
00:03:40 13040 8192000000 10323689872 10758811648 1366215968 14.293 99 26 50 5 1416623056 43621104 6785984
00:04:56 4526 8192000000 10326573376 10765160448 1370295088 14.330 100 27 54 1 1429726672 51316432 8115152
00:06:30 18743 8192000000 10296950000 10748297216 1349057200 14.139 100 25 50 0 1405657120 49218112 7381808
00:07:49 55403 8192000000 10264440960 10700083200 1338898272 14.047 97 22 44 1 1354484736 10956832 4629632
00:09:50 44541 8192000000 10275684640 10719137792 1343418096 14.088 98 12 24 0 1359338208 12673344 3246768
00:11:36 71006 8192000000 10279973968 10731245568 1349139888 14.140 98 7 14 0 1359167744 8884672 1143184
00:14:05 32213 8192000000 10285327824 10710765568 1331241664 13.978 97 59 118 2 1357698656 13023328 13433664
00:15:38 90074 8192000000 10297304192 10734043136 1362691488 14.262 99 32 64 0 1386764464 16425088 7647888
00:18:13 80627 8192000000 10291073536 10743001088 1382160544 14.436 100 18 35 2 1398367040 12120096 4086400
00:20:34 12256 8192000000 10285180976 10726690816 1360860976 14.245 98 31 60 0 1378661264 11327120 6473168
00:21:48 46226 8192000000 10298477792 10746822656 1369538880 14.323 99 21 43 0 1393567472 18359008 5669584
00:23:40 12485 8192000000 10298604448 10751803392 1382990400 14.443 100 40 80 1 1408861312 16779584 9091328
00:24:57 40601 8192000000 10273100432 10743975936 1366459200 14.295 99 9 18 0 1379873616 11104336 2310080
00:27:07 27999 8192000000 10314049632 10767257600 1392802272 14.531 100 14 28 0 1412972368 17159120 3010976
00:28:36 71285 8192000000 10300797936 10766843904 1397098688 14.569 100 16 32 0 1419550400 18344160 4107552
00:31:01 36441 8192000000 10289594272 10756050944 1380661728 14.422 99 25 50 3 1400647936 14334256 5651952
00:32:48 37537 8192000000 10295094288 10745630720 1364670832 14.279 98 28 56 1 1378199296 7421968 6106496
00:34:26 41999 8192000000 10300737456 10763284480 1396693136 14.566 100 32 64 1 1421099120 16372544 8033440
00:37:05 38421 8192000000 10291738384 10738151424 1337506800 14.035 96 22 44 0 1350504368 8458624 4538944
00:38:53 6433 8192000000 10302577952 10737041408 1371505776 14.341 98 57 111 1 1391450448 6350816 13593856
00:40:01 67415 8192000000 10315563200 10757554176 1343052192 14.085 96 24 48 0 1360260512 11540352 5667968
00:42:12 69824 8192000000 10303517456 10752413696 1337919600 14.039 96 13 26 0 1351828400 11161776 2747024
00:44:25 63464 8192000000 10255147696 10700890112 1300394016 13.699 94 20 39 3 1318195744 12732192 5069536
00:46:28 103295 8192000000 10265335488 10709614592 1294413696 13.644 94 18 36 0 1308896048 10254416 4227936
00:49:17 35438 8192000000 10243075824 10673803264 1271505056 13.435 94 37 74 1 1290473424 10531952 8436416
00:50:57 44198 8192000000 10249584992 10693435392 1336397328 14.025 99 16 32 0 1347977808 8051728 3528752
00:53:41 76524 8192000000 10258551984 10712772608 1342666160 14.081 99 12 24 0 1360569568 15040896 2862512
00:56:15 41639 8192000000 10275033264 10728423424 1368896096 14.317 100 29 58 2 1392805312 17255856 6653360
00:58:04 53306 8192000000 10285542464 10735968256 1356914032 14.210 98 28 57 1 1375082384 11705712 6462640
01:00:07 71449 8192000000 10273854992 10736730112 1346529952 14.116 99 15 30 1 1366531936 15940000 4061984
01:02:20 28770 8192000000 10284124512 10728509440 1370127600 14.328 100 30 60 1 1385881696 9168704 6585392
01:03:52 23203 8192000000 10272187472 10713169920 1342710624 14.082 98 28 56 2 1358816672 9740720 6365328
01:05:16 86825 8192000000 10282983504 10741387264 1334635520 14.009 97 19 38 1 1349309712 10359920 4314272
01:07:43 120409 8192000000 10294354336 10738774016 1374247392 14.365 100 14 28 1 1386322576 8937008 3138176
01:10:45 57931 8192000000 10304170336 10760589312 1387265856 14.481 99 30 60 0 1407369472 13304160 6799456
01:12:51 79006 8192000000 10293285888 10766622720 1375531200 14.377 99 18 36 1 1403093392 23628096 3934096
01:15:28 69243 8192000000 10292692912 10755710976 1395095136 14.551 100 20 40 1 1408356176 7866080 5394960
01:17:42 69200 8192000000 10295467968 10766032896 1399154128 14.587 100 21 42 3 1416955776 13002400 4799248
01:20:05 66406 8192000000 10303435088 10762838016 1374731728 14.369 98 19 36 0 1390580016 11280320 4567968
01:22:46 68138 8192000000 10289830096 10747555840 1355425360 14.196 97 10 20 0 1369305360 11220288 2659712
01:24:56 51211 8192000000 10311051856 10769956864 1398812768 14.584 100 24 48 0 1416702736 12212976 5676992
01:26:48 43533 8192000000 10295660352 10743316480 1352396272 14.169 97 18 36 0 1368240224 11834128 4009824
01:28:36 21226 8192000000 10313356928 10756898816 1383579840 14.449 100 44 88 1 1404107280 11048992 9478448
01:30:42 49167 8192000000 10284115488 10736304128 1344442192 14.097 97 27 54 3 1360279936 9498960 6338784
01:32:32 90972 8192000000 10267695648 10715131904 1326457040 13.935 96 21 42 1 1342079040 10915840 4706160
01:35:24 34214 8192000000 10267259872 10724458496 1355805712 14.200 99 23 46 0 1370633056 9438032 5389312
01:37:02 79003 8192000000 9730016672 10106200064 858301440 9.483 62 9 18 0 867126400 7177344 1647616

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 13-04-2022 01:40:15 to 13-04-2022 01:40:30.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 96 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

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

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          237959
MON$OLDEST_ACTIVE               238440
MON$OLDEST_SNAPSHOT             238440
MON$NEXT_TRANSACTION            238440
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                       2038880
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33577 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33577 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33577 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33577 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 ---
:297: 
:298: mscs-test-161.budzdorov.ru    Wed Apr 13 01:38:34 2022
:299:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:300: 
:301: 
:302: mscs-test-161.budzdorov.ru    Wed Apr 13 01:38:35 2022
:303:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:304: 
--- end of diff output ---

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

13.04.22 01:40:34. 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-04-13 01:40:36 No severe PSQL-related problems occured
13.04.22 01:40:37. End of report.