Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 14.01.2022 16: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 16312268 kB
MemAvailable 31287780 kB
Buffers 0 kB
Cached 15380852 kB
SwapCached 2312 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.9
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-01-14 16:38:01
DTS_END                         2022-01-14 16:38:01.3770
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/50022
TRN_ID                          240525
ATT_ID                          680
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: 9555.18 23793 1146622
customer order: creation 173.22 345 20786
customer order: refuse 15.05 336 1806
order to supplier: creation 46.55 1225 5586
order to supplier: removal 3.60 100182 432
invoice (draft): creation 46.71 3355 5605
invoice (draft): removal 55.18 749 6621
invoice accept: apply 41.75 17624 5010
invoice accept: cancel 50.26 738 6031
customer reserve: creation 3885.30 173 466236
customer reserve: removal 4933.11 57 591973
realization accept: apply 83.98 170 10078
realization accept: cancel 89.15 110 10698
payment from customer: creation 59.82 127 7178
payment from customer: removal 5.74 63 689
payment to supplier: creation 49.64 140 5957
payment to supplier: removal 3.53 59 423
service: total inventory turnovers 4.94 1281 593
service: total monetary turnovers 6.67 61 800
service: aggregate perf. data 0.97 15855 116
service: refresh index statistics 0.03 333209 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 14-01-2022 16:39:31 to 14-01-2022 16:39:33.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9543
TEST_TIME 2 5472
TEST_TIME 3 4970
TEST_TIME 4 5129
TEST_TIME 5 5319
TEST_TIME 6 5492
TEST_TIME 7 5670
TEST_TIME 8 6030
TEST_TIME 9 5945
TEST_TIME 10 6019
TEST_TIME 11 5922
TEST_TIME 12 5910
TEST_TIME 13 6560
TEST_TIME 14 6625
TEST_TIME 15 7128
TEST_TIME 16 7539
TEST_TIME 17 7453
TEST_TIME 18 7769
TEST_TIME 19 7890
TEST_TIME 20 8096
TEST_TIME 21 8050
TEST_TIME 22 8806
TEST_TIME 23 8754
TEST_TIME 24 9038
TEST_TIME 25 9299
TEST_TIME 26 9890
TEST_TIME 27 10409
TEST_TIME 28 11323
TEST_TIME 29 11277
TEST_TIME 30 11295
TEST_TIME 31 11114
TEST_TIME 32 11267
TEST_TIME 33 11123
TEST_TIME 34 10893
TEST_TIME 35 10820
TEST_TIME 36 10705
TEST_TIME 37 10707
TEST_TIME 38 10632
TEST_TIME 39 10561
TEST_TIME 40 10500
TEST_TIME 41 10382
TEST_TIME 42 10384
TEST_TIME 43 10316
TEST_TIME 44 10444
TEST_TIME 45 10346
TEST_TIME 46 10238
TEST_TIME 47 10134
TEST_TIME 48 10065
TEST_TIME 49 10106
TEST_TIME 50 10061
TEST_TIME 51 10038
TEST_TIME 52 9893
TEST_TIME 53 9922
TEST_TIME 54 9889
TEST_TIME 55 9866
TEST_TIME 56 9870
TEST_TIME 57 9871
TEST_TIME 58 9917
TEST_TIME 59 9943
TEST_TIME 60 10191
TEST_TIME 61 10288
TEST_TIME 62 10240
TEST_TIME 63 10348
TEST_TIME 64 10375
TEST_TIME 65 10382
TEST_TIME 66 10385
TEST_TIME 67 10404
TEST_TIME 68 10317
TEST_TIME 69 10215
TEST_TIME 70 10206
TEST_TIME 71 10186
TEST_TIME 72 10167
TEST_TIME 73 10098
TEST_TIME 74 10120
TEST_TIME 75 10026
TEST_TIME 76 10038
TEST_TIME 77 10001
TEST_TIME 78 9905
TEST_TIME 79 9883
TEST_TIME 80 9940
TEST_TIME 81 9928
TEST_TIME 82 9920
TEST_TIME 83 9896
TEST_TIME 84 9930
TEST_TIME 85 9939
TEST_TIME 86 9970
TEST_TIME 87 9932
TEST_TIME 88 9983
TEST_TIME 89 9945
TEST_TIME 90 10081
TEST_TIME 91 10065
TEST_TIME 92 10032
TEST_TIME 93 9933
TEST_TIME 94 9898
TEST_TIME 95 9916
TEST_TIME 96 9901
TEST_TIME 97 9881
TEST_TIME 98 9852
TEST_TIME 99 9861
TEST_TIME 100 9827
TEST_TIME 101 9820
TEST_TIME 102 9805
TEST_TIME 103 9947
TEST_TIME 104 9900
TEST_TIME 105 9974
TEST_TIME 106 9976
TEST_TIME 107 9935
TEST_TIME 108 9944
TEST_TIME 109 9913
TEST_TIME 110 9837
TEST_TIME 111 9831
TEST_TIME 112 9782
TEST_TIME 113 9769
TEST_TIME 114 9770
TEST_TIME 115 9723
TEST_TIME 116 9691
TEST_TIME 117 9657
TEST_TIME 118 9665
TEST_TIME 119 9638
TEST_TIME 120 9560

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 14-01-2022 16:39:34 to 14-01-2022 16:39:38.

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 1578297 1578297 0 0.00 0 24439 6 0 0
doc_list_biud 1578297 1578297 0 0.00 0 32142 27 0 0
sp_add_invoice_to_stock 5104 5010 94 1.84 30 166320 17624 0 94
sp_cancel_adding_invoice 6031 6031 0 0.00 4 76062 738 0 0
sp_cancel_client_order 1806 1806 0 0.00 4 10045 336 0 0
sp_cancel_customer_reserve 591973 591973 0 0.00 1 32145 57 0 0
sp_cancel_pay_from_customer 689 689 0 0.00 2 3571 63 0 0
sp_cancel_pay_to_supplier 423 423 0 0.00 3 1925 60 0 0
sp_cancel_supplier_invoice 6628 6621 7 0.10 1 24101 749 0 7
sp_cancel_supplier_order 432 432 0 0.00 7 1213009 100182 0 0
sp_cancel_write_off 10698 10698 0 0.00 1 12574 111 0 0
sp_client_order 20786 20786 0 0.00 4 33276 345 0 0
sp_customer_reserve 466236 466236 0 0.00 0 37574 174 0 0
sp_fill_shopping_cart 31976 31976 0 0.00 1 14614 35 0 0
sp_fill_shopping_cart_clo_res 466228 466228 0 0.00 0 29912 14 0 0
sp_get_clo_for_invoice 4998 4998 0 0.00 0 29334 292 0 0
sp_kill_cost_storno 17841 17841 0 0.00 0 12549 59 0 0
sp_kill_qstorno_ret_qs2qd 1201697 1201697 0 0.00 0 32139 31 0 0
sp_kill_qty_storno 632636 632636 0 0.00 0 32141 66 0 0
sp_lock_selected_doc 21021 21021 0 0.00 0 6460 4 0 0
sp_make_cost_storno 28210 28210 0 0.00 0 12851 57 0 0
sp_multiply_rows_for_pdistr 28210 28210 0 0.00 0 13980 32 0 0
sp_multiply_rows_for_qdistr 20786 20786 0 0.00 0 9327 147 0 0
sp_pay_from_customer 7178 7178 0 0.00 4 22582 127 0 0
sp_pay_to_supplier 5957 5957 0 0.00 5 13992 140 0 0
sp_payment_common 13135 13135 0 0.00 1 22577 32 0 0
sp_qd_handle_on_cancel_clo 1806 1806 0 0.00 0 10038 309 0 0
sp_qd_handle_on_invoice_upd_sts 11028 11028 0 0.00 0 31560 319 0 0
sp_qd_handle_on_reserve_upd_sts 10078 10078 0 0.00 0 17966 51 0 0
sp_reserve_write_off 10078 10078 0 0.00 6 17981 171 0 0
sp_supplier_invoice 5605 5605 0 0.00 6 90489 3356 0 0
sp_supplier_order 5586 5586 0 0.00 5 39399 1226 0 0
srv_aggregate_perf_data 116 116 0 0.00 2976 189076 15855 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 2078 132381 44815 0 0
srv_find_qd_qs_mism 1098345 1098345 0 0.00 0 24426 5 0 0
srv_make_invnt_saldo 593 593 0 0.00 53 119991 1282 0 0
srv_make_money_saldo 800 800 0 0.00 1 20493 62 0 0
srv_recalc_idx_stat 4 4 0 0.00 214355 420466 333209 0 0
t$perf-abend:sp_add_invoice_to_stock 94 94 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5010 5010 0 0.00 0 16529 192 0 0
t$perf-norm:sp_cancel_adding_invoice 398 398 0 0.00 0 9444 143 0 0
t$perf-norm:sp_cancel_client_order 1806 1806 0 0.00 0 7892 18 0 0
t$perf-norm:sp_cancel_customer_reserve 711 711 0 0.00 0 2421 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 689 689 0 0.00 0 272 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 423 423 0 0.00 0 478 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 421 421 0 0.00 0 2408 22 0 0
t$perf-norm:sp_cancel_supplier_order 432 432 0 0.00 0 24496 1464 0 0
t$perf-norm:sp_cancel_write_off 1066 1066 0 0.00 0 1712 8 0 0
t$perf-norm:sp_client_order 20786 20786 0 0.00 0 7643 19 0 0
t$perf-norm:sp_customer_reserve 956 956 0 0.00 0 7547 11 0 0
t$perf-norm:sp_pay_from_customer 7178 7178 0 0.00 0 5718 11 0 0
t$perf-norm:sp_pay_to_supplier 5957 5957 0 0.00 0 5724 13 0 0
t$perf-norm:sp_reserve_write_off 10078 10078 0 0.00 0 8243 18 0 0
t$perf-norm:sp_supplier_invoice 5605 5605 0 0.00 0 7805 19 0 0
t$perf-norm:sp_supplier_order 5586 5586 0 0.00 0 12887 16 0 0
t$perf-norm:srv_aggregate_perf_data 116 116 0 0.00 0 356 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 272 8 0 0
t$perf-norm:srv_make_invnt_saldo 593 593 0 0.00 0 2853 22 0 0
t$perf-norm:srv_make_money_saldo 800 800 0 0.00 0 4794 25 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 13 5 0 0
v_add_invoice_to_stock 10182 9994 188 1.84 0 11162 21 0 188
v_all_customers 38404 38404 0 0.00 0 50 1 0 0
v_all_suppliers 31380 31380 0 0.00 0 69 1 0 0
v_all_wares 41571 41571 0 0.00 0 107 1 0 0
v_cancel_adding_invoice 796 796 0 0.00 0 2697 9 0 0
v_cancel_client_order 3612 3612 0 0.00 0 3013 5 0 0
v_cancel_customer_prepayment 1378 1378 0 0.00 0 1283 5 0 0
v_cancel_customer_reserve 1422 1422 0 0.00 0 265 3 0 0
v_cancel_payment_to_supplier 846 846 0 0.00 0 1911 7 0 0
v_cancel_supplier_invoice 856 842 14 1.63 0 1658 16 0 14
v_cancel_supplier_order 864 864 0 0.00 0 1561 6 0 0
v_cancel_write_off 2132 2132 0 0.00 0 1765 14 0 0
v_max_id_clo_ord 5586 5586 0 0.00 0 2645 2 0 0
v_max_id_clo_res 956 956 0 0.00 0 3344 14 0 0
v_max_id_ord_sup 5604 5604 0 0.00 0 2161 2 0 0
v_max_non_paid_invoice 1457 1457 0 0.00 0 2144 5 0 0
v_max_non_paid_realizn 3630 3630 0 0.00 0 3240 6 0 0
v_min_id_clo_ord 5586 5586 0 0.00 0 1671 6 0 0
v_min_id_clo_res 956 956 0 0.00 0 1495 4 0 0
v_min_id_ord_sup 5604 5604 0 0.00 0 5512 7 0 0
v_min_non_paid_invoice 5957 5957 0 0.00 0 12576 24 0 0
v_min_non_paid_realizn 7178 7178 0 0.00 0 22573 27 0 0
v_our_firm 10262 10262 0 0.00 0 14 1 0 0
v_reserve_write_off 20156 20156 0 0.00 0 4166 6 0 0
x_lock_depdocs_on_canc_invoice 12231 12231 0 0.00 0 12839 114 0 0
x_lock_depdocs_on_canc_sup_ord 432 432 0 0.00 0 7289 351 0 0
x_make_qstorno_1000_1200 5586 5586 0 0.00 2 39390 1148 0 0
x_make_qstorno_1200_2000 5605 5605 0 0.00 3 90468 3239 0 0
x_make_qstorno_2100_3300 466236 466236 0 0.00 0 37572 157 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 14-01-2022 16:39:39 to 14-01-2022 16:39:43.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
14:08:05 19 8192000000 8817501888 8835276800 19178224 0.233 5 5 6 0 19447792 98112 171456
14:09:06 24568 8192000000 9909245680 10219524096 980893376 10.693 100 84 168 0 1009474048 6053264 22527408
14:10:33 28742 8192000000 9959958064 10286956544 1043894880 11.302 100 89 177 0 1073929648 7539568 22495200
14:12:11 16282 8192000000 9989144176 10325307392 1078137392 11.630 100 83 166 0 1107805856 9408624 20259840
14:13:30 16303 8192000000 10034610976 10385391616 1123904784 12.064 100 89 178 1 1156017536 11254496 20858256
14:14:50 13585 8192000000 10095766464 10454339584 1167943440 12.478 100 85 170 1 1200826256 13458768 19424048
14:16:05 7738 8192000000 10127294928 10494287872 1220607568 12.967 100 85 168 1 1252668560 13591376 18469616
14:17:13 3782 8192000000 10152829664 10526072832 1245939152 13.201 100 84 166 2 1276201648 11608352 18654144
14:18:19 2497 8192000000 10190140656 10571370496 1275188144 13.469 100 87 172 0 1309416848 15519888 18708816
14:19:22 2027 8192000000 10213110992 10596270080 1291537520 13.618 100 94 188 1 1326327248 13509200 21280528
14:20:26 20668 8192000000 10204320624 10606501888 1302717536 13.720 100 51 102 1 1323163776 9008112 11438128
14:21:48 5694 8192000000 10204846576 10612699136 1309566048 13.782 100 41 82 1 1325931904 6645872 9719984
14:22:56 14137 8192000000 10196869808 10617266176 1310824000 13.794 100 13 26 0 1323840928 9986736 3030192
14:24:15 71555 8192000000 10218675760 10637869056 1321321296 13.889 100 33 66 2 1334549216 5018224 8209696
14:26:27 5181 8192000000 10219373424 10636374016 1312216416 13.806 99 32 64 1 1325865024 5921904 7726704
14:27:34 5065 8192000000 10221357408 10646654976 1327373536 13.943 100 25 50 1 1339157232 5530752 6252944
14:28:39 11890 8192000000 10194491584 10610708480 1252722752 13.263 95 17 35 1 1261712832 5167408 3822672
14:29:52 23878 8192000000 10170148624 10579546112 1238161376 13.129 96 30 60 1 1251376592 6923712 6291504
14:31:23 3006 8192000000 10146698752 10543112192 1228202640 13.037 98 39 79 4 1245067040 7384832 9479568
14:32:26 6415 8192000000 10152173520 10545774592 1235588288 13.106 99 32 64 2 1249542608 7040144 6914176
14:33:35 74434 8192000000 10161270528 10572169216 1263251840 13.360 99 31 62 1 1276058304 5350416 7456048
14:35:49 8677 8192000000 10190665552 10600013824 1292975184 13.631 100 25 50 2 1304117392 5206944 5935264
14:36:58 7491 8192000000 10192488576 10608308224 1304388560 13.735 100 18 36 2 1313929312 5565168 3975584
14:38:08 11960 8192000000 10236749696 10640666624 1328347328 13.952 100 57 115 2 1349064448 6886848 13830272
14:39:25 84398 8192000000 10252629168 10670419968 1349913424 14.147 100 44 88 1 1366792032 6337600 10541008
14:42:20 26194 8192000000 10282624048 10710384640 1373002912 14.354 100 33 67 1 1392478080 11655360 7819808
14:43:48 5489 8192000000 10298244432 10715967488 1378980000 14.407 100 56 110 2 1408041232 15913872 13147360
14:45:00 11727 8192000000 10326558480 10744520704 1393476256 14.537 100 53 105 1 1427546560 21416992 12653312
14:46:17 8045 8192000000 10329807152 10750373888 1402784880 14.620 100 51 101 1 1439167264 23795584 12586800
14:47:31 2076 8192000000 10345244768 10768297984 1414546912 14.724 100 64 128 1 1453675152 23200512 15927728
14:48:39 10606 8192000000 10368370928 10794278912 1429622992 14.858 100 62 121 0 1475216128 31239728 14353408
14:49:57 5803 8192000000 10368269808 10792198144 1420772608 14.780 99 56 112 3 1472057776 37357488 13927680
14:51:09 10532 8192000000 10378408064 10800099328 1425691984 14.823 100 64 126 2 1483013952 41939536 15382432
14:52:24 3956 8192000000 10367405680 10786676736 1407690768 14.663 99 75 150 1 1465836480 38522512 19623200
14:53:31 5143 8192000000 10378908016 10804166656 1422784464 14.797 100 62 125 0 1484476352 45729200 15962688
14:54:50 5656 8192000000 10348749184 10778812416 1396659168 14.565 99 35 70 1 1446606736 40211872 9735696
14:56:07 2241 8192000000 10402093200 10815430656 1427774384 14.842 100 91 181 0 1501551792 50917296 22860112
14:57:14 3421 8192000000 10373074912 10792792064 1409954688 14.684 100 51 102 1 1473293424 49697296 13641440
14:58:21 3184 8192000000 10386520272 10800340992 1387325280 14.482 98 73 145 1 1465256288 59007888 18923120
14:59:26 4521 8192000000 10364467264 10782973952 1381525600 14.430 99 51 102 2 1455136608 59006144 14604864
15:00:45 15567 8192000000 10346402816 10778796032 1388553888 14.493 100 40 80 1 1458088640 58682752 10852000
15:02:11 18749 8192000000 10325210192 10755846144 1368012768 14.309 100 34 68 1 1435266096 56924288 10329040
15:03:32 26101 8192000000 10305473856 10738429952 1352953584 14.174 100 30 60 3 1417560176 56282960 8323632
15:05:41 53427 8192000000 10242975696 10704388096 1286588096 13.573 97 16 32 0 1337677424 46843296 4246032
15:07:46 70032 8192000000 10272125344 10713718784 1346703424 14.118 100 8 16 0 1370318000 22434448 1180128
15:10:38 132381 8192000000 10261591104 10704441344 1316922720 13.849 97 15 31 0 1336262944 15777152 3563072
15:14:06 31868 8192000000 10281173808 10723770368 1371577488 14.341 100 38 76 2 1389466848 10039888 7849472
15:15:39 86003 8192000000 10282053328 10744631296 1375834848 14.379 100 20 41 0 1398910928 18799856 4276224
15:18:21 30933 8192000000 10282902768 10733285376 1369985712 14.327 99 21 42 0 1382559344 8011472 4562160
15:19:57 25982 8192000000 10314408832 10756960256 1393862752 14.540 100 43 86 1 1414658384 10728384 10067248
15:21:24 39094 8192000000 10303230112 10758688768 1383569808 14.448 99 15 31 0 1400630688 13536640 3524240
15:23:04 21568 8192000000 10293830528 10770587648 1394459232 14.546 100 7 14 0 1409441776 13388144 1594400
15:24:38 41938 8192000000 10291764080 10752602112 1392604336 14.529 100 16 32 0 1407097856 10596112 3897408
15:26:20 73740 8192000000 10290397472 10750078976 1372852928 14.353 98 17 34 0 1386694720 9943024 3898768
15:28:35 11553 8192000000 10300116848 10757361664 1392706880 14.530 100 21 42 0 1410400560 13213360 4480320
15:29:47 53282 8192000000 10293265456 10761109504 1389808112 14.504 99 18 35 1 1404105360 10358320 3938928
15:31:50 54430 8192000000 10281456560 10754510848 1369672448 14.324 99 9 18 0 1383044896 11262608 2109840
15:33:48 55889 8192000000 10289006336 10750357504 1363395344 14.268 98 14 28 0 1379176208 12414656 3366208
15:36:10 61604 8192000000 10310687040 10772557824 1371740992 14.343 98 15 30 1 1407044400 31609792 3693616
15:38:19 67753 8192000000 10302660416 10773331968 1369271120 14.321 98 14 28 0 1404918736 32181536 3466080
15:40:38 35276 8192000000 10294102016 10753224704 1374994736 14.372 99 32 64 0 1395033264 12896128 7142400
15:42:17 48699 8192000000 10288521376 10745180160 1347958672 14.129 97 8 16 0 1362563568 12771168 1833728
15:44:14 60312 8192000000 10286673888 10734555136 1346995104 14.120 98 25 51 0 1367087584 14215856 5876624
15:46:15 38289 8192000000 10291716016 10734202880 1351342048 14.160 98 27 54 0 1367546528 9755056 6449424
15:47:54 90569 8192000000 10277045008 10748858368 1364788048 14.280 99 21 42 0 1386823264 16999856 5035360
15:50:33 38644 8192000000 10247783568 10704187392 1334201008 14.005 97 15 30 1 1347142896 9409136 3532752
15:52:12 68343 8192000000 10271802352 10740502528 1359913264 14.237 99 10 20 0 1378756832 16523008 2320560
15:54:23 61372 8192000000 10263662976 10728718336 1351257600 14.159 99 8 16 1 1367151360 13989200 1904560
15:56:25 60528 8192000000 10238400144 10689167360 1286259072 13.570 95 20 40 0 1303526880 12290560 4977248
15:58:26 47548 8192000000 10245022080 10698448896 1334726112 14.010 99 12 24 0 1344806608 8000976 2079520
16:00:27 52041 8192000000 10269699920 10715553792 1341194944 14.068 99 27 54 0 1357095632 10082816 5817872
16:02:29 53018 8192000000 10256110224 10712297472 1322589184 13.900 97 20 40 1 1345842880 18769344 4484352
16:04:27 43920 8192000000 10270691792 10705657856 1340195760 14.059 98 34 66 1 1356552704 8240896 8116048
16:06:32 48813 8192000000 10281556976 10731253760 1360396384 14.241 99 28 56 0 1381928224 15318784 6213056
16:08:25 84932 8192000000 10300406016 10754146304 1381574304 14.431 99 13 26 1 1394413104 10161920 2676880
16:10:51 129559 8192000000 10299620144 10767765504 1398912032 14.585 100 22 44 0 1423993760 19875984 5205744
16:14:03 66201 8192000000 10281600400 10745847808 1372885872 14.353 98 9 16 1 1384246480 9586416 1774192
16:16:11 61039 8192000000 10286299232 10734739456 1338112656 14.040 96 23 45 1 1350546928 7453456 4980816
16:18:12 47827 8192000000 10317134064 10764578816 1386856592 14.478 99 32 63 2 1403885392 9898576 7130224
16:20:18 94595 8192000000 10309230096 10799411200 1401565200 14.609 100 11 22 0 1427141920 23028880 2547840
16:22:54 124588 8192000000 10290200192 10754330624 1356002800 14.201 97 10 20 0 1370411968 12173168 2236000
16:26:06 56269 8192000000 10287795776 10736562176 1322530560 13.900 95 26 52 0 1340085392 12210608 5344224
16:28:04 68611 8192000000 10278141152 10715254784 1345026736 14.103 98 64 128 2 1370950608 11290304 14633568
16:30:18 59689 8192000000 10252281088 10703986688 1329434976 13.962 97 33 66 0 1344733776 7528704 7770096
16:32:18 74257 8192000000 10262424576 10730065920 1369535904 14.323 100 12 24 0 1380829328 8756000 2537424
16:34:50 62213 8192000000 10259065216 10724032512 1355337920 14.195 99 12 22 2 1369009840 11206800 2465120
16:37:14 14107 8192000000 10248993920 10693513216 1333432960 13.998 99 32 64 3 1347990032 6955200 7601872

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 14-01-2022 16:39:45 to 14-01-2022 16:39:59.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 14-01-2022 16:40:00 to 14-01-2022 16:40:02.

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          239739
MON$OLDEST_ACTIVE               241066
MON$OLDEST_SNAPSHOT             241066
MON$NEXT_TRANSACTION            241066
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                       2044960
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.9.33548 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.9.33548 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.9.33548 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.9.33548 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
--- end of diff output ---

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

14.01.22 16:40:03. 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-01-14 16:40:06 No severe PSQL-related problems occured
14.01.22 16:40:07. End of report.