Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 30.04.2022 19: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 16444676 kB
MemAvailable 31512864 kB
Buffers 0 kB
Cached 15237148 kB
SwapCached 3188 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 3.0.10
DB_NAME                         /home/bases/oltp30-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3333
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp30-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp30
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-04-30 19:38:15
DTS_END                         2022-04-30 19:38:15.6030
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/44284
TRN_ID                          240930
ATT_ID                          688
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: 9362.10 19764 1123452
customer order: creation 172.48 381 20697
customer order: refuse 14.47 372 1736
order to supplier: creation 46.23 1272 5547
order to supplier: removal 3.49 100356 419
invoice (draft): creation 46.21 3546 5545
invoice (draft): removal 53.53 795 6424
invoice accept: apply 41.78 17299 5014
invoice accept: cancel 48.13 884 5775
customer reserve: creation 3855.01 170 462601
customer reserve: removal 4786.68 58 574401
realization accept: apply 83.70 201 10044
realization accept: cancel 79.32 110 9518
payment from customer: creation 60.31 144 7237
payment from customer: removal 6.07 100 728
payment to supplier: creation 49.07 172 5888
payment to supplier: removal 3.63 101 436
service: total inventory turnovers 4.58 1294 549
service: total monetary turnovers 6.63 34 796
service: aggregate perf. data 0.78 21693 93
service: refresh index statistics 0.03 246301 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 30-04-2022 19:39:00 to 30-04-2022 19:39:02.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3175
TEST_TIME 2 4435
TEST_TIME 3 3995
TEST_TIME 4 4647
TEST_TIME 5 5456
TEST_TIME 6 5516
TEST_TIME 7 5328
TEST_TIME 8 5313
TEST_TIME 9 5520
TEST_TIME 10 5740
TEST_TIME 11 6106
TEST_TIME 12 6091
TEST_TIME 13 6826
TEST_TIME 14 6842
TEST_TIME 15 7162
TEST_TIME 16 7057
TEST_TIME 17 7477
TEST_TIME 18 7760
TEST_TIME 19 7991
TEST_TIME 20 8051
TEST_TIME 21 8645
TEST_TIME 22 8671
TEST_TIME 23 8470
TEST_TIME 24 8531
TEST_TIME 25 9102
TEST_TIME 26 9186
TEST_TIME 27 9739
TEST_TIME 28 9743
TEST_TIME 29 10124
TEST_TIME 30 11010
TEST_TIME 31 10953
TEST_TIME 32 10612
TEST_TIME 33 10804
TEST_TIME 34 10777
TEST_TIME 35 10576
TEST_TIME 36 10526
TEST_TIME 37 10460
TEST_TIME 38 10323
TEST_TIME 39 10219
TEST_TIME 40 10197
TEST_TIME 41 10161
TEST_TIME 42 10075
TEST_TIME 43 9952
TEST_TIME 44 9816
TEST_TIME 45 9699
TEST_TIME 46 9681
TEST_TIME 47 9651
TEST_TIME 48 9600
TEST_TIME 49 9505
TEST_TIME 50 9465
TEST_TIME 51 9382
TEST_TIME 52 9346
TEST_TIME 53 9300
TEST_TIME 54 9422
TEST_TIME 55 9343
TEST_TIME 56 9366
TEST_TIME 57 9316
TEST_TIME 58 9336
TEST_TIME 59 9374
TEST_TIME 60 9353
TEST_TIME 61 9398
TEST_TIME 62 9466
TEST_TIME 63 9467
TEST_TIME 64 9488
TEST_TIME 65 9521
TEST_TIME 66 9491
TEST_TIME 67 9635
TEST_TIME 68 9627
TEST_TIME 69 9619
TEST_TIME 70 9644
TEST_TIME 71 9647
TEST_TIME 72 9734
TEST_TIME 73 9675
TEST_TIME 74 9555
TEST_TIME 75 9562
TEST_TIME 76 9574
TEST_TIME 77 9514
TEST_TIME 78 9482
TEST_TIME 79 9511
TEST_TIME 80 9475
TEST_TIME 81 9452
TEST_TIME 82 9439
TEST_TIME 83 9428
TEST_TIME 84 9369
TEST_TIME 85 9351
TEST_TIME 86 9312
TEST_TIME 87 9378
TEST_TIME 88 9452
TEST_TIME 89 9432
TEST_TIME 90 9373
TEST_TIME 91 9462
TEST_TIME 92 9454
TEST_TIME 93 9503
TEST_TIME 94 9513
TEST_TIME 95 9562
TEST_TIME 96 9561
TEST_TIME 97 9488
TEST_TIME 98 9569
TEST_TIME 99 9547
TEST_TIME 100 9484
TEST_TIME 101 9458
TEST_TIME 102 9395
TEST_TIME 103 9402
TEST_TIME 104 9403
TEST_TIME 105 9367
TEST_TIME 106 9350
TEST_TIME 107 9343
TEST_TIME 108 9298
TEST_TIME 109 9250
TEST_TIME 110 9256
TEST_TIME 111 9254
TEST_TIME 112 9285
TEST_TIME 113 9319
TEST_TIME 114 9264
TEST_TIME 115 9314
TEST_TIME 116 9360
TEST_TIME 117 9361
TEST_TIME 118 9343
TEST_TIME 119 9423
TEST_TIME 120 9365

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 30-04-2022 19:39:04 to 30-04-2022 19:39:07.

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 1551568 1551568 0 0.00 0 31197 6 0 0
doc_list_biud 1551565 1551565 0 0.00 0 22862 27 0 0
sp_add_invoice_to_stock 5111 5014 97 1.89 28 153210 17300 0 97
sp_cancel_adding_invoice 5775 5775 0 0.00 4 72783 884 0 0
sp_cancel_client_order 1736 1736 0 0.00 4 13031 372 0 0
sp_cancel_customer_reserve 574401 574401 0 0.00 1 21539 58 0 0
sp_cancel_pay_from_customer 728 728 0 0.00 2 9012 101 0 0
sp_cancel_pay_to_supplier 436 436 0 0.00 2 8748 101 0 0
sp_cancel_supplier_invoice 6436 6424 12 0.18 2 18960 795 0 12
sp_cancel_supplier_order 419 419 0 0.00 7 1014634 100357 0 0
sp_cancel_write_off 9518 9518 0 0.00 1 18917 111 0 0
sp_client_order 20697 20697 0 0.00 3 35224 381 0 0
sp_customer_reserve 462601 462601 0 0.00 0 38013 170 0 0
sp_fill_shopping_cart 31774 31774 0 0.00 1 36873 45 0 0
sp_fill_shopping_cart_clo_res 462595 462595 0 0.00 0 19037 14 0 0
sp_get_clo_for_invoice 5008 5008 0 0.00 0 28476 345 0 0
sp_kill_cost_storno 16457 16457 0 0.00 0 8720 60 0 0
sp_kill_qstorno_ret_qs2qd 1165163 1165163 0 0.00 0 21526 32 0 0
sp_kill_qty_storno 613325 613325 0 0.00 0 22860 69 0 0
sp_lock_selected_doc 20895 20895 0 0.00 0 10309 6 0 0
sp_make_cost_storno 28177 28177 0 0.00 0 31194 72 0 0
sp_multiply_rows_for_pdistr 28177 28177 0 0.00 0 25302 39 0 0
sp_multiply_rows_for_qdistr 20697 20697 0 0.00 0 10737 167 0 0
sp_pay_from_customer 7237 7237 0 0.00 3 25318 145 0 0
sp_pay_to_supplier 5888 5888 0 0.00 5 19300 172 0 0
sp_payment_common 13123 13123 0 0.00 1 19245 44 0 0
sp_qd_handle_on_cancel_clo 1736 1736 0 0.00 0 13025 328 0 0
sp_qd_handle_on_invoice_upd_sts 10783 10783 0 0.00 0 22856 343 0 0
sp_qd_handle_on_reserve_upd_sts 10044 10044 0 0.00 0 12572 59 0 0
sp_reserve_write_off 10044 10044 0 0.00 6 31208 202 0 0
sp_supplier_invoice 5545 5545 0 0.00 7 93201 3546 0 0
sp_supplier_order 5547 5547 0 0.00 5 56910 1273 0 0
srv_aggregate_perf_data 93 93 0 0.00 2369 506797 21694 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1285 154869 49874 0 0
srv_find_qd_qs_mism 1075310 1075310 0 0.00 0 22102 5 0 0
srv_make_invnt_saldo 549 549 0 0.00 27 196699 1294 0 0
srv_make_money_saldo 796 796 0 0.00 0 7219 35 0 0
srv_recalc_idx_stat 4 4 0 0.00 196346 349036 246302 0 0
t$perf-abend:sp_add_invoice_to_stock 97 97 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 5014 5014 0 0.00 0 10545 152 0 0
t$perf-norm:sp_cancel_adding_invoice 412 412 0 0.00 0 2049 105 0 0
t$perf-norm:sp_cancel_client_order 1736 1736 0 0.00 0 10433 17 0 0
t$perf-norm:sp_cancel_customer_reserve 701 701 0 0.00 0 1130 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 728 728 0 0.00 0 686 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 436 436 0 0.00 0 10366 27 0 0
t$perf-norm:sp_cancel_supplier_invoice 422 422 0 0.00 0 997 18 0 0
t$perf-norm:sp_cancel_supplier_order 419 419 0 0.00 0 28312 1346 0 0
t$perf-norm:sp_cancel_write_off 991 991 0 0.00 0 2224 11 0 0
t$perf-norm:sp_client_order 20697 20697 0 0.00 0 15828 18 0 0
t$perf-norm:sp_customer_reserve 989 989 0 0.00 0 5447 17 0 0
t$perf-norm:sp_pay_from_customer 7237 7237 0 0.00 0 9365 9 0 0
t$perf-norm:sp_pay_to_supplier 5888 5888 0 0.00 0 10664 14 0 0
t$perf-norm:sp_reserve_write_off 10044 10044 0 0.00 0 9610 10 0 0
t$perf-norm:sp_supplier_invoice 5545 5545 0 0.00 0 3113 15 0 0
t$perf-norm:sp_supplier_order 5547 5547 0 0.00 0 5218 18 0 0
t$perf-norm:srv_aggregate_perf_data 93 93 0 0.00 0 709 17 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 279 6 0 0
t$perf-norm:srv_make_invnt_saldo 549 549 0 0.00 0 1299 20 0 0
t$perf-norm:srv_make_money_saldo 796 796 0 0.00 0 1401 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 4 3 0 0
v_add_invoice_to_stock 10202 10008 194 1.90 0 11140 20 0 194
v_all_customers 37920 37920 0 0.00 0 86 1 0 0
v_all_suppliers 30618 30618 0 0.00 0 15 1 0 0
v_all_wares 41386 41386 0 0.00 0 133 1 0 0
v_cancel_adding_invoice 824 824 0 0.00 0 4152 14 0 0
v_cancel_client_order 3472 3472 0 0.00 0 10469 9 0 0
v_cancel_customer_prepayment 1456 1456 0 0.00 0 3817 7 0 0
v_cancel_customer_reserve 1402 1402 0 0.00 0 1222 6 0 0
v_cancel_payment_to_supplier 872 872 0 0.00 0 2895 7 0 0
v_cancel_supplier_invoice 868 844 24 2.76 0 2724 19 0 24
v_cancel_supplier_order 838 838 0 0.00 0 628 4 0 0
v_cancel_write_off 1982 1982 0 0.00 0 1501 12 0 0
v_max_id_clo_ord 5541 5541 0 0.00 0 2350 2 0 0
v_max_id_clo_res 989 989 0 0.00 0 3548 13 0 0
v_max_id_ord_sup 5539 5539 0 0.00 0 4652 5 0 0
v_max_non_paid_invoice 1658 1658 0 0.00 0 6679 7 0 0
v_max_non_paid_realizn 3871 3871 0 0.00 0 4338 4 0 0
v_min_id_clo_ord 5541 5541 0 0.00 0 7470 13 0 0
v_min_id_clo_res 989 989 0 0.00 0 968 6 0 0
v_min_id_ord_sup 5539 5539 0 0.00 0 7026 14 0 0
v_min_non_paid_invoice 5887 5887 0 0.00 0 19238 45 0 0
v_min_non_paid_realizn 7236 7236 0 0.00 0 12500 29 0 0
v_our_firm 10196 10196 0 0.00 0 121 1 0 0
v_reserve_write_off 20086 20086 0 0.00 0 11045 6 0 0
x_lock_depdocs_on_canc_invoice 11777 11777 0 0.00 0 18275 113 0 0
x_lock_depdocs_on_canc_sup_ord 419 419 0 0.00 0 7519 296 0 0
x_make_qstorno_1000_1200 5547 5547 0 0.00 1 56769 1183 0 0
x_make_qstorno_1200_2000 5545 5545 0 0.00 3 93112 3378 0 0
x_make_qstorno_2100_3300 462601 462601 0 0.00 0 38012 153 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 30-04-2022 19:39:08 to 30-04-2022 19:39:12.

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:08:19 2 8192000000 8801737536 8813907968 7763408 0.094 2 2 3 0 7904496 66144 74944
17:09:20 27252 8192000000 9896751664 10204794880 977340688 10.658 100 87 173 0 1007585552 6359744 23885120
17:10:54 22222 8192000000 9944697152 10264514560 1023717184 11.108 100 92 184 1 1056266080 8605440 23943456
17:12:22 18335 8192000000 9975123680 10309165056 1056883664 11.427 100 78 156 1 1086291344 9448400 19959280
17:13:47 11959 8192000000 10031942432 10378903552 1116883120 11.998 100 81 162 1 1147552160 11093552 19575488
17:15:05 7918 8192000000 10080565104 10438406144 1168663184 12.484 100 78 156 0 1198584032 12066672 17854176
17:16:14 5894 8192000000 10120616880 10491719680 1214173424 12.908 100 76 152 2 1243735888 12379184 17183280
17:17:21 2621 8192000000 10157893456 10532130816 1245870960 13.200 100 85 168 2 1276903600 12511504 18521136
17:18:26 2385 8192000000 10189118464 10568015872 1277438592 13.490 100 88 174 1 1311024000 14493392 19092016
17:19:29 1620 8192000000 10211215536 10595467264 1293551888 13.637 100 88 177 1 1325324512 11471168 20301456
17:20:32 2689 8192000000 10215169872 10604163072 1300191264 13.697 100 82 165 0 1328837600 9648784 18997552
17:21:37 4347 8192000000 10200082800 10605674496 1306763904 13.757 100 36 72 0 1323365200 8197280 8404016
17:22:43 11575 8192000000 10201888880 10618363904 1314206640 13.824 100 18 36 0 1324220464 5853536 4160288
17:24:03 9337 8192000000 10205952224 10624491520 1318134912 13.860 100 24 48 0 1332721152 9411088 5175152
17:25:12 33753 8192000000 10207793328 10630987776 1318350528 13.862 100 30 60 0 1331438496 5857488 7230480
17:26:52 5215 8192000000 10222330256 10640924672 1326767104 13.938 100 22 44 0 1337859824 5784592 5308128
17:28:00 5420 8192000000 10210770336 10624176128 1303520208 13.727 99 26 54 1 1314314112 4866800 5927104
17:29:06 60864 8192000000 10167542768 10581102592 1229608288 13.050 94 26 52 0 1241594960 5183232 6803440
17:31:11 6591 8192000000 10120825120 10518011904 1201482416 12.790 96 28 56 0 1213596432 5912960 6201056
17:32:19 6406 8192000000 10137480400 10537734144 1230916192 13.063 99 25 48 2 1242672592 5742320 6014080
17:33:28 63362 8192000000 10160726432 10564116480 1256334912 13.296 99 34 68 2 1271024640 5252400 9437328
17:35:32 6152 8192000000 10175189888 10586431488 1285259520 13.561 100 29 58 0 1297806352 5529312 7017520
17:36:39 7468 8192000000 10200216096 10614894592 1302695264 13.720 100 20 40 0 1322606384 14576976 5334144
17:37:47 11291 8192000000 10197381808 10619224064 1312227232 13.806 100 12 24 0 1320020592 5114016 2679344
17:39:06 62479 8192000000 10251048288 10666041344 1341644832 14.072 100 50 100 0 1360360640 7259104 11456704
17:41:10 11692 8192000000 10257195968 10677739520 1356017408 14.202 100 40 80 0 1374021184 9103392 8900384
17:42:30 1934 8192000000 10290686224 10702127104 1371360832 14.339 100 64 126 1 1400709056 14663888 14684336
17:43:37 36889 8192000000 10285379696 10717396992 1382562256 14.439 100 38 76 0 1407856256 16239968 9054032
17:45:18 25720 8192000000 10315515296 10732974080 1378399680 14.402 99 49 98 1 1407162624 17467712 11295232
17:46:54 7301 8192000000 10313833616 10738397184 1396025632 14.560 100 41 82 0 1425449968 19237328 10187008
17:48:07 7275 8192000000 10329791472 10754973696 1405232816 14.642 100 57 112 1 1440032336 21828672 12970848
17:49:17 1927 8192000000 10328044464 10743275520 1396179792 14.561 100 79 156 0 1440212736 24691984 19340960
17:50:26 5792 8192000000 10307218640 10724737024 1378344192 14.402 99 56 111 1 1418782064 27387136 13050736
17:51:36 8791 8192000000 10321339152 10738167808 1384156880 14.454 100 72 144 0 1434773376 33098736 17517760
17:52:56 3717 8192000000 10314562448 10725433344 1366064896 14.292 99 77 154 1 1419399248 33612064 19722288
17:54:03 7866 8192000000 10297360480 10704015360 1354639584 14.189 99 66 131 1 1401160112 29905776 16614752
17:55:17 2430 8192000000 10329832256 10729275392 1369374560 14.321 100 89 177 3 1431978688 39209440 23394688
17:56:28 3255 8192000000 10330888896 10740781056 1375452784 14.376 100 70 140 2 1435256768 41322192 18481792
17:57:38 12334 8192000000 10308241936 10724265984 1362584272 14.261 100 44 88 1 1410552320 35846912 12121136
17:58:56 1277 8192000000 10355800160 10761814016 1384932752 14.461 100 84 166 1 1451297296 44407248 21957296
18:00:02 3663 8192000000 10328418272 10751467520 1382276112 14.437 100 46 92 1 1430547584 35464464 12807008
18:01:25 7741 8192000000 10342247856 10765053952 1393820992 14.540 100 55 109 0 1449408224 41264544 14322688
18:02:37 22389 8192000000 10326629728 10754592768 1358925312 14.228 99 39 78 3 1421183312 50664864 11593136
18:04:11 8754 8192000000 10307415792 10736533504 1354554560 14.188 100 34 68 1 1412734800 48255888 9924352
18:05:25 7984 8192000000 10307091568 10739671040 1353634816 14.180 100 31 62 2 1410566416 49165040 7766560
18:06:35 21980 8192000000 10283319152 10718142464 1348786256 14.137 100 21 42 2 1393788384 39003616 5998512
18:07:59 39060 8192000000 10286139184 10726281216 1339637728 14.054 99 10 20 0 1384903472 42324848 2940896
18:09:40 90756 8192000000 10282140912 10709884928 1358765424 14.226 100 20 40 2 1378409104 14883360 4760320
18:12:13 18285 8192000000 10283122016 10740248576 1377623936 14.395 100 11 22 1 1393081808 12812256 2645616
18:14:04 131616 8192000000 10297210752 10746142720 1375094016 14.373 99 15 30 0 1389783696 11233264 3456416
18:17:25 46912 8192000000 10304400160 10745958400 1365811504 14.290 98 18 35 1 1379341888 9150848 4379536
18:19:35 154846 8192000000 10290057680 10742599680 1375428480 14.376 99 14 28 1 1388679216 10433264 2817472
18:23:10 101645 8192000000 10288560384 10746175488 1383835136 14.451 99 30 62 0 1397649088 7276240 6537712
18:25:52 81101 8192000000 10310678864 10771189760 1399137472 14.587 100 34 68 0 1417064992 9277136 8650384
18:28:14 48141 8192000000 10297241072 10752774144 1381281952 14.428 99 22 44 1 1397039024 10762928 4994144
18:30:07 77542 8192000000 10309661040 10779234304 1391668752 14.521 99 14 28 0 1415073280 19344048 4060480
18:32:33 48496 8192000000 10294486848 10777260032 1373845520 14.361 98 10 20 0 1389075680 12690768 2539392
18:34:21 63339 8192000000 10294742576 10770694144 1387184576 14.481 99 16 32 0 1401240432 10489840 3566016
18:36:28 104295 8192000000 10304029472 10762260480 1351227680 14.159 96 12 24 0 1364373056 10878400 2266976
18:39:14 42889 8192000000 10301937632 10763735040 1367799728 14.307 98 25 50 3 1388716832 15827152 5089952
18:41:07 86951 8192000000 10275673456 10723241984 1309906672 13.785 94 7 14 1 1323535952 12235120 1394160
18:43:35 50963 8192000000 10271262608 10725064704 1321235312 13.888 96 18 36 0 1335951184 10833984 3881888
18:45:30 87372 8192000000 10258051440 10710618112 1309837568 13.785 96 13 26 0 1325953648 13424048 2692032
18:47:58 113095 8192000000 10287240992 10732273664 1334171584 14.005 98 16 32 0 1349356048 11662240 3522224
18:50:58 76476 8192000000 10270091120 10730131456 1363651232 14.270 100 2 4 0 1382295632 18391184 253216
18:53:44 63502 8192000000 10242462192 10682826752 1268505696 13.408 93 13 27 1 1280453024 9166016 2781312
18:55:49 38803 8192000000 10269754160 10706673664 1341581072 14.072 99 39 78 1 1361135344 10999136 8555136
18:57:29 33352 8192000000 10247272704 10699141120 1329332096 13.961 98 23 45 0 1345730096 10905072 5492928
18:59:05 59262 8192000000 10241753920 10692640768 1304734512 13.738 96 18 35 0 1318484912 9690304 4060096
19:01:12 43952 8192000000 10239177120 10706833408 1334630256 14.009 99 5 10 0 1348942160 13006880 1305024
19:03:23 64959 8192000000 10280880992 10723586048 1336367648 14.025 98 9 17 1 1348437056 9793648 2275760
19:05:53 106946 8192000000 10274868944 10742382592 1356520848 14.206 99 12 24 0 1378774288 19073760 3179680
19:08:40 34149 8192000000 10300515760 10743316480 1377326000 14.393 99 38 76 2 1397243136 11535952 8381184
19:10:17 69243 8192000000 10296799920 10751098880 1364951216 14.282 98 12 24 1 1379010688 11522000 2537472
19:12:32 74710 8192000000 10297730528 10754711552 1368211872 14.311 98 10 20 0 1383028912 12838688 1978352
19:14:47 62606 8192000000 10325192960 10762919936 1396784352 14.566 100 27 54 0 1413112160 10396864 5930944
19:16:51 25983 8192000000 10287184352 10735476736 1332773760 13.992 96 30 60 1 1350574384 11400736 6399888
19:18:17 143249 8192000000 10294338992 10753503232 1377255376 14.392 99 24 48 1 1391978528 9113184 5609968
19:21:41 58354 8192000000 10279542096 10746916864 1369918736 14.326 98 8 16 1 1382208640 10439616 1850288
19:23:40 61939 8192000000 10273847200 10734915584 1358676832 14.225 98 14 28 0 1373089408 10980192 3432384
19:25:43 72964 8192000000 10283930816 10741452800 1382777744 14.441 100 16 32 0 1395605296 9583376 3244176
19:28:01 96848 8192000000 10274769408 10738479104 1309875488 13.785 94 8 16 0 1322557920 11358080 1324352
19:30:41 81740 8192000000 10276993680 10738716672 1325102672 13.923 96 14 28 1 1339765024 11128624 3533728
19:33:03 43614 8192000000 10285160032 10744111104 1345111568 14.103 97 29 58 0 1374923568 23327568 6484432
19:35:01 70808 8192000000 10264602928 10709241856 1306307104 13.753 95 14 28 0 1319942864 10250336 3385424
19:37:37 48095 8192000000 8990793696 9194586112 156173824 1.870 12 1 2 0 157962016 1745840 42352

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 30-04-2022 19:39:14 to 30-04-2022 19:39:29.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 97 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:01, from 30-04-2022 19:39:30 to 30-04-2022 19:39:31.

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          241641
MON$OLDEST_ACTIVE               241642
MON$OLDEST_SNAPSHOT             241642
MON$NEXT_TRANSACTION            241642
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                       2051488
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33580 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33580 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33580 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33580 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 ---
:79: 
:80: mscs-test-161.budzdorov.ru    Sat Apr 30 19:38:27 2022
:81:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:82: 
--- end of diff output ---

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

30.04.22 19:39:32. There are 3 logs in /var/tmp/logs-oltp30 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2022-04-30 19:39:35 No severe PSQL-related problems occured
30.04.22 19:39:36. End of report.