Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 23.11.2021 22:38
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 16706716 kB
MemAvailable 31532864 kB
Buffers 0 kB
Cached 14941828 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-23 22:37:58
DTS_END                         2021-11-23 22:37:58.7670
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/34558
TRN_ID                          248141
ATT_ID                          704
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: 9542.41 20739 1145089
customer order: creation 183.44 368 22013
customer order: refuse 15.13 351 1815
order to supplier: creation 48.29 1286 5795
order to supplier: removal 3.50 97825 420
invoice (draft): creation 49.01 3286 5881
invoice (draft): removal 55.20 761 6624
invoice accept: apply 43.00 16664 5160
invoice accept: cancel 49.89 829 5987
customer reserve: creation 3897.32 168 467678
customer reserve: removal 4885.67 54 586280
realization accept: apply 89.46 190 10735
realization accept: cancel 85.53 93 10264
payment from customer: creation 63.14 106 7577
payment from customer: removal 6.02 96 722
payment to supplier: creation 51.01 129 6121
payment to supplier: removal 3.63 57 435
service: total inventory turnovers 4.93 1174 591
service: total monetary turnovers 7.28 29 873
service: aggregate perf. data 0.95 16909 114
service: refresh index statistics 0.03 274412 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 23-11-2021 22:38:42 to 23-11-2021 22:38:44.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3553
TEST_TIME 2 3903
TEST_TIME 3 4703
TEST_TIME 4 5681
TEST_TIME 5 5683
TEST_TIME 6 5084
TEST_TIME 7 4997
TEST_TIME 8 5054
TEST_TIME 9 5023
TEST_TIME 10 5433
TEST_TIME 11 5429
TEST_TIME 12 5610
TEST_TIME 13 5600
TEST_TIME 14 5835
TEST_TIME 15 6289
TEST_TIME 16 6887
TEST_TIME 17 6862
TEST_TIME 18 7020
TEST_TIME 19 7322
TEST_TIME 20 8026
TEST_TIME 21 8098
TEST_TIME 22 9112
TEST_TIME 23 9271
TEST_TIME 24 9461
TEST_TIME 25 9801
TEST_TIME 26 9930
TEST_TIME 27 9994
TEST_TIME 28 9869
TEST_TIME 29 10376
TEST_TIME 30 10329
TEST_TIME 31 10171
TEST_TIME 32 10370
TEST_TIME 33 10210
TEST_TIME 34 10182
TEST_TIME 35 10002
TEST_TIME 36 9922
TEST_TIME 37 10229
TEST_TIME 38 10162
TEST_TIME 39 10030
TEST_TIME 40 9989
TEST_TIME 41 10112
TEST_TIME 42 10150
TEST_TIME 43 10161
TEST_TIME 44 10044
TEST_TIME 45 9879
TEST_TIME 46 9774
TEST_TIME 47 9741
TEST_TIME 48 9773
TEST_TIME 49 9690
TEST_TIME 50 9682
TEST_TIME 51 9626
TEST_TIME 52 9726
TEST_TIME 53 9844
TEST_TIME 54 9734
TEST_TIME 55 9656
TEST_TIME 56 9665
TEST_TIME 57 9623
TEST_TIME 58 9575
TEST_TIME 59 9543
TEST_TIME 60 9503
TEST_TIME 61 9462
TEST_TIME 62 9531
TEST_TIME 63 9501
TEST_TIME 64 9546
TEST_TIME 65 9662
TEST_TIME 66 9648
TEST_TIME 67 9699
TEST_TIME 68 9765
TEST_TIME 69 9722
TEST_TIME 70 9788
TEST_TIME 71 9772
TEST_TIME 72 9790
TEST_TIME 73 9835
TEST_TIME 74 9860
TEST_TIME 75 9925
TEST_TIME 76 9808
TEST_TIME 77 9748
TEST_TIME 78 9812
TEST_TIME 79 9726
TEST_TIME 80 9696
TEST_TIME 81 9639
TEST_TIME 82 9600
TEST_TIME 83 9531
TEST_TIME 84 9515
TEST_TIME 85 9494
TEST_TIME 86 9498
TEST_TIME 87 9459
TEST_TIME 88 9438
TEST_TIME 89 9467
TEST_TIME 90 9454
TEST_TIME 91 9486
TEST_TIME 92 9545
TEST_TIME 93 9558
TEST_TIME 94 9569
TEST_TIME 95 9569
TEST_TIME 96 9617
TEST_TIME 97 9643
TEST_TIME 98 9659
TEST_TIME 99 9709
TEST_TIME 100 9668
TEST_TIME 101 9737
TEST_TIME 102 9650
TEST_TIME 103 9601
TEST_TIME 104 9577
TEST_TIME 105 9566
TEST_TIME 106 9546
TEST_TIME 107 9564
TEST_TIME 108 9513
TEST_TIME 109 9465
TEST_TIME 110 9436
TEST_TIME 111 9412
TEST_TIME 112 9496
TEST_TIME 113 9520
TEST_TIME 114 9481
TEST_TIME 115 9528
TEST_TIME 116 9553
TEST_TIME 117 9552
TEST_TIME 118 9524
TEST_TIME 119 9570
TEST_TIME 120 9550

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 23-11-2021 22:38:46 to 23-11-2021 22:38:49.

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 1580314 1580314 0 0.00 0 17087 6 0 0
doc_list_biud 1580314 1580314 0 0.00 0 61733 26 0 0
sp_add_invoice_to_stock 5346 5160 186 3.47 21 188415 16664 0 186
sp_cancel_adding_invoice 5987 5987 0 0.00 4 70963 829 0 0
sp_cancel_client_order 1815 1815 0 0.00 5 11083 352 0 0
sp_cancel_customer_reserve 586280 586280 0 0.00 1 61735 55 0 0
sp_cancel_pay_from_customer 722 722 0 0.00 3 10098 96 0 0
sp_cancel_pay_to_supplier 435 435 0 0.00 3 1578 57 0 0
sp_cancel_supplier_invoice 6641 6624 17 0.25 2 18952 761 0 17
sp_cancel_supplier_order 420 420 0 0.00 8 1081860 97826 0 0
sp_cancel_write_off 10264 10264 0 0.00 1 16460 94 0 0
sp_client_order 22013 22013 0 0.00 3 17758 368 0 0
sp_customer_reserve 467678 467678 0 0.00 0 78775 168 0 0
sp_fill_shopping_cart 33683 33683 0 0.00 1 16271 39 0 0
sp_fill_shopping_cart_clo_res 467665 467665 0 0.00 0 13993 13 0 0
sp_get_clo_for_invoice 5145 5145 0 0.00 0 36356 278 0 0
sp_kill_cost_storno 17408 17408 0 0.00 0 7553 50 0 0
sp_kill_qstorno_ret_qs2qd 1189868 1189868 0 0.00 0 61729 30 0 0
sp_kill_qty_storno 627270 627270 0 0.00 0 61733 65 0 0
sp_lock_selected_doc 21960 21960 0 0.00 0 8284 6 0 0
sp_make_cost_storno 29578 29578 0 0.00 0 16431 59 0 0
sp_multiply_rows_for_pdistr 29578 29578 0 0.00 0 15591 28 0 0
sp_multiply_rows_for_qdistr 22013 22013 0 0.00 0 14952 162 0 0
sp_pay_from_customer 7577 7577 0 0.00 4 15642 106 0 0
sp_pay_to_supplier 6121 6121 0 0.00 5 15877 129 0 0
sp_payment_common 13698 13698 0 0.00 1 13520 27 0 0
sp_qd_handle_on_cancel_clo 1815 1815 0 0.00 0 11074 318 0 0
sp_qd_handle_on_invoice_upd_sts 11132 11132 0 0.00 0 40135 343 0 0
sp_qd_handle_on_reserve_upd_sts 10735 10735 0 0.00 0 11699 58 0 0
sp_reserve_write_off 10735 10735 0 0.00 6 16122 190 0 0
sp_supplier_invoice 5881 5881 0 0.00 6 116378 3287 0 0
sp_supplier_order 5795 5795 0 0.00 6 57763 1287 0 0
srv_aggregate_perf_data 114 114 0 0.00 2350 140485 16909 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1340 123646 46974 0 0
srv_find_qd_qs_mism 1096369 1096369 0 0.00 0 17087 5 0 0
srv_make_invnt_saldo 591 591 0 0.00 23 92382 1174 0 0
srv_make_money_saldo 873 873 0 0.00 1 4043 29 0 0
srv_recalc_idx_stat 4 4 0 0.00 139621 439281 274412 0 0
t$perf-abend:sp_add_invoice_to_stock 186 186 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 17 17 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5160 5160 0 0.00 0 8672 164 0 0
t$perf-norm:sp_cancel_adding_invoice 438 438 0 0.00 1 4427 94 0 0
t$perf-norm:sp_cancel_client_order 1815 1815 0 0.00 0 5074 16 0 0
t$perf-norm:sp_cancel_customer_reserve 772 772 0 0.00 0 4626 24 0 0
t$perf-norm:sp_cancel_pay_from_customer 722 722 0 0.00 0 2487 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 435 435 0 0.00 0 1031 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 412 412 0 0.00 0 1319 11 0 0
t$perf-norm:sp_cancel_supplier_order 420 420 0 0.00 0 23938 1825 0 0
t$perf-norm:sp_cancel_write_off 1067 1067 0 0.00 0 10816 32 0 0
t$perf-norm:sp_client_order 22013 22013 0 0.00 0 12550 19 0 0
t$perf-norm:sp_customer_reserve 1074 1074 0 0.00 0 3669 7 0 0
t$perf-norm:sp_pay_from_customer 7577 7577 0 0.00 0 5168 10 0 0
t$perf-norm:sp_pay_to_supplier 6121 6121 0 0.00 0 3435 8 0 0
t$perf-norm:sp_reserve_write_off 10735 10735 0 0.00 0 7336 12 0 0
t$perf-norm:sp_supplier_invoice 5881 5881 0 0.00 0 14956 21 0 0
t$perf-norm:sp_supplier_order 5795 5795 0 0.00 0 4791 16 0 0
t$perf-norm:srv_aggregate_perf_data 114 114 0 0.00 0 418 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 425 13 0 0
t$perf-norm:srv_make_invnt_saldo 591 591 0 0.00 0 940 25 0 0
t$perf-norm:srv_make_money_saldo 873 873 0 0.00 0 3927 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 6 3 0 0
v_add_invoice_to_stock 10660 10288 372 3.48 0 6318 21 0 372
v_all_customers 40250 40250 0 0.00 0 14 1 0 0
v_all_suppliers 32082 32082 0 0.00 0 17 1 0 0
v_all_wares 44026 44026 0 0.00 0 13 1 0 0
v_cancel_adding_invoice 876 876 0 0.00 0 1554 7 0 0
v_cancel_client_order 3630 3630 0 0.00 0 5612 9 0 0
v_cancel_customer_prepayment 1444 1444 0 0.00 0 3547 11 0 0
v_cancel_customer_reserve 1544 1544 0 0.00 0 326 3 0 0
v_cancel_payment_to_supplier 870 870 0 0.00 0 1449 6 0 0
v_cancel_supplier_invoice 858 824 34 3.96 0 5372 22 0 34
v_cancel_supplier_order 840 840 0 0.00 0 1622 6 0 0
v_cancel_write_off 2134 2134 0 0.00 0 5872 18 0 0
v_max_id_clo_ord 5795 5795 0 0.00 0 770 1 0 0
v_max_id_clo_res 1074 1074 0 0.00 0 6450 22 0 0
v_max_id_ord_sup 5875 5875 0 0.00 0 3528 4 0 0
v_max_non_paid_invoice 1750 1750 0 0.00 0 5917 6 0 0
v_max_non_paid_realizn 4052 4052 0 0.00 0 4671 6 0 0
v_min_id_clo_ord 5795 5795 0 0.00 0 4115 5 0 0
v_min_id_clo_res 1074 1074 0 0.00 0 4577 9 0 0
v_min_id_ord_sup 5875 5875 0 0.00 0 6776 13 0 0
v_min_non_paid_invoice 6121 6121 0 0.00 0 13514 19 0 0
v_min_non_paid_realizn 7577 7577 0 0.00 0 12412 20 0 0
v_our_firm 10826 10826 0 0.00 0 18 1 0 0
v_reserve_write_off 21470 21470 0 0.00 0 5739 9 0 0
x_lock_depdocs_on_canc_invoice 12199 12199 0 0.00 0 22179 120 0 0
x_lock_depdocs_on_canc_sup_ord 420 420 0 0.00 0 6534 302 0 0
x_make_qstorno_1000_1200 5795 5795 0 0.00 2 57751 1213 0 0
x_make_qstorno_1200_2000 5881 5881 0 0.00 2 116202 3140 0 0
x_make_qstorno_2100_3300 467678 467678 0 0.00 0 78775 152 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 23-11-2021 22:38:50 to 23-11-2021 22:38:54.

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:08:02 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:03 23395 8192000000 9921801744 10237763584 1005859472 10.935 100 88 177 0 1034291440 5707488 22724480
20:10:40 21678 8192000000 9978031936 10308554752 1060424608 11.461 100 93 186 0 1092410592 8612656 23373328
20:12:09 22047 8192000000 10000614528 10344042496 1088748464 11.731 100 87 174 0 1121048112 10951936 21347712
20:13:35 11458 8192000000 10048319088 10401705984 1137751328 12.194 100 83 166 0 1169784608 12693280 19340000
20:14:51 9570 8192000000 10100450656 10465431552 1185444736 12.641 100 79 158 1 1215112800 11912064 17756000
20:16:02 4817 8192000000 10129902608 10506240000 1222723392 12.987 100 74 147 0 1250906176 11847312 16335472
20:17:10 8683 8192000000 10181169760 10563264512 1266739936 13.392 100 78 155 0 1295521120 12098240 16682944
20:18:20 1813 8192000000 10208404640 10595139584 1288261728 13.588 100 88 176 2 1321460528 13175408 20023392
20:19:23 2609 8192000000 10218216368 10616340480 1309678592 13.783 100 73 145 2 1340421952 13901488 16841872
20:20:28 4195 8192000000 10207609312 10619744256 1320102736 13.878 100 29 58 1 1334183536 7526896 6553904
20:21:35 50229 8192000000 10214434032 10639237120 1328012080 13.949 100 29 59 0 1341077408 5971024 7094304
20:23:27 7017 8192000000 10230951552 10654343168 1335096864 14.013 100 27 52 1 1346843360 5821600 5924896
20:24:37 9757 8192000000 10227695952 10656747520 1340834480 14.065 100 27 54 0 1353811968 7092320 5885168
20:25:50 7010 8192000000 10215713200 10639777792 1308746352 13.775 98 20 40 1 1318732176 5575472 4410352
20:26:59 55275 8192000000 10205888592 10626867200 1294268480 13.643 97 31 62 1 1307223392 5808160 7146752
20:28:56 5568 8192000000 10189981280 10600570880 1282332512 13.534 99 19 38 1 1292576864 5782320 4462032
20:30:05 9333 8192000000 10151987056 10551291904 1216924512 12.933 96 25 50 3 1228754528 5736704 6093312
20:31:19 6032 8192000000 10125984464 10516189184 1199376064 12.771 97 23 46 1 1210585552 5875792 5333696
20:32:26 9774 8192000000 10128109024 10534449152 1224488736 13.003 98 27 54 0 1236978576 6848368 5641472
20:33:37 7302 8192000000 10165970400 10578231296 1277080880 13.486 100 21 43 0 1289078208 5633712 6363616
20:34:46 8146 8192000000 10180945168 10593910784 1290001248 13.604 100 20 40 1 1300504080 5519136 4983696
20:35:58 9784 8192000000 10187865248 10605453312 1301171152 13.706 100 13 26 2 1309311776 5199728 2940896
20:37:09 27635 8192000000 10193473312 10609917952 1303233024 13.725 100 19 38 1 1313530064 6356816 3940224
20:38:43 25919 8192000000 10239577680 10647240704 1331418384 13.980 100 47 94 0 1350483376 8876000 10188992
20:40:14 9361 8192000000 10267121424 10677272576 1350519952 14.152 100 53 106 0 1375509280 13290464 11698864
20:41:34 13420 8192000000 10282033280 10695979008 1366726656 14.298 100 56 112 1 1394102832 13794144 13582032
20:42:55 57147 8192000000 10296780624 10720010240 1380318992 14.419 100 52 104 0 1412039120 19733360 11986768
20:44:59 13955 8192000000 10308346672 10728894464 1374319504 14.366 99 42 84 0 1406717936 22096576 10301856
20:46:17 18027 8192000000 10319540672 10742808576 1395592240 14.556 100 50 98 3 1433908640 26470256 11846144
20:47:39 4388 8192000000 10328990400 10752720896 1400753552 14.602 100 50 100 4 1442719296 29401600 12564144
20:48:48 7547 8192000000 10358615328 10774450176 1420801552 14.780 100 63 126 0 1468411136 32919072 14690512
20:50:04 3282 8192000000 10370256592 10788921344 1423110288 14.800 100 74 148 0 1477785520 36341968 18333264
20:51:11 9133 8192000000 10362357504 10785529856 1406942864 14.657 99 59 116 2 1463378816 40659984 15775968
20:52:26 4613 8192000000 10366524208 10786664448 1411391536 14.696 100 66 132 0 1475626128 46868880 17365712
20:53:38 2372 8192000000 10349221120 10761469952 1395563552 14.555 100 86 172 1 1452341424 35886512 20891360
20:54:46 7576 8192000000 10347775040 10760622080 1378283424 14.401 98 78 154 1 1435598928 37628368 19687136
20:56:00 3375 8192000000 10367396336 10770059264 1395209712 14.552 100 87 174 0 1466703360 49299760 22193888
20:57:08 1340 8192000000 10353986176 10762506240 1374714240 14.369 99 76 153 1 1446207712 51964560 19528912
20:58:19 5488 8192000000 10289069424 10707812352 1330190400 13.969 99 53 105 2 1388947568 44282672 14474496
20:59:31 14352 8192000000 10271919680 10693132288 1336786320 14.028 99 33 64 0 1376515056 30899184 8829552
21:01:10 20550 8192000000 10286315664 10713587712 1338063056 14.040 99 28 56 3 1384925936 39613488 7249392
21:02:36 23869 8192000000 10275329648 10714525696 1355632912 14.198 100 19 38 1 1384201456 23095536 5473008
21:04:01 97555 8192000000 10275153984 10703106048 1295259056 13.652 96 24 48 1 1326750448 24996368 6495024
21:06:48 12673 8192000000 10273507312 10718445568 1354883264 14.191 100 22 44 1 1373732736 13285296 5564176
21:08:41 49605 8192000000 10289094496 10718822400 1342765920 14.082 98 38 76 4 1367659552 15735120 9158512
21:10:38 123646 8192000000 10299923664 10744193024 1323823904 13.911 96 14 28 0 1342217296 16027280 2366112
21:13:48 81660 8192000000 10288758192 10744590336 1368896992 14.317 99 9 18 0 1390091952 19565712 1629248
21:16:10 20941 8192000000 10312527968 10746073088 1378623344 14.404 99 51 100 1 1397162912 7405696 11133872
21:17:34 113486 8192000000 10307639952 10762100736 1380102384 14.417 99 6 12 0 1397849424 16302656 1444384
21:20:28 96885 8192000000 10311875872 10756530176 1387689792 14.485 99 15 31 2 1403904096 12726416 3487888
21:23:06 53644 8192000000 10316256016 10759995392 1389283456 14.499 99 50 95 1 1409235760 8906336 11045968
21:25:08 32801 8192000000 10313192960 10770907136 1404896032 14.639 100 36 69 0 1424989840 12020224 8073584
21:26:45 35558 8192000000 10322877984 10784079872 1414799104 14.727 100 21 42 0 1433358832 13445792 5113936
21:29:00 49435 8192000000 10311495520 10775203840 1393968720 14.541 98 27 54 0 1418768304 18252304 6547280
21:31:00 28932 8192000000 10316110400 10788130816 1398728464 14.584 99 5 10 0 1416996304 17146256 1121584
21:32:30 50006 8192000000 10313741632 10782941184 1407909568 14.665 100 11 21 1 1426343504 15881600 2552336
21:34:23 58335 8192000000 10291912960 10745643008 1347401872 14.124 96 21 42 0 1359280208 7008848 4869488
21:36:30 55418 8192000000 10298485632 10761990144 1394989920 14.550 100 22 44 1 1408429776 8831040 4608816
21:38:31 49877 8192000000 10296644560 10756866048 1378832736 14.406 98 17 34 1 1390753520 7848784 4072000
21:40:21 97724 8192000000 10273466096 10732769280 1328051264 13.950 96 15 30 1 1348823152 17243280 3528608
21:43:00 57660 8192000000 10270965968 10717511680 1300494272 13.700 94 21 43 1 1316542032 11900064 4147696
21:44:58 78002 8192000000 10270313296 10694664192 1278476336 13.499 94 32 64 0 1297146848 11056736 7613776
21:47:18 70477 8192000000 10262854016 10693971968 1282358432 13.535 95 23 46 0 1299079056 11703152 5017472
21:49:29 80342 8192000000 10251515168 10696237056 1281003040 13.522 95 19 38 0 1303261008 17983296 4274672
21:51:53 67780 8192000000 10268176464 10725228544 1328035504 13.949 98 14 28 1 1348784144 17640000 3108640
21:54:41 81217 8192000000 10259993248 10716889088 1342863440 14.083 99 18 35 0 1367646608 20514960 4268208
21:57:11 30619 8192000000 10270216032 10719072256 1364955888 14.282 100 44 88 1 1384206128 9965328 9284912
21:58:44 74666 8192000000 10262845600 10717323264 1354638160 14.189 99 9 18 0 1365448848 8522160 2288528
22:01:02 61625 8192000000 10260218960 10722439168 1369780080 14.325 100 20 40 1 1385964464 10976352 5208032
22:03:04 53894 8192000000 10263090400 10727972864 1354984400 14.192 99 15 30 0 1366811680 8458960 3368320
22:05:02 46163 8192000000 10294435296 10740097024 1368342560 14.312 99 16 32 1 1380419536 8344752 3732224
22:06:52 40066 8192000000 10294442944 10745667584 1371070272 14.337 99 26 52 2 1387265760 10246688 5948800
22:08:35 55337 8192000000 10299855232 10760437760 1390199632 14.508 100 26 52 2 1407159936 11103040 5857264
22:10:44 120866 8192000000 10308216592 10765058048 1329163552 13.960 95 16 32 0 1346262512 13653744 3445216
22:13:48 67326 8192000000 10308201168 10760908800 1370576800 14.332 98 11 22 1 1384527920 11470416 2480704
22:15:58 83803 8192000000 10294995120 10748915712 1339394416 14.052 96 13 26 0 1352306192 10228880 2682896
22:18:22 116143 8192000000 10299911872 10754744320 1370708144 14.333 98 22 44 1 1389232560 13354880 5169536
22:21:37 35211 8192000000 10304618480 10763812864 1384228896 14.454 99 21 42 0 1406611712 17769840 4612976
22:23:13 47890 8192000000 10289285232 10749546496 1375009264 14.372 99 22 44 0 1391002080 11171408 4821408
22:25:53 53661 8192000000 10284612320 10740338688 1355739808 14.199 97 20 40 1 1373070864 12292336 5038720
22:27:51 51156 8192000000 10292708480 10753458176 1379318928 14.410 99 16 32 1 1395968976 12475328 4174720
22:29:49 46193 8192000000 10262412480 10718302208 1286739360 13.575 93 24 48 1 1300753360 8647888 5366112
22:31:46 37388 8192000000 10281100096 10724691968 1356608848 14.207 99 32 64 0 1376231792 11472544 8150400
22:33:24 76294 8192000000 10266665680 10712961024 1294240192 13.643 94 16 32 0 1308415216 10422688 3752336
22:35:42 45811 8192000000 10289604912 10730172416 1365165328 14.284 100 27 52 0 1384686192 13503872 6016992
22:37:33 25767 8192000000 10260329840 10696720384 1306071808 13.750 96 36 72 0 1324160272 10006080 8082384

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 23-11-2021 22:38:56 to 23-11-2021 22:39:11.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 186 v_add_invoice_to_stock 335544517
except 17 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 23-11-2021 22:39:12 to 23-11-2021 22:39:13.

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          246785
MON$OLDEST_ACTIVE               249155
MON$OLDEST_SNAPSHOT             249155
MON$NEXT_TRANSACTION            249155
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                       2037920
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 ---
:256: 
:257: mscs-test-161.budzdorov.ru    Tue Nov 23 22:38:02 2021
:258:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:259: 
--- end of diff output ---

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

23.11.21 22:39:14. 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-23 22:39:17 No severe PSQL-related problems occured
23.11.21 22:39:18. End of report.