Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 20.11.2021 19: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 16708932 kB
MemAvailable 31609568 kB
Buffers 0 kB
Cached 14989620 kB
SwapCached 0 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.8
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 2021-11-20 19:37:54
DTS_END                         2021-11-20 19:37:54.3170
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/41840
TRN_ID                          238400
ATT_ID                          671
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: 9338.43 20139 1120612
customer order: creation 170.08 371 20410
customer order: refuse 14.53 361 1744
order to supplier: creation 45.18 1298 5422
order to supplier: removal 3.33 104679 400
invoice (draft): creation 44.94 3621 5393
invoice (draft): removal 53.68 745 6441
invoice accept: apply 41.53 18124 4983
invoice accept: cancel 48.27 782 5792
customer reserve: creation 3881.87 178 465824
customer reserve: removal 4743.77 58 569253
realization accept: apply 81.18 194 9742
realization accept: cancel 83.93 107 10071
payment from customer: creation 57.66 112 6919
payment from customer: removal 5.56 82 667
payment to supplier: creation 47.74 148 5729
payment to supplier: removal 3.53 65 424
service: total inventory turnovers 4.47 1252 536
service: total monetary turnovers 6.37 27 764
service: aggregate perf. data 0.78 22194 94
service: refresh index statistics 0.03 248382 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 20-11-2021 19:39:53 to 20-11-2021 19:39:55.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1539
TEST_TIME 2 2732
TEST_TIME 3 2260
TEST_TIME 4 3300
TEST_TIME 5 3718
TEST_TIME 6 4309
TEST_TIME 7 4509
TEST_TIME 8 4627
TEST_TIME 9 4659
TEST_TIME 10 5011
TEST_TIME 11 5153
TEST_TIME 12 5095
TEST_TIME 13 5266
TEST_TIME 14 5538
TEST_TIME 15 5875
TEST_TIME 16 5948
TEST_TIME 17 6134
TEST_TIME 18 6326
TEST_TIME 19 6474
TEST_TIME 20 7127
TEST_TIME 21 7762
TEST_TIME 22 7879
TEST_TIME 23 8378
TEST_TIME 24 8441
TEST_TIME 25 9067
TEST_TIME 26 9122
TEST_TIME 27 9536
TEST_TIME 28 9611
TEST_TIME 29 10309
TEST_TIME 30 10102
TEST_TIME 31 10198
TEST_TIME 32 10241
TEST_TIME 33 10189
TEST_TIME 34 10478
TEST_TIME 35 10502
TEST_TIME 36 10343
TEST_TIME 37 10091
TEST_TIME 38 9964
TEST_TIME 39 9984
TEST_TIME 40 9805
TEST_TIME 41 9912
TEST_TIME 42 9802
TEST_TIME 43 9755
TEST_TIME 44 9845
TEST_TIME 45 9764
TEST_TIME 46 9688
TEST_TIME 47 9579
TEST_TIME 48 9695
TEST_TIME 49 9653
TEST_TIME 50 9582
TEST_TIME 51 9602
TEST_TIME 52 9475
TEST_TIME 53 9463
TEST_TIME 54 9464
TEST_TIME 55 9383
TEST_TIME 56 9493
TEST_TIME 57 9618
TEST_TIME 58 9566
TEST_TIME 59 9624
TEST_TIME 60 9631
TEST_TIME 61 9663
TEST_TIME 62 9652
TEST_TIME 63 9726
TEST_TIME 64 9714
TEST_TIME 65 9741
TEST_TIME 66 9678
TEST_TIME 67 9880
TEST_TIME 68 9850
TEST_TIME 69 9724
TEST_TIME 70 9686
TEST_TIME 71 9630
TEST_TIME 72 9599
TEST_TIME 73 9594
TEST_TIME 74 9543
TEST_TIME 75 9476
TEST_TIME 76 9468
TEST_TIME 77 9440
TEST_TIME 78 9435
TEST_TIME 79 9446
TEST_TIME 80 9451
TEST_TIME 81 9421
TEST_TIME 82 9453
TEST_TIME 83 9463
TEST_TIME 84 9530
TEST_TIME 85 9498
TEST_TIME 86 9486
TEST_TIME 87 9487
TEST_TIME 88 9486
TEST_TIME 89 9575
TEST_TIME 90 9596
TEST_TIME 91 9640
TEST_TIME 92 9552
TEST_TIME 93 9520
TEST_TIME 94 9501
TEST_TIME 95 9467
TEST_TIME 96 9408
TEST_TIME 97 9357
TEST_TIME 98 9403
TEST_TIME 99 9397
TEST_TIME 100 9436
TEST_TIME 101 9417
TEST_TIME 102 9382
TEST_TIME 103 9480
TEST_TIME 104 9470
TEST_TIME 105 9477
TEST_TIME 106 9475
TEST_TIME 107 9471
TEST_TIME 108 9497
TEST_TIME 109 9502
TEST_TIME 110 9576
TEST_TIME 111 9539
TEST_TIME 112 9514
TEST_TIME 113 9439
TEST_TIME 114 9453
TEST_TIME 115 9475
TEST_TIME 116 9425
TEST_TIME 117 9423
TEST_TIME 118 9398
TEST_TIME 119 9363
TEST_TIME 120 9340

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 20-11-2021 19:39:57 to 20-11-2021 19:40:00.

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 1552464 1552464 0 0.00 0 22450 6 0 0
doc_list_biud 1552464 1552464 0 0.00 0 33643 27 0 0
sp_add_invoice_to_stock 5062 4983 79 1.56 50 172766 18124 0 79
sp_cancel_adding_invoice 5792 5792 0 0.00 5 76892 783 0 0
sp_cancel_client_order 1744 1744 0 0.00 4 14114 361 0 0
sp_cancel_customer_reserve 569253 569253 0 0.00 1 24847 59 0 0
sp_cancel_pay_from_customer 667 667 0 0.00 3 3639 83 0 0
sp_cancel_pay_to_supplier 424 424 0 0.00 3 1277 65 0 0
sp_cancel_supplier_invoice 6453 6441 12 0.18 2 23263 746 0 12
sp_cancel_supplier_order 400 400 0 0.00 11 1152768 104680 0 0
sp_cancel_write_off 10071 10071 0 0.00 1 16586 107 0 0
sp_client_order 20410 20410 0 0.00 3 19943 371 0 0
sp_customer_reserve 465824 465824 0 0.00 0 85401 178 0 0
sp_fill_shopping_cart 31220 31220 0 0.00 1 20589 40 0 0
sp_fill_shopping_cart_clo_res 465819 465819 0 0.00 0 17703 13 0 0
sp_get_clo_for_invoice 4978 4978 0 0.00 0 26026 284 0 0
sp_kill_cost_storno 16954 16954 0 0.00 0 22440 56 0 0
sp_kill_qstorno_ret_qs2qd 1155418 1155418 0 0.00 0 24843 32 0 0
sp_kill_qty_storno 608421 608421 0 0.00 0 33643 69 0 0
sp_lock_selected_doc 20452 20452 0 0.00 0 9464 6 0 0
sp_make_cost_storno 27368 27368 0 0.00 0 16401 57 0 0
sp_multiply_rows_for_pdistr 27368 27368 0 0.00 0 8104 30 0 0
sp_multiply_rows_for_qdistr 20410 20410 0 0.00 0 19922 156 0 0
sp_pay_from_customer 6919 6919 0 0.00 4 13821 112 0 0
sp_pay_to_supplier 5729 5729 0 0.00 5 15176 148 0 0
sp_payment_common 12648 12648 0 0.00 2 15168 30 0 0
sp_qd_handle_on_cancel_clo 1744 1744 0 0.00 0 14099 328 0 0
sp_qd_handle_on_invoice_upd_sts 10770 10770 0 0.00 0 33642 331 0 0
sp_qd_handle_on_reserve_upd_sts 9742 9742 0 0.00 0 18254 70 0 0
sp_reserve_write_off 9742 9742 0 0.00 6 22142 195 0 0
sp_supplier_invoice 5393 5393 0 0.00 6 97800 3622 0 0
sp_supplier_order 5422 5422 0 0.00 4 27697 1298 0 0
srv_aggregate_perf_data 94 94 0 0.00 3033 416496 22195 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 2898 122546 45926 0 0
srv_find_qd_qs_mism 1073576 1073576 0 0.00 0 19323 5 0 0
srv_make_invnt_saldo 536 536 0 0.00 33 42076 1252 0 0
srv_make_money_saldo 764 764 0 0.00 1 2356 27 0 0
srv_recalc_idx_stat 4 4 0 0.00 92199 373075 248383 0 0
t$perf-abend:sp_add_invoice_to_stock 79 79 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4984 4984 0 0.00 0 15408 167 0 0
t$perf-norm:sp_cancel_adding_invoice 413 413 0 0.00 0 2352 89 0 0
t$perf-norm:sp_cancel_client_order 1744 1744 0 0.00 0 2529 12 0 0
t$perf-norm:sp_cancel_customer_reserve 701 701 0 0.00 0 28719 51 0 0
t$perf-norm:sp_cancel_pay_from_customer 667 667 0 0.00 0 318 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 424 424 0 0.00 0 762 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 426 426 0 0.00 0 2414 18 0 0
t$perf-norm:sp_cancel_supplier_order 400 400 0 0.00 0 25597 1793 0 0
t$perf-norm:sp_cancel_write_off 957 957 0 0.00 0 2767 15 0 0
t$perf-norm:sp_client_order 20410 20410 0 0.00 0 14837 22 0 0
t$perf-norm:sp_customer_reserve 951 951 0 0.00 0 1073 5 0 0
t$perf-norm:sp_pay_from_customer 6919 6919 0 0.00 0 14811 13 0 0
t$perf-norm:sp_pay_to_supplier 5729 5729 0 0.00 0 14842 13 0 0
t$perf-norm:sp_reserve_write_off 9742 9742 0 0.00 0 17888 16 0 0
t$perf-norm:sp_supplier_invoice 5394 5394 0 0.00 0 5676 16 0 0
t$perf-norm:sp_supplier_order 5422 5422 0 0.00 0 12502 16 0 0
t$perf-norm:srv_aggregate_perf_data 94 94 0 0.00 0 758 15 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 73 3 0 0
t$perf-norm:srv_make_invnt_saldo 536 536 0 0.00 0 1779 30 0 0
t$perf-norm:srv_make_money_saldo 764 764 0 0.00 0 911 12 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 6 3 0 0
v_add_invoice_to_stock 10114 9956 158 1.56 0 6880 19 0 158
v_all_customers 36870 36870 0 0.00 0 16 1 0 0
v_all_suppliers 29574 29574 0 0.00 0 25 1 0 0
v_all_wares 40820 40820 0 0.00 0 24 1 0 0
v_cancel_adding_invoice 826 826 0 0.00 0 4865 12 0 0
v_cancel_client_order 3488 3488 0 0.00 0 7047 9 0 0
v_cancel_customer_prepayment 1334 1334 0 0.00 0 3497 7 0 0
v_cancel_customer_reserve 1402 1402 0 0.00 0 6203 20 0 0
v_cancel_payment_to_supplier 848 848 0 0.00 0 275 3 0 0
v_cancel_supplier_invoice 876 852 24 2.73 0 4518 22 0 24
v_cancel_supplier_order 800 800 0 0.00 0 371 4 0 0
v_cancel_write_off 1914 1914 0 0.00 0 5258 17 0 0
v_max_id_clo_ord 5422 5422 0 0.00 0 1515 2 0 0
v_max_id_clo_res 951 951 0 0.00 0 1599 10 0 0
v_max_id_ord_sup 5388 5388 0 0.00 0 6929 5 0 0
v_max_non_paid_invoice 1752 1752 0 0.00 0 7062 7 0 0
v_max_non_paid_realizn 3702 3702 0 0.00 0 4248 5 0 0
v_min_id_clo_ord 5422 5422 0 0.00 0 6044 8 0 0
v_min_id_clo_res 951 951 0 0.00 0 2018 6 0 0
v_min_id_ord_sup 5388 5388 0 0.00 0 7794 13 0 0
v_min_non_paid_invoice 5729 5729 0 0.00 0 15163 27 0 0
v_min_non_paid_realizn 6919 6919 0 0.00 0 8372 17 0 0
v_our_firm 10384 10384 0 0.00 0 20 1 0 0
v_reserve_write_off 19484 19484 0 0.00 0 7647 6 0 0
x_lock_depdocs_on_canc_invoice 11807 11807 0 0.00 0 20464 113 0 0
x_lock_depdocs_on_canc_sup_ord 400 400 0 0.00 0 11066 419 0 0
x_make_qstorno_1000_1200 5422 5422 0 0.00 1 27688 1210 0 0
x_make_qstorno_1200_2000 5393 5393 0 0.00 3 97706 3481 0 0
x_make_qstorno_2100_3300 465824 465824 0 0.00 0 85399 163 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 20-11-2021 19:40:01 to 20-11-2021 19:40:05.

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
17:07:58 5 8192000000 8798508736 8809775104 4972256 0.060 2 1 2 0 5071856 56816 42784
17:08:59 21993 8192000000 9891665200 10200662016 977134848 10.656 100 88 176 0 1006977488 5988608 23854032
17:10:28 16352 8192000000 9953356944 10276761600 1035091424 11.217 100 92 184 0 1066749312 8034016 23623872
17:11:50 15618 8192000000 9988829824 10323501056 1068612048 11.539 100 85 170 0 1099885136 10172336 21100752
17:13:12 12894 8192000000 10040181216 10389643264 1130164720 12.123 100 83 166 2 1162388096 12958992 19264384
17:14:28 10509 8192000000 10094394784 10456199168 1179901568 12.589 100 80 160 0 1210630928 12578496 18150864
17:15:42 3755 8192000000 10137941168 10506862592 1219858544 12.960 100 74 148 0 1249640608 13043664 16738400
17:16:47 3823 8192000000 10165466000 10543788032 1249719408 13.236 100 81 163 1 1280417824 12361888 18336528
17:17:53 1800 8192000000 10198852160 10580406272 1282369200 13.535 100 89 178 1 1315734832 13816560 19549072
17:18:56 22506 8192000000 10202693024 10605666304 1303990848 13.732 100 58 116 1 1326598800 8498464 14109488
17:20:26 5414 8192000000 10208304848 10612957184 1314359312 13.826 100 47 91 3 1332850544 8334032 10157200
17:21:32 11230 8192000000 10194625152 10616328192 1316671712 13.847 100 17 34 1 1327270320 6626320 3972288
17:22:44 6783 8192000000 10207443504 10625925120 1323236352 13.906 100 22 41 1 1333555152 5459856 4858944
17:23:52 8246 8192000000 10211993120 10631696384 1329626832 13.964 100 17 34 1 1338993968 4832960 4534176
17:25:02 15988 8192000000 10220814544 10645118976 1331034976 13.977 100 22 43 0 1341754912 6020896 4699040
17:26:19 4580 8192000000 10224984032 10646298624 1332517728 13.990 100 23 46 2 1343990832 6041744 5431360
17:27:23 6090 8192000000 10216383504 10636779520 1321983824 13.895 100 19 38 2 1332273472 5538368 4751280
17:28:30 9000 8192000000 10182229728 10590953472 1249955408 13.238 96 22 43 1 1260538704 5636896 4946400
17:29:40 28871 8192000000 10154558192 10557304832 1155054928 12.357 90 22 45 1 1164765760 5250656 4460176
17:31:10 4232 8192000000 10148656368 10547744768 1251831472 13.255 100 31 62 2 1265942432 6999984 7110976
17:32:16 10562 8192000000 10152878128 10558787584 1262199152 13.350 100 30 60 2 1274819744 4905184 7715408
17:33:30 72986 8192000000 10179797632 10591113216 1288625936 13.592 100 37 73 1 1303259152 5194320 9438896
17:35:43 7746 8192000000 10195159856 10610597888 1302759680 13.720 100 22 44 1 1313907184 6164464 4983040
17:36:52 10543 8192000000 10203567392 10623303680 1310216304 13.788 100 20 40 0 1321213648 6738512 4258832
17:38:07 25591 8192000000 10242753440 10656694272 1338349760 14.043 100 42 82 1 1352204848 4746608 9108480
17:39:41 11426 8192000000 10273517328 10682548224 1355630016 14.198 100 64 128 0 1386822000 16164096 15027888
17:40:56 84360 8192000000 10273016048 10701578240 1361749616 14.253 100 33 65 0 1382178800 12806992 7622192
17:43:25 12519 8192000000 10298487168 10718068736 1374785808 14.370 100 55 108 2 1407505136 20355984 12363344
17:44:44 14501 8192000000 10325437104 10743046144 1391229248 14.517 100 58 115 0 1428570768 24331440 13010080
17:46:10 16703 8192000000 10336152496 10755862528 1397479200 14.573 100 58 116 1 1443436880 31936080 14021600
17:47:30 4637 8192000000 10355506832 10774614016 1405859104 14.647 100 67 134 1 1457327312 34524064 16944144
17:48:41 18311 8192000000 10339400416 10769162240 1399934480 14.594 99 45 86 3 1443773984 32864096 10975408
17:50:04 5018 8192000000 10343523840 10763366400 1401393552 14.607 100 58 114 3 1450261984 34229344 14639088
17:51:13 5404 8192000000 10354274560 10776621056 1406214176 14.650 100 57 113 3 1459165168 39178576 13772416
17:52:21 2896 8192000000 10371523520 10786791424 1415786880 14.735 100 81 160 1 1474016704 38279040 19950784
17:53:29 7688 8192000000 10356662416 10773630976 1392221696 14.526 99 65 129 0 1452505504 44062432 16221376
17:54:42 4255 8192000000 10337516496 10748534784 1374453488 14.367 100 83 164 0 1443162208 48263488 20445232
17:55:53 3692 8192000000 10340964144 10752851968 1375760848 14.379 100 65 129 1 1443983136 51512096 16710192
17:57:03 7152 8192000000 10348385136 10762387456 1378666544 14.405 100 69 138 1 1453670352 57205120 17798688
17:58:12 28631 8192000000 10329622880 10752585728 1353020000 14.175 98 39 77 0 1411139920 47042848 11077072
17:59:51 4679 8192000000 10343638512 10771841024 1384465648 14.456 100 46 93 3 1449013616 51499104 13048864
18:00:58 6486 8192000000 10335238688 10768728064 1385264032 14.464 100 41 82 1 1442604288 46274976 11065280
18:02:08 26658 8192000000 10304487408 10735079424 1338521200 14.044 97 27 54 1 1388927280 42728544 7677536
18:03:44 13679 8192000000 10301321056 10742108160 1354533568 14.188 99 22 44 3 1396384848 35128592 6722688
18:05:15 48602 8192000000 10287649280 10739118080 1334055728 14.004 98 10 20 0 1373716752 36658480 3002544
18:07:16 54094 8192000000 10304744272 10732589056 1371021184 14.336 100 22 44 1 1387534432 11312064 5201184
18:09:21 83905 8192000000 10299856928 10742706176 1382656640 14.440 100 20 41 0 1400677120 13368784 4651696
18:11:45 94675 8192000000 10292520912 10742185984 1373567696 14.359 99 11 22 0 1387062096 10987024 2507376
18:14:27 53824 8192000000 10298359536 10746806272 1373635072 14.360 99 36 72 0 1388341168 6178336 8527760
18:16:25 45012 8192000000 10312422160 10767708160 1391801024 14.522 100 33 66 0 1414195632 14925712 7468896
18:18:17 28614 8192000000 10285162832 10741391360 1356809216 14.209 98 16 32 0 1376187104 15041712 4336176
18:19:47 36862 8192000000 10318017072 10758041600 1388810208 14.495 100 43 86 0 1414902720 15688960 10403552
18:21:27 35320 8192000000 10307075648 10769211392 1394353664 14.545 100 24 49 0 1417727968 17818400 5555904
18:23:06 85699 8192000000 10306274240 10771599360 1400292672 14.598 100 17 34 1 1419437184 14895744 4248768
18:25:51 50306 8192000000 10301782528 10769625088 1399560768 14.591 100 18 36 1 1417326528 13698224 4067536
18:28:17 72362 8192000000 10288396960 10765119488 1380856784 14.424 99 16 32 0 1396103744 11790016 3456944
18:30:53 79088 8192000000 10307207872 10774032384 1390171056 14.507 99 14 28 0 1407064176 13694032 3199088
18:33:19 43271 8192000000 10321826384 10782355456 1412829552 14.709 100 16 32 0 1426663808 10002928 3831328
18:35:06 46002 8192000000 10304100048 10760302592 1368118736 14.310 97 19 39 0 1381171872 8455152 4597984
18:36:52 78074 8192000000 10305874480 10764722176 1364423584 14.277 98 18 36 0 1378629520 10443648 3762288
18:39:13 22248 8192000000 10309921280 10757263360 1371516768 14.341 98 54 109 1 1390272864 6480656 12275440
18:40:41 66951 8192000000 10311718512 10771304448 1393620400 14.538 100 12 24 0 1408118432 11958000 2540032
18:42:52 36290 8192000000 10285844128 10725502976 1325375744 13.925 96 39 79 0 1348223696 13861056 8986896
18:44:43 105211 8192000000 10268481664 10735980544 1299394960 13.690 96 3 6 0 1334257984 34401712 461312
18:47:51 30543 8192000000 10289270496 10720903168 1361388096 14.250 100 49 97 1 1380245104 7815456 11041552
18:49:30 38228 8192000000 10241202176 10686615552 1307591728 13.764 97 24 48 1 1320610272 7650816 5367728
18:51:12 60815 8192000000 10259343536 10719932416 1352596864 14.171 100 12 23 0 1366710288 11622576 2490848
18:53:15 73883 8192000000 10243624096 10704175104 1320188192 13.878 97 11 22 0 1333324432 10312976 2823264
18:56:00 63553 8192000000 10240453136 10699325440 1314354720 13.826 97 29 58 0 1334954880 14092112 6508048
18:58:07 42243 8192000000 10262260272 10707632128 1331177344 13.978 98 18 36 0 1343372736 8400368 3795024
19:00:01 22824 8192000000 10265031600 10703998976 1328101392 13.950 97 32 64 2 1343322080 8257696 6962992
19:01:24 60187 8192000000 10277511872 10728742912 1368024896 14.309 100 17 34 2 1382759072 10488560 4245616
19:03:26 33167 8192000000 10293548464 10729787392 1366220384 14.293 100 44 88 1 1387818192 11824016 9773792
19:05:03 72365 8192000000 10284127264 10739007488 1365736800 14.289 99 28 56 1 1387801584 15832800 6231984
19:07:52 95083 8192000000 10282024016 10739671040 1346378848 14.115 98 14 28 0 1356994464 7692160 2923456
19:11:13 32310 8192000000 10283184032 10748207104 1382073120 14.435 100 24 48 0 1404830672 17733696 5023856
19:14:33 47994 8192000000 10287947552 10739707904 1352114896 14.167 97 21 42 0 1366628128 9539376 4973856
19:16:35 93530 8192000000 10298768064 10764222464 1364127408 14.274 98 16 32 0 1379413728 11689904 3596416
19:19:14 85651 8192000000 10307313616 10770001920 1380776688 14.423 99 11 22 0 1400278528 16823904 2677936
19:21:41 54314 8192000000 10306291824 10753544192 1355569040 14.198 97 37 74 1 1372465888 8807040 8089808
19:23:38 52285 8192000000 10318938624 10772836352 1384777872 14.459 99 28 57 0 1401971072 11461376 5731824
19:25:40 122546 8192000000 10310052192 10774478848 1331061376 13.977 95 6 12 1 1342992288 10799136 1131776
19:28:47 109452 8192000000 10305865184 10756018176 1362364336 14.259 98 23 46 0 1381646720 13044560 6237824
19:31:46 67244 8192000000 10257786512 10730291200 1321413360 13.890 96 12 24 2 1340013008 15715744 2883904
19:34:54 60696 8192000000 10248475488 10700644352 1301815824 13.712 96 17 34 0 1312471376 6969152 3686400
19:37:06 57606 8192000000 9929090176 10340925440 1054506272 11.404 78 5 10 0 1062676032 7127088 1042672

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 20-11-2021 19:40:07 to 20-11-2021 19:40:22.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 79 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 20-11-2021 19:40:23 to 20-11-2021 19:40:25.

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          238972
MON$OLDEST_ACTIVE               238973
MON$OLDEST_SNAPSHOT             238973
MON$NEXT_TRANSACTION            238973
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                       2042720
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33540 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33540 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33540 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33540 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 ---
:95: 
:96: mscs-test-161.budzdorov.ru    Sat Nov 20 19:38:17 2021
:97:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:98: 
:99: 
:100: mscs-test-161.budzdorov.ru    Sat Nov 20 19:38:17 2021
:101:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:102: 
--- end of diff output ---

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

20.11.21 19:40:26. 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
2021-11-20 19:40:28 No severe PSQL-related problems occured
20.11.21 19:40:29. End of report.