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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16641688 kB
MemAvailable 31581048 kB
Buffers 0 kB
Cached 15060528 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-26 22:37:55
DTS_END                         2021-11-26 22:37:55.7860
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/52918
TRN_ID                          243779
ATT_ID                          639
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: 9376.11 17790 1125133
customer order: creation 182.23 368 21868
customer order: refuse 15.32 350 1838
order to supplier: creation 47.43 1271 5691
order to supplier: removal 3.59 99894 431
invoice (draft): creation 47.52 3441 5702
invoice (draft): removal 52.48 832 6297
invoice accept: apply 42.98 17024 5158
invoice accept: cancel 47.82 843 5738
customer reserve: creation 3970.25 168 476430
customer reserve: removal 4658.52 60 559022
realization accept: apply 85.54 193 10265
realization accept: cancel 89.51 108 10741
payment from customer: creation 60.15 115 7218
payment from customer: removal 5.92 117 710
payment to supplier: creation 50.62 133 6074
payment to supplier: removal 3.68 83 441
service: total inventory turnovers 4.80 1069 576
service: total monetary turnovers 6.77 23 812
service: aggregate perf. data 0.98 18498 117
service: refresh index statistics 0.03 211217 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 26-11-2021 22:40:05 to 26-11-2021 22:40:07.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2780
TEST_TIME 2 2953
TEST_TIME 3 3219
TEST_TIME 4 2987
TEST_TIME 5 3707
TEST_TIME 6 3764
TEST_TIME 7 4478
TEST_TIME 8 4695
TEST_TIME 9 4748
TEST_TIME 10 4922
TEST_TIME 11 5138
TEST_TIME 12 5467
TEST_TIME 13 5578
TEST_TIME 14 5970
TEST_TIME 15 5949
TEST_TIME 16 6870
TEST_TIME 17 6929
TEST_TIME 18 7114
TEST_TIME 19 7316
TEST_TIME 20 7893
TEST_TIME 21 8333
TEST_TIME 22 8378
TEST_TIME 23 8895
TEST_TIME 24 9310
TEST_TIME 25 9227
TEST_TIME 26 9476
TEST_TIME 27 9511
TEST_TIME 28 9654
TEST_TIME 29 10257
TEST_TIME 30 10636
TEST_TIME 31 10454
TEST_TIME 32 10364
TEST_TIME 33 10369
TEST_TIME 34 10284
TEST_TIME 35 10328
TEST_TIME 36 10390
TEST_TIME 37 10219
TEST_TIME 38 10344
TEST_TIME 39 10283
TEST_TIME 40 10166
TEST_TIME 41 10014
TEST_TIME 42 9910
TEST_TIME 43 9926
TEST_TIME 44 9891
TEST_TIME 45 9783
TEST_TIME 46 9741
TEST_TIME 47 9688
TEST_TIME 48 9707
TEST_TIME 49 9719
TEST_TIME 50 9783
TEST_TIME 51 9687
TEST_TIME 52 9646
TEST_TIME 53 9657
TEST_TIME 54 9602
TEST_TIME 55 9635
TEST_TIME 56 9572
TEST_TIME 57 9602
TEST_TIME 58 9557
TEST_TIME 59 9608
TEST_TIME 60 9686
TEST_TIME 61 9608
TEST_TIME 62 9681
TEST_TIME 63 9677
TEST_TIME 64 9727
TEST_TIME 65 9786
TEST_TIME 66 9859
TEST_TIME 67 9829
TEST_TIME 68 9879
TEST_TIME 69 9811
TEST_TIME 70 9824
TEST_TIME 71 9789
TEST_TIME 72 9695
TEST_TIME 73 9710
TEST_TIME 74 9631
TEST_TIME 75 9659
TEST_TIME 76 9628
TEST_TIME 77 9573
TEST_TIME 78 9509
TEST_TIME 79 9536
TEST_TIME 80 9460
TEST_TIME 81 9446
TEST_TIME 82 9443
TEST_TIME 83 9450
TEST_TIME 84 9431
TEST_TIME 85 9402
TEST_TIME 86 9357
TEST_TIME 87 9496
TEST_TIME 88 9596
TEST_TIME 89 9535
TEST_TIME 90 9614
TEST_TIME 91 9622
TEST_TIME 92 9654
TEST_TIME 93 9667
TEST_TIME 94 9631
TEST_TIME 95 9689
TEST_TIME 96 9703
TEST_TIME 97 9626
TEST_TIME 98 9591
TEST_TIME 99 9560
TEST_TIME 100 9536
TEST_TIME 101 9499
TEST_TIME 102 9452
TEST_TIME 103 9446
TEST_TIME 104 9422
TEST_TIME 105 9425
TEST_TIME 106 9394
TEST_TIME 107 9387
TEST_TIME 108 9346
TEST_TIME 109 9355
TEST_TIME 110 9393
TEST_TIME 111 9433
TEST_TIME 112 9475
TEST_TIME 113 9444
TEST_TIME 114 9430
TEST_TIME 115 9467
TEST_TIME 116 9470
TEST_TIME 117 9466
TEST_TIME 118 9476
TEST_TIME 119 9448
TEST_TIME 120 9381

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 26-11-2021 22:40:08 to 26-11-2021 22:40:12.

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 1566678 1566678 0 0.00 0 41412 6 0 0
doc_list_biud 1566677 1566677 0 0.00 0 41094 28 0 0
sp_add_invoice_to_stock 5239 5158 81 1.54 47 179377 17024 0 81
sp_cancel_adding_invoice 5738 5738 0 0.00 5 58512 843 0 0
sp_cancel_client_order 1838 1838 0 0.00 4 22500 351 0 0
sp_cancel_customer_reserve 559022 559022 0 0.00 1 26728 61 0 0
sp_cancel_pay_from_customer 710 710 0 0.00 3 12158 118 0 0
sp_cancel_pay_to_supplier 441 441 0 0.00 3 4099 83 0 0
sp_cancel_supplier_invoice 6301 6297 4 0.06 2 18849 832 0 4
sp_cancel_supplier_order 431 431 0 0.00 7 1262362 99894 0 0
sp_cancel_write_off 10741 10741 0 0.00 1 9019 108 0 0
sp_client_order 21868 21868 0 0.00 3 39939 368 0 0
sp_customer_reserve 476430 476430 0 0.00 0 54702 169 0 0
sp_fill_shopping_cart 33241 33241 0 0.00 1 24949 39 0 0
sp_fill_shopping_cart_clo_res 476421 476421 0 0.00 0 15410 12 0 0
sp_get_clo_for_invoice 5149 5149 0 0.00 0 15548 264 0 0
sp_kill_cost_storno 17630 17630 0 0.00 0 11852 55 0 0
sp_kill_qstorno_ret_qs2qd 1135513 1135513 0 0.00 0 26711 33 0 0
sp_kill_qty_storno 599481 599481 0 0.00 0 41094 71 0 0
sp_lock_selected_doc 21405 21405 0 0.00 0 7492 7 0 0
sp_make_cost_storno 28706 28706 0 0.00 0 41348 56 0 0
sp_multiply_rows_for_pdistr 28706 28706 0 0.00 0 8164 29 0 0
sp_multiply_rows_for_qdistr 21868 21868 0 0.00 0 25435 154 0 0
sp_pay_from_customer 7218 7218 0 0.00 4 13441 116 0 0
sp_pay_to_supplier 6074 6074 0 0.00 4 41416 133 0 0
sp_payment_common 13292 13292 0 0.00 1 18211 34 0 0
sp_qd_handle_on_cancel_clo 1838 1838 0 0.00 0 22493 314 0 0
sp_qd_handle_on_invoice_upd_sts 10887 10887 0 0.00 0 41092 346 0 0
sp_qd_handle_on_reserve_upd_sts 10265 10265 0 0.00 0 18847 73 0 0
sp_reserve_write_off 10265 10265 0 0.00 6 18899 194 0 0
sp_supplier_invoice 5702 5702 0 0.00 5 92450 3441 0 0
sp_supplier_order 5691 5691 0 0.00 5 57480 1271 0 0
srv_aggregate_perf_data 117 117 0 0.00 2744 487565 18499 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1755 140645 44531 0 0
srv_find_qd_qs_mism 1075869 1075869 0 0.00 0 13119 5 0 0
srv_make_invnt_saldo 576 576 0 0.00 44 29133 1069 0 0
srv_make_money_saldo 812 812 0 0.00 1 775 23 0 0
srv_recalc_idx_stat 4 4 0 0.00 88923 349203 211217 0 0
t$perf-abend:sp_add_invoice_to_stock 81 81 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 4 4 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5158 5158 0 0.00 0 10727 137 0 0
t$perf-norm:sp_cancel_adding_invoice 447 447 0 0.00 0 3868 91 0 0
t$perf-norm:sp_cancel_client_order 1838 1838 0 0.00 0 3681 10 0 0
t$perf-norm:sp_cancel_customer_reserve 709 709 0 0.00 0 307 4 0 0
t$perf-norm:sp_cancel_pay_from_customer 710 710 0 0.00 0 395 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 441 441 0 0.00 0 3099 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 395 395 0 0.00 0 828 12 0 0
t$perf-norm:sp_cancel_supplier_order 431 431 0 0.00 0 21443 1575 0 0
t$perf-norm:sp_cancel_write_off 1020 1020 0 0.00 0 1849 9 0 0
t$perf-norm:sp_client_order 21868 21868 0 0.00 0 16194 16 0 0
t$perf-norm:sp_customer_reserve 1076 1076 0 0.00 0 1503 7 0 0
t$perf-norm:sp_pay_from_customer 7218 7218 0 0.00 0 9419 9 0 0
t$perf-norm:sp_pay_to_supplier 6074 6074 0 0.00 0 5945 11 0 0
t$perf-norm:sp_reserve_write_off 10265 10265 0 0.00 0 6324 10 0 0
t$perf-norm:sp_supplier_invoice 5702 5702 0 0.00 0 7341 22 0 0
t$perf-norm:sp_supplier_order 5691 5691 0 0.00 0 6172 16 0 0
t$perf-norm:srv_aggregate_perf_data 117 117 0 0.00 0 714 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 214 5 0 0
t$perf-norm:srv_make_invnt_saldo 576 576 0 0.00 0 687 18 0 0
t$perf-norm:srv_make_money_saldo 812 812 0 0.00 0 760 11 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 3 0 0
v_add_invoice_to_stock 10460 10298 162 1.54 0 8624 20 0 162
v_all_customers 39622 39622 0 0.00 0 126 1 0 0
v_all_suppliers 31378 31378 0 0.00 0 18 1 0 0
v_all_wares 43736 43736 0 0.00 0 124 1 0 0
v_cancel_adding_invoice 894 894 0 0.00 0 4061 16 0 0
v_cancel_client_order 3676 3676 0 0.00 0 3166 7 0 0
v_cancel_customer_prepayment 1420 1420 0 0.00 0 6434 13 0 0
v_cancel_customer_reserve 1418 1418 0 0.00 0 2514 6 0 0
v_cancel_payment_to_supplier 882 882 0 0.00 0 1885 7 0 0
v_cancel_supplier_invoice 798 790 8 1.00 0 4507 20 0 8
v_cancel_supplier_order 862 862 0 0.00 0 6118 12 0 0
v_cancel_write_off 2040 2040 0 0.00 0 5104 23 0 0
v_max_id_clo_ord 5689 5689 0 0.00 0 854 1 0 0
v_max_id_clo_res 1076 1076 0 0.00 0 2394 11 0 0
v_max_id_ord_sup 5684 5684 0 0.00 0 3422 2 0 0
v_max_non_paid_invoice 1758 1758 0 0.00 0 364 2 0 0
v_max_non_paid_realizn 3878 3878 0 0.00 0 3925 6 0 0
v_min_id_clo_ord 5689 5689 0 0.00 0 24830 19 0 0
v_min_id_clo_res 1076 1076 0 0.00 0 11727 25 0 0
v_min_id_ord_sup 5684 5684 0 0.00 0 8518 8 0 0
v_min_non_paid_invoice 6074 6074 0 0.00 0 18206 21 0 0
v_min_non_paid_realizn 7218 7218 0 0.00 0 13435 24 0 0
v_our_firm 10794 10794 0 0.00 0 89 1 0 0
v_reserve_write_off 20530 20530 0 0.00 0 6071 6 0 0
x_lock_depdocs_on_canc_invoice 11640 11640 0 0.00 0 11909 116 0 0
x_lock_depdocs_on_canc_sup_ord 431 431 0 0.00 0 5550 268 0 0
x_make_qstorno_1000_1200 5691 5691 0 0.00 1 57471 1184 0 0
x_make_qstorno_1200_2000 5702 5702 0 0.00 2 92241 3299 0 0
x_make_qstorno_2100_3300 476430 476430 0 0.00 0 44752 154 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 26-11-2021 22:40:13 to 26-11-2021 22:40:17.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
20:07:59 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:00 18875 8192000000 9904590896 10216464384 991602256 10.797 100 87 173 0 1020568688 6174000 22792432
20:10:30 23464 8192000000 9966608176 10294476800 1050330816 11.364 100 85 170 2 1080581040 8981920 21268304
20:11:56 22088 8192000000 10016758768 10359410688 1092596032 11.767 100 83 164 0 1123103664 10338864 20168768
20:13:25 9815 8192000000 10053187200 10404642816 1135893408 12.177 100 83 165 0 1167095424 11551248 19650768
20:14:48 8528 8192000000 10086815360 10448474112 1176546208 12.558 100 82 164 0 1208638192 13669168 18422816
20:16:00 4914 8192000000 10121566576 10493206528 1207862144 12.849 100 80 160 1 1238836080 12880016 18093920
20:17:08 3808 8192000000 10159702432 10535030784 1247075792 13.211 100 94 187 2 1280850496 12829696 20945008
20:18:13 1934 8192000000 10196872992 10580987904 1282036976 13.532 100 85 169 2 1313107600 12309440 18761184
20:19:17 1668 8192000000 10220727936 10605985792 1302899520 13.722 100 88 176 1 1336942080 13283136 20759424
20:20:21 12573 8192000000 10221886576 10610270208 1309251664 13.779 100 86 171 4 1340091936 10908176 19932096
20:21:35 4637 8192000000 10201531136 10612125696 1310344928 13.789 100 36 68 3 1324272912 5630864 8297120
20:22:43 6125 8192000000 10201803520 10618126336 1313730288 13.820 100 23 46 1 1325553760 7083312 4740160
20:23:52 77377 8192000000 10222623600 10645561344 1323812976 13.911 100 26 52 0 1344351808 14260544 6278288
20:26:11 5185 8192000000 10217722944 10637701120 1316670160 13.847 99 25 50 1 1328119600 5961008 5488432
20:27:17 15329 8192000000 10220052672 10642857984 1320399904 13.880 99 18 36 3 1329645184 5200400 4044880
20:28:37 16082 8192000000 10157978848 10555396096 1194869008 12.729 91 14 28 1 1202702832 4694464 3139360
20:29:58 8789 8192000000 10170829808 10585505792 1272495968 13.444 99 36 71 1 1287939024 7484288 7958768
20:31:07 10501 8192000000 10156932928 10561101824 1242905920 13.173 98 36 73 3 1257016160 4887056 9223184
20:32:18 6087 8192000000 10146552224 10539245568 1235969984 13.109 99 33 66 0 1254434576 11130688 7333904
20:33:25 15103 8192000000 10155884000 10561028096 1254313664 13.278 99 13 26 2 1262805776 5482752 3009360
20:34:40 36126 8192000000 10171524896 10583289856 1281635344 13.528 100 29 58 0 1294719392 6122368 6961680
20:36:21 7785 8192000000 10199171456 10603892736 1294997264 13.650 100 34 67 1 1309829600 7378256 7454080
20:37:29 9950 8192000000 10206669808 10618101760 1304069024 13.732 100 23 45 0 1318387968 9336320 4982624
20:38:45 15861 8192000000 10235216576 10642694144 1327808976 13.947 100 42 84 0 1346225328 9271472 9144880
20:40:06 17403 8192000000 10264800032 10673831936 1347163616 14.122 100 52 104 0 1372831312 13596800 12070896
20:41:26 86205 8192000000 10263941712 10692481024 1361145536 14.248 100 37 74 1 1389547216 19538896 8862784
20:43:57 10641 8192000000 10280492560 10699673600 1351962448 14.165 99 48 94 2 1386454992 22675152 11817392
20:45:12 13217 8192000000 10302327392 10721644544 1381428288 14.429 100 37 74 2 1410435664 19730784 9276592
20:46:27 5994 8192000000 10314756000 10734911488 1392198720 14.525 100 62 121 2 1427644224 21407952 14037552
20:47:40 4344 8192000000 10331736416 10748317696 1398343120 14.580 100 75 151 0 1439240400 22940400 17956880
20:48:54 3784 8192000000 10334945408 10759847936 1408488128 14.671 100 46 89 2 1444333600 24698992 11146480
20:50:00 4380 8192000000 10340701472 10766204928 1401963248 14.612 99 57 112 2 1444103568 27737824 14402496
20:51:12 5816 8192000000 10338805024 10754240512 1402265024 14.615 100 76 152 3 1447285648 25933728 19086896
20:52:25 3895 8192000000 10363916384 10780479488 1414303776 14.722 100 68 136 0 1468209072 36523728 17381568
20:53:34 1753 8192000000 10365106176 10776010752 1415016208 14.728 100 92 184 0 1467017312 29922272 22078832
20:54:43 4061 8192000000 10360509344 10771234816 1410030592 14.684 100 80 159 1 1465414960 35596016 19788352
20:55:50 2604 8192000000 10354511632 10768355328 1401311168 14.607 100 81 161 1 1459812224 37891216 20609840
20:56:57 2193 8192000000 10338521136 10748043264 1382281440 14.437 100 81 161 2 1441523216 38217872 21023904
20:58:02 18331 8192000000 10308024560 10728108032 1353289472 14.177 98 41 82 1 1403161840 38229952 11642416
20:59:28 9909 8192000000 10323571680 10747846656 1379681520 14.414 100 40 80 1 1428042848 37615200 10746128
21:00:40 14660 8192000000 10324203200 10755952640 1367941616 14.309 98 39 78 0 1423048544 43672736 11434192
21:01:59 19907 8192000000 10328603456 10764673024 1394437456 14.545 100 30 60 1 1434683872 32268928 7977488
21:03:20 27449 8192000000 10321099120 10758094848 1368658240 14.315 99 26 52 0 1418498128 41959056 7880832
21:04:54 17542 8192000000 10287792224 10720677888 1326061488 13.932 98 34 68 2 1374623072 38916176 9645408
21:06:15 23238 8192000000 10276866720 10732519424 1341789424 14.074 99 13 26 1 1376438480 30949408 3699648
21:07:43 22420 8192000000 10275018624 10700001280 1325992720 13.931 98 37 71 0 1341327808 6789248 8545840
21:09:10 79621 8192000000 10268834752 10712633344 1311331600 13.798 96 19 38 1 1327228128 12038736 3857792
21:11:37 53536 8192000000 10303396624 10742865920 1372290448 14.348 100 15 28 0 1387742784 12478960 2973376
21:13:33 82678 8192000000 10318701664 10755432448 1377931360 14.398 99 16 30 0 1395688816 13545776 4211680
21:15:58 109072 8192000000 10301503024 10738659328 1319769696 13.875 95 4 8 1 1335547504 14724624 1053184
21:18:48 76131 8192000000 10281660352 10732068864 1364136624 14.274 98 31 62 1 1378854576 7084672 7633280
21:21:13 25585 8192000000 10304102624 10749202432 1393698400 14.539 100 44 86 2 1411404656 7704272 10001984
21:22:41 33594 8192000000 10297756640 10753781760 1385767664 14.468 99 12 24 0 1397693072 8964064 2961344
21:24:17 26523 8192000000 10317409328 10766630912 1400164896 14.596 100 26 53 1 1417248512 10442336 6641280
21:25:45 60779 8192000000 10311539664 10765656064 1394463632 14.546 99 25 50 0 1419917056 18497248 6956176
21:27:48 80571 8192000000 10310652352 10775408640 1398576016 14.582 99 11 22 1 1421927776 20686304 2665456
21:30:31 47225 8192000000 10297342320 10765926400 1381990192 14.434 99 11 22 0 1396720512 12064688 2665632
21:32:19 68336 8192000000 10291082096 10747568128 1381300640 14.428 98 12 22 1 1398634144 15118880 2214624
21:34:27 38390 8192000000 10294057904 10752544768 1368562096 14.314 98 21 40 3 1383985936 10635840 4788000
21:36:19 37055 8192000000 10279770368 10731270144 1336438768 14.025 96 14 28 0 1348347520 8748032 3160720
21:38:00 26871 8192000000 10304618944 10753261568 1369930400 14.326 98 24 46 2 1384308416 8734912 5643104
21:39:33 30582 8192000000 10274545264 10718339072 1324227504 13.915 96 20 40 0 1335855488 7147520 4480464
21:41:14 31476 8192000000 10289980048 10739937280 1373418304 14.358 100 18 34 1 1389867872 12892832 3556736
21:43:43 140645 8192000000 10274512704 10730483712 1244082592 13.184 90 11 22 1 1261549040 15268688 2197760
21:47:04 79293 8192000000 10271993808 10732257280 1338171328 14.041 98 36 72 1 1359361008 13441392 7748288
21:49:25 98807 8192000000 10265929520 10728173568 1330352144 13.970 97 11 22 0 1348789840 16010688 2427008
21:52:27 45792 8192000000 10251579984 10714103808 1356666368 14.207 100 10 20 0 1368516864 9640320 2210176
21:54:13 86179 8192000000 10254005824 10706583552 1303868032 13.730 95 22 45 2 1320112240 10523120 5721088
21:56:40 64164 8192000000 10263473040 10717130752 1344339104 14.097 99 11 22 0 1355295952 8540448 2416400
21:58:45 31778 8192000000 10267214144 10725220352 1368797600 14.316 100 31 62 0 1388791504 12116256 7877648
22:00:21 53506 8192000000 10293911984 10747912192 1377037248 14.390 100 17 34 0 1393272960 12514640 3721072
22:02:17 46209 8192000000 10301310368 10752888832 1352435424 14.169 97 19 38 1 1375241296 17943504 4862368
22:04:16 95473 8192000000 10287089008 10764070912 1351646704 14.162 98 11 22 0 1379474000 24848976 2978320
22:06:54 44845 8192000000 10288263168 10750713856 1372896288 14.353 99 33 66 0 1393920160 13764128 7259744
22:08:43 18577 8192000000 10310017680 10751307776 1386220160 14.472 100 37 73 1 1403124928 8924672 7980096
22:10:02 71177 8192000000 10320965008 10772332544 1403561168 14.627 100 27 53 2 1419602880 10779584 5262128
22:12:13 38298 8192000000 10327375280 10784358400 1408213648 14.668 100 37 74 2 1430040432 13494656 8332128
22:13:52 125706 8192000000 10290919616 10753495040 1349255792 14.141 96 16 32 0 1366747792 14038032 3453968
22:17:19 62454 8192000000 10292396464 10766352384 1390958624 14.514 99 16 32 1 1402577104 8075184 3543296
22:19:25 52574 8192000000 10285801904 10750795776 1373517264 14.359 98 12 24 0 1384503440 7830992 3155184
22:21:31 58462 8192000000 10282604768 10749288448 1366770448 14.298 98 25 50 2 1380746672 8011856 5964368
22:23:54 53861 8192000000 10281980288 10744881152 1338617440 14.045 96 14 28 1 1349678368 7532352 3528576
22:25:59 55732 8192000000 10265664528 10719821824 1315467664 13.836 95 22 44 2 1334337280 13566848 5302768
22:28:03 50928 8192000000 10267160176 10735042560 1315517936 13.836 95 21 42 0 1334268192 13691152 5059104
22:29:55 50880 8192000000 10287868992 10734174208 1349180512 14.140 98 25 50 1 1362148128 7773456 5194160
22:31:50 69894 8192000000 10301030064 10746052608 1368541008 14.314 99 16 33 4 1383634928 11711424 3382496
22:34:02 21257 8192000000 10283574000 10723352576 1324317296 13.916 96 47 94 0 1347177856 12675744 10184816
22:35:24 134339 8192000000 10280630528 10745200640 1342691824 14.082 97 23 46 0 1361428192 13143104 5593264

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 81 v_add_invoice_to_stock 335544517
except 4 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 26-11-2021 22:40:34 to 26-11-2021 22:40:36.

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          243532
MON$OLDEST_ACTIVE               244274
MON$OLDEST_SNAPSHOT             244274
MON$NEXT_TRANSACTION            244274
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                       2048032
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 ---
:408: 
:409: mscs-test-161.budzdorov.ru    Fri Nov 26 22:37:57 2021
:410:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:411: 
:412: 
:413: mscs-test-161.budzdorov.ru    Fri Nov 26 22:38:09 2021
:414:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:415: 
--- end of diff output ---

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

26.11.21 22:40:37. 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-26 22:40:40 No severe PSQL-related problems occured
26.11.21 22:40:41. End of report.