Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 11.08.2021 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 58e1ddde69c44dad93d327777e5becec
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-05-09 08:45
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 4793.92
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 16201480 kB
MemAvailable 30978172 kB
Buffers 0 kB
Cached 15438548 kB
SwapCached 2560 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-08-11 19:37:44
DTS_END                         2021-08-11 19:37:44.3640
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/54470
TRN_ID                          249053
ATT_ID                          663
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: 9469.74 17341 1136369
customer order: creation 184.87 326 22184
customer order: refuse 15.97 325 1916
order to supplier: creation 48.82 1272 5858
order to supplier: removal 3.50 102009 420
invoice (draft): creation 48.44 3287 5813
invoice (draft): removal 52.17 821 6260
invoice accept: apply 44.12 17454 5294
invoice accept: cancel 48.11 801 5773
customer reserve: creation 4030.61 175 483673
customer reserve: removal 4679.18 59 561501
realization accept: apply 89.38 183 10726
realization accept: cancel 88.19 107 10583
payment from customer: creation 61.98 112 7437
payment from customer: removal 5.96 105 715
payment to supplier: creation 52.11 120 6253
payment to supplier: removal 3.55 65 426
service: total inventory turnovers 4.86 1043 583
service: total monetary turnovers 7.04 37 845
service: aggregate perf. data 0.88 21384 105
service: refresh index statistics 0.03 197141 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 11-08-2021 19:38:41 to 11-08-2021 19:38:43.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3712
TEST_TIME 2 5025
TEST_TIME 3 5156
TEST_TIME 4 4827
TEST_TIME 5 5733
TEST_TIME 6 5994
TEST_TIME 7 6199
TEST_TIME 8 6006
TEST_TIME 9 5845
TEST_TIME 10 5626
TEST_TIME 11 5578
TEST_TIME 12 5967
TEST_TIME 13 5878
TEST_TIME 14 5954
TEST_TIME 15 6066
TEST_TIME 16 6309
TEST_TIME 17 6448
TEST_TIME 18 6565
TEST_TIME 19 6878
TEST_TIME 20 7458
TEST_TIME 21 7944
TEST_TIME 22 8149
TEST_TIME 23 8283
TEST_TIME 24 9074
TEST_TIME 25 9235
TEST_TIME 26 9296
TEST_TIME 27 9378
TEST_TIME 28 9448
TEST_TIME 29 9572
TEST_TIME 30 10163
TEST_TIME 31 10594
TEST_TIME 32 10549
TEST_TIME 33 10536
TEST_TIME 34 10350
TEST_TIME 35 10223
TEST_TIME 36 10140
TEST_TIME 37 9920
TEST_TIME 38 10096
TEST_TIME 39 10029
TEST_TIME 40 9826
TEST_TIME 41 9798
TEST_TIME 42 9710
TEST_TIME 43 9723
TEST_TIME 44 9923
TEST_TIME 45 9777
TEST_TIME 46 9766
TEST_TIME 47 9764
TEST_TIME 48 9666
TEST_TIME 49 9713
TEST_TIME 50 9755
TEST_TIME 51 9832
TEST_TIME 52 9848
TEST_TIME 53 9902
TEST_TIME 54 9889
TEST_TIME 55 9890
TEST_TIME 56 9994
TEST_TIME 57 9975
TEST_TIME 58 10010
TEST_TIME 59 9969
TEST_TIME 60 9997
TEST_TIME 61 9991
TEST_TIME 62 10026
TEST_TIME 63 9906
TEST_TIME 64 9851
TEST_TIME 65 9773
TEST_TIME 66 9784
TEST_TIME 67 9723
TEST_TIME 68 9680
TEST_TIME 69 9657
TEST_TIME 70 9621
TEST_TIME 71 9548
TEST_TIME 72 9469
TEST_TIME 73 9480
TEST_TIME 74 9434
TEST_TIME 75 9464
TEST_TIME 76 9443
TEST_TIME 77 9422
TEST_TIME 78 9525
TEST_TIME 79 9584
TEST_TIME 80 9533
TEST_TIME 81 9619
TEST_TIME 82 9608
TEST_TIME 83 9594
TEST_TIME 84 9565
TEST_TIME 85 9630
TEST_TIME 86 9705
TEST_TIME 87 9613
TEST_TIME 88 9567
TEST_TIME 89 9502
TEST_TIME 90 9466
TEST_TIME 91 9421
TEST_TIME 92 9461
TEST_TIME 93 9514
TEST_TIME 94 9503
TEST_TIME 95 9502
TEST_TIME 96 9469
TEST_TIME 97 9495
TEST_TIME 98 9519
TEST_TIME 99 9499
TEST_TIME 100 9524
TEST_TIME 101 9529
TEST_TIME 102 9544
TEST_TIME 103 9537
TEST_TIME 104 9554
TEST_TIME 105 9596
TEST_TIME 106 9641
TEST_TIME 107 9638
TEST_TIME 108 9564
TEST_TIME 109 9533
TEST_TIME 110 9498
TEST_TIME 111 9483
TEST_TIME 112 9415
TEST_TIME 113 9472
TEST_TIME 114 9450
TEST_TIME 115 9495
TEST_TIME 116 9472
TEST_TIME 117 9461
TEST_TIME 118 9476
TEST_TIME 119 9487
TEST_TIME 120 9472

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 11-08-2021 19:38:45 to 11-08-2021 19:38:48.

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 1584037 1584037 0 0.00 0 20715 6 0 0
doc_list_biud 1584032 1584032 0 0.00 0 45804 27 0 0
sp_add_invoice_to_stock 5409 5294 115 2.12 42 185331 17454 0 115
sp_cancel_adding_invoice 5773 5773 0 0.00 5 52364 802 0 0
sp_cancel_client_order 1916 1916 0 0.00 4 9924 325 0 0
sp_cancel_customer_reserve 561501 561501 0 0.00 1 45806 60 0 0
sp_cancel_pay_from_customer 715 715 0 0.00 2 9951 106 0 0
sp_cancel_pay_to_supplier 426 426 0 0.00 3 4035 65 0 0
sp_cancel_supplier_invoice 6268 6260 8 0.12 1 15245 822 0 8
sp_cancel_supplier_order 420 420 0 0.00 5 1206255 102010 0 0
sp_cancel_write_off 10583 10583 0 0.00 1 18038 108 0 0
sp_client_order 22184 22184 0 0.00 4 30596 326 0 0
sp_customer_reserve 483673 483673 0 0.00 0 47414 176 0 0
sp_fill_shopping_cart 33824 33824 0 0.00 1 10235 35 0 0
sp_fill_shopping_cart_clo_res 483666 483666 0 0.00 0 21216 14 0 0
sp_get_clo_for_invoice 5287 5287 0 0.00 0 19749 275 0 0
sp_kill_cost_storno 17497 17497 0 0.00 0 8320 55 0 0
sp_kill_qstorno_ret_qs2qd 1140265 1140265 0 0.00 0 45741 33 0 0
sp_kill_qty_storno 602466 602466 0 0.00 0 45804 70 0 0
sp_lock_selected_doc 22094 22094 0 0.00 0 7637 6 0 0
sp_make_cost_storno 29703 29703 0 0.00 0 20712 51 0 0
sp_multiply_rows_for_pdistr 29703 29703 0 0.00 0 8570 26 0 0
sp_multiply_rows_for_qdistr 22184 22184 0 0.00 0 10346 141 0 0
sp_pay_from_customer 7437 7437 0 0.00 4 11185 113 0 0
sp_pay_to_supplier 6253 6253 0 0.00 4 16165 120 0 0
sp_payment_common 13689 13689 0 0.00 1 16058 29 0 0
sp_qd_handle_on_cancel_clo 1916 1916 0 0.00 0 9918 297 0 0
sp_qd_handle_on_invoice_upd_sts 11060 11060 0 0.00 0 28659 321 0 0
sp_qd_handle_on_reserve_upd_sts 10726 10726 0 0.00 0 20015 71 0 0
sp_reserve_write_off 10726 10726 0 0.00 6 20721 183 0 0
sp_supplier_invoice 5813 5813 0 0.00 7 83757 3287 0 0
sp_supplier_order 5858 5858 0 0.00 4 82407 1272 0 0
srv_aggregate_perf_data 105 105 0 0.00 2182 571813 21384 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1476 171552 47584 0 0
srv_find_qd_qs_mism 1085848 1085848 0 0.00 0 7745 5 0 0
srv_make_invnt_saldo 583 583 0 0.00 21 58248 1044 0 0
srv_make_money_saldo 845 845 0 0.00 1 11407 37 0 0
srv_recalc_idx_stat 4 4 0 0.00 105185 271808 197141 0 0
t$perf-abend:sp_add_invoice_to_stock 115 115 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5294 5294 0 0.00 0 11618 162 0 0
t$perf-norm:sp_cancel_adding_invoice 455 455 0 0.00 0 6497 105 0 0
t$perf-norm:sp_cancel_client_order 1916 1916 0 0.00 0 7095 22 0 0
t$perf-norm:sp_cancel_customer_reserve 683 683 0 0.00 0 463 5 0 0
t$perf-norm:sp_cancel_pay_from_customer 715 715 0 0.00 0 2422 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 426 426 0 0.00 0 562 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 423 423 0 0.00 0 2026 20 0 0
t$perf-norm:sp_cancel_supplier_order 420 420 0 0.00 0 24300 1634 0 0
t$perf-norm:sp_cancel_write_off 1043 1043 0 0.00 0 2796 10 0 0
t$perf-norm:sp_client_order 22184 22184 0 0.00 0 8137 16 0 0
t$perf-norm:sp_customer_reserve 1046 1046 0 0.00 0 340 2 0 0
t$perf-norm:sp_pay_from_customer 7437 7437 0 0.00 0 8184 9 0 0
t$perf-norm:sp_pay_to_supplier 6253 6253 0 0.00 0 7714 12 0 0
t$perf-norm:sp_reserve_write_off 10726 10726 0 0.00 0 8130 10 0 0
t$perf-norm:sp_supplier_invoice 5813 5813 0 0.00 0 7053 19 0 0
t$perf-norm:sp_supplier_order 5858 5858 0 0.00 0 9356 16 0 0
t$perf-norm:srv_aggregate_perf_data 105 105 0 0.00 0 952 14 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 81 2 0 0
t$perf-norm:srv_make_invnt_saldo 583 583 0 0.00 0 4288 36 0 0
t$perf-norm:srv_make_money_saldo 845 845 0 0.00 0 879 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 5 4 0 0
v_add_invoice_to_stock 10804 10574 230 2.12 0 7238 16 0 230
v_all_customers 40062 40062 0 0.00 0 24 1 0 0
v_all_suppliers 32170 32170 0 0.00 0 124 1 0 0
v_all_wares 44366 44366 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 910 910 0 0.00 0 169 5 0 0
v_cancel_client_order 3832 3832 0 0.00 0 3849 6 0 0
v_cancel_customer_prepayment 1430 1430 0 0.00 0 1314 5 0 0
v_cancel_customer_reserve 1366 1366 0 0.00 0 7528 8 0 0
v_cancel_payment_to_supplier 852 852 0 0.00 0 4003 8 0 0
v_cancel_supplier_invoice 862 846 16 1.85 0 3297 20 0 16
v_cancel_supplier_order 840 840 0 0.00 0 7512 13 0 0
v_cancel_write_off 2086 2086 0 0.00 0 3863 14 0 0
v_max_id_clo_ord 5850 5850 0 0.00 0 4374 3 0 0
v_max_id_clo_res 1046 1046 0 0.00 0 6822 26 0 0
v_max_id_ord_sup 5791 5791 0 0.00 0 6085 9 0 0
v_max_non_paid_invoice 1809 1809 0 0.00 0 3870 5 0 0
v_max_non_paid_realizn 3991 3991 0 0.00 0 3138 4 0 0
v_min_id_clo_ord 5850 5850 0 0.00 0 4629 6 0 0
v_min_id_clo_res 1046 1046 0 0.00 0 2304 7 0 0
v_min_id_ord_sup 5791 5791 0 0.00 0 5840 8 0 0
v_min_non_paid_invoice 6253 6253 0 0.00 0 16052 21 0 0
v_min_non_paid_realizn 7436 7436 0 0.00 0 10558 18 0 0
v_our_firm 11194 11194 0 0.00 0 84 1 0 0
v_reserve_write_off 21452 21452 0 0.00 0 8392 7 0 0
x_lock_depdocs_on_canc_invoice 11610 11610 0 0.00 0 14429 109 0 0
x_lock_depdocs_on_canc_sup_ord 420 420 0 0.00 0 4755 317 0 0
x_make_qstorno_1000_1200 5858 5858 0 0.00 2 82382 1198 0 0
x_make_qstorno_1200_2000 5813 5813 0 0.00 3 83745 3159 0 0
x_make_qstorno_2100_3300 483673 483673 0 0.00 0 47391 159 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 11-08-2021 19:38:49 to 11-08-2021 19:38:53.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
17:07:48 2 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
17:08:50 38215 8192000000 10397907280 10723020800 1455320352 15.085 100 94 188 0 1491380656 6326720 29733584
17:10:34 21868 8192000000 10465516176 10799165440 1514595168 15.603 100 88 176 1 1551543680 8735344 28213168
17:12:10 17533 8192000000 10528443904 10875985920 1576283072 16.136 100 80 160 0 1612538192 11329408 24925712
17:13:31 14330 8192000000 10604895952 10962755584 1659909568 16.848 100 91 182 1 1700785664 13921984 26954112
17:14:54 7840 8192000000 10642786976 11020464128 1713306704 17.296 100 74 148 1 1748347024 13568976 21471344
17:16:04 5523 8192000000 10707680304 11088089088 1775143632 17.809 100 77 150 2 1808997488 12750544 21103312
17:17:12 2263 8192000000 10774174512 11157028864 1830365648 18.262 100 85 170 1 1868327744 14232208 23729888
17:18:16 1915 8192000000 10806654992 11194200064 1867670336 18.565 100 92 184 0 1907224928 12881184 26673408
17:19:19 3309 8192000000 10821440448 11217928192 1878386704 18.652 100 74 148 3 1907039472 7584768 21068000
17:20:23 2951 8192000000 10801752560 11224260608 1886545696 18.718 100 41 77 6 1904559280 6845888 11167696
17:21:29 18460 8192000000 10800094576 11233820672 1897252128 18.804 100 13 26 1 1905755456 5587264 2916064
17:22:48 61881 8192000000 10841034672 11265503232 1904174432 18.860 100 31 61 1 1923027312 9123360 9729520
17:24:53 9061 8192000000 10830158336 11264229376 1915879728 18.954 100 28 57 2 1930193616 6065008 8248880
17:26:04 7489 8192000000 10812540992 11243966464 1883660992 18.695 99 31 62 0 1899306832 6112096 9533744
17:27:13 7981 8192000000 10799571280 11238875136 1882976656 18.689 99 28 56 0 1896691904 5972288 7742960
17:28:27 22198 8192000000 10744525776 11166904320 1734904992 17.476 93 17 34 1 1744320464 5198352 4217120
17:29:49 5600 8192000000 10710299520 11123032064 1764874928 17.725 98 33 64 3 1781812880 7532144 9405808
17:30:55 5966 8192000000 10706885664 11127083008 1787333984 17.910 99 34 68 4 1803269824 6217808 9718032
17:32:05 78911 8192000000 10749386848 11173310464 1801928608 18.030 98 21 42 1 1813543984 5302064 6313312
17:34:24 6552 8192000000 10773706224 11204870144 1863601008 18.532 100 28 56 0 1878295088 5656416 9037664
17:35:32 10894 8192000000 10787997856 11217301504 1875054800 18.625 100 19 37 2 1885948880 5519392 5374688
17:36:46 20207 8192000000 10829571264 11242364928 1887185872 18.723 100 32 63 6 1901494096 5285568 9022656
17:38:07 28072 8192000000 10850774752 11271897088 1917648144 18.968 100 39 78 1 1935408144 7005920 10754080
17:39:40 9270 8192000000 10881145408 11307200512 1946580464 19.199 100 59 117 0 1974266224 10276256 17409504
17:40:57 30227 8192000000 10892778304 11320573952 1958802816 19.297 100 52 104 2 1983837840 11034736 14000288
17:42:31 7019 8192000000 10923069600 11345567744 1979764544 19.463 100 68 136 0 2015102688 15713408 19624736
17:43:47 18693 8192000000 10906552848 11338629120 1940345568 19.150 98 55 110 2 1973646816 17803136 15498112
17:45:08 16779 8192000000 10924595888 11360493568 1984598272 19.501 100 49 96 1 2016043152 16437760 15007120
17:46:27 10809 8192000000 10925298912 11355131904 1953242208 19.252 98 48 96 1 1990603472 23437856 13923408
17:47:47 5766 8192000000 10936113200 11358285824 1965806576 19.352 99 53 106 0 2003630656 22366752 15457328
17:48:56 5614 8192000000 10946534608 11368218624 1975125232 19.426 99 76 152 1 2025990016 28206080 22658704
17:50:07 6547 8192000000 10935091808 11365167104 1973185616 19.411 100 51 100 1 2021470320 31874032 16410672
17:51:16 2977 8192000000 10943614528 11362144256 1972262336 19.403 100 57 114 2 2023422384 32520480 18639568
17:52:25 1531 8192000000 10955642496 11367612416 1974772832 19.423 100 72 144 0 2034733200 38043488 21916880
17:53:31 5869 8192000000 10975388464 11394932736 1995599440 19.588 100 76 149 1 2062909872 43378848 23931584
17:54:41 1475 8192000000 10961324848 11373871104 1973419488 19.413 100 75 150 3 2041543232 44433376 23690368
17:55:52 4301 8192000000 10963662208 11375792128 1968603600 19.374 100 71 143 1 2039622336 47740976 23277760
17:57:01 6783 8192000000 10947014720 11365175296 1958694080 19.296 100 72 143 1 2036678560 54147568 23836912
17:58:11 10354 8192000000 10940621664 11366248448 1945256640 19.189 99 60 119 0 2014456448 49154304 20045504
17:59:24 8533 8192000000 10965026000 11402403840 1982503440 19.485 100 40 80 3 2043305056 47961904 12839712
18:00:37 11203 8192000000 10934176672 11364691968 1945014816 19.187 99 49 98 1 2011101280 49952832 16133632
18:02:03 9704 8192000000 10927384992 11370164224 1952336048 19.245 100 39 77 2 2007087840 41624864 13126928
18:03:19 11594 8192000000 10920239744 11359207424 1955246496 19.268 99 34 68 2 2000845776 34317904 11281376
18:04:35 25077 8192000000 10928656944 11387547648 1972906832 19.409 100 23 46 1 2017174352 36422096 7845424
18:06:09 28835 8192000000 10917554832 11381915648 1936837088 19.122 99 23 43 1 1993701200 48836256 8027856
18:07:53 85185 8192000000 10907122064 11359907840 1944230608 19.181 99 16 32 0 1973006704 24347568 4428528
18:10:26 71574 8192000000 10929076304 11375894528 1977135056 19.442 100 24 48 2 1993178880 9360096 6683728
18:12:40 171552 8192000000 10894637040 11365502976 1942295840 19.165 98 30 60 0 1976845072 25931680 8617552
18:16:33 93770 8192000000 10925214768 11386089472 1986171840 19.514 100 20 40 2 2003353472 10746576 6435056
18:19:09 31529 8192000000 10923264096 11394953216 1976462160 19.437 99 16 32 0 2004539824 22792048 5285616
18:20:43 43318 8192000000 10943350896 11409047552 2007172656 19.679 100 32 64 0 2037608224 20593776 9841792
18:22:31 23915 8192000000 10944656352 11408199680 2010712112 19.707 100 28 57 1 2037462320 18321888 8428320
18:23:58 44735 8192000000 10923297680 11399966720 1975817072 19.432 99 14 28 0 2000144896 19862400 4465424
18:25:45 40409 8192000000 10932549888 11409530880 1999852048 19.622 100 7 14 2 2022836192 20372128 2612016
18:27:53 134013 8192000000 10920434208 11407958016 1947145328 19.204 97 19 39 1 1971388464 18634736 5608400
18:31:11 25725 8192000000 10927570688 11394060288 1999991888 19.623 100 35 69 2 2021935312 12266064 9677360
18:32:41 55898 8192000000 10910935616 11389263872 1957731632 19.288 98 12 24 0 1978544912 17190256 3623024
18:34:42 94507 8192000000 10907195392 11352465408 1866605232 18.557 94 31 61 2 1884624928 9109904 8909792
18:37:17 68227 8192000000 10885484368 11343282176 1843171568 18.367 93 25 50 2 1859839744 10399568 6268608
18:40:04 123201 8192000000 10838785008 11296411648 1863867616 18.535 96 41 80 3 1885156064 9889152 11399296
18:43:40 36766 8192000000 10855005808 11332976640 1913474736 18.935 98 7 14 1 1922793072 7188256 2130080
18:45:22 63381 8192000000 10852658688 11335622656 1888497840 18.734 97 14 28 0 1900754192 8387488 3868864
18:47:26 79241 8192000000 10826855872 11299217408 1837491584 18.320 95 13 26 2 1852544256 11766720 3285952
18:50:12 65589 8192000000 10837780272 11302461440 1884004496 18.697 98 11 22 1 1898486528 10964800 3517232
18:52:21 104353 8192000000 10872218576 11345129472 1892913488 18.769 97 14 28 0 1906825488 9732608 4179392
18:55:11 62759 8192000000 10850630208 11328290816 1858955584 18.495 96 12 24 2 1876606896 14176224 3475088
18:57:23 118578 8192000000 10866145408 11327954944 1861736304 18.517 96 15 30 1 1880293056 14525200 4031552
19:00:25 56987 8192000000 10868774448 11331690496 1876875072 18.640 96 24 48 3 1895390064 12034672 6480320
19:02:24 19732 8192000000 10877318432 11337420800 1930338784 19.070 99 51 103 0 1965730848 21105056 14287008
19:03:45 100563 8192000000 10859959296 11331862528 1927038256 19.043 99 24 48 1 1943118240 9783744 6296240
19:06:32 23589 8192000000 10892702496 11361177600 1963200944 19.331 100 26 53 1 1983188160 12632976 7354240
19:07:57 58648 8192000000 10905151168 11388157952 1979907680 19.464 100 13 26 0 2000311712 17076256 3327776
19:10:56 140164 8192000000 10904496624 11383975936 1927913904 19.050 97 13 26 0 1939281120 8420896 2946320
19:14:19 27505 8192000000 10919981632 11385524224 1967602976 19.366 99 22 43 0 1980653184 7440160 5610048
19:15:51 22969 8192000000 10895129872 11374436352 1946528768 19.199 98 21 42 0 1961211280 8359376 6323136
19:17:15 69258 8192000000 10884325776 11368693760 1906893984 18.882 96 19 38 2 1922674496 10415792 5364720
19:19:32 62653 8192000000 10891291696 11368181760 1932459264 19.087 98 18 36 1 1948174816 10673776 5041776
19:21:36 54383 8192000000 10906532512 11373072384 1934697520 19.104 98 27 54 1 1954915696 12670928 7547248
19:23:34 99781 8192000000 10855166224 11322929152 1849137088 18.415 94 22 44 1 1866161680 11260304 5764288
19:26:13 84320 8192000000 10861586080 11332358144 1845901584 18.389 94 24 48 1 1865332544 12273712 7157248
19:28:39 54782 8192000000 10866565872 11348107264 1921742016 19.001 99 14 28 1 1947682240 21938912 4001312
19:30:34 39299 8192000000 10842732976 11316166656 1860178912 18.505 96 7 14 1 1881295792 19015216 2101664
19:32:17 74381 8192000000 10856532304 11319533568 1866616432 18.557 97 17 34 0 1884662016 13081616 4963968
19:34:37 75652 8192000000 10852002848 11327991808 1886069184 18.714 98 21 42 0 1906308800 13993760 6245856
19:37:24 27839 8192000000 9238104704 9503948800 396605936 4.617 21 3 6 0 402819888 5495888 718064

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 11-08-2021 19:38:55 to 11-08-2021 19:39:10.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 115 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 11-08-2021 19:39:11 to 11-08-2021 19: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          250615
MON$OLDEST_ACTIVE               250616
MON$OLDEST_SNAPSHOT             250616
MON$NEXT_TRANSACTION            250616
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                       2036192
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.33488 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33488 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33488 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33488 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.0

Database statistics

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

Database validation

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

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

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

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

11.08.21 19: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-08-11 19:39:16 No severe PSQL-related problems occured
11.08.21 19:39:17. End of report.