Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 21.10.2022 07: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 15110460 kB
MemAvailable 30451184 kB
Buffers 0 kB
Cached 16573292 kB
SwapCached 1948 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 4.0.3
DB_NAME                         /home/bases/oltp40-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/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/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=3400
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/oltp40-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-oltp40
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-10-21 07:37:20
DTS_END                         2022-10-21 07:37:20.5900
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(68:17)
IP                              ::1/44368
TRN_ID                          229691
ATT_ID                          601
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: 9332.98 19098 1119957
customer order: creation 183.03 430 21963
customer order: refuse 14.67 388 1760
order to supplier: creation 48.43 1278 5812
order to supplier: removal 3.59 142867 431
invoice (draft): creation 48.85 2844 5862
invoice (draft): removal 51.53 939 6183
invoice accept: apply 43.68 24240 5242
invoice accept: cancel 46.32 1118 5558
customer reserve: creation 4057.38 232 486885
customer reserve: removal 4532.88 89 543946
realization accept: apply 88.06 270 10567
realization accept: cancel 77.53 159 9303
payment from customer: creation 62.58 166 7509
payment from customer: removal 6.29 119 755
payment to supplier: creation 50.68 177 6081
payment to supplier: removal 3.84 100 461
service: total inventory turnovers 4.40 3968 528
service: total monetary turnovers 8.14 298 977
service: aggregate perf. data 1.08 14312 129
service: refresh index statistics 0.04 187958 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 21-10-2022 07:38:29 to 21-10-2022 07:38:31.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6819
TEST_TIME 2 5908
TEST_TIME 3 5025
TEST_TIME 4 4864
TEST_TIME 5 4899
TEST_TIME 6 5061
TEST_TIME 7 5021
TEST_TIME 8 5637
TEST_TIME 9 5961
TEST_TIME 10 6095
TEST_TIME 11 6339
TEST_TIME 12 6960
TEST_TIME 13 7340
TEST_TIME 14 7586
TEST_TIME 15 7799
TEST_TIME 16 7578
TEST_TIME 17 7533
TEST_TIME 18 7694
TEST_TIME 19 8062
TEST_TIME 20 8518
TEST_TIME 21 8698
TEST_TIME 22 8977
TEST_TIME 23 9071
TEST_TIME 24 9144
TEST_TIME 25 9141
TEST_TIME 26 9088
TEST_TIME 27 9321
TEST_TIME 28 9500
TEST_TIME 29 9474
TEST_TIME 30 9660
TEST_TIME 31 9817
TEST_TIME 32 10448
TEST_TIME 33 10889
TEST_TIME 34 11282
TEST_TIME 35 11160
TEST_TIME 36 10966
TEST_TIME 37 10837
TEST_TIME 38 11022
TEST_TIME 39 10868
TEST_TIME 40 10821
TEST_TIME 41 10850
TEST_TIME 42 10870
TEST_TIME 43 10984
TEST_TIME 44 10948
TEST_TIME 45 10752
TEST_TIME 46 10597
TEST_TIME 47 10480
TEST_TIME 48 10392
TEST_TIME 49 10283
TEST_TIME 50 10251
TEST_TIME 51 10177
TEST_TIME 52 10107
TEST_TIME 53 10086
TEST_TIME 54 10139
TEST_TIME 55 10098
TEST_TIME 56 10058
TEST_TIME 57 10195
TEST_TIME 58 10083
TEST_TIME 59 9994
TEST_TIME 60 9970
TEST_TIME 61 9883
TEST_TIME 62 9898
TEST_TIME 63 9913
TEST_TIME 64 9883
TEST_TIME 65 9936
TEST_TIME 66 9869
TEST_TIME 67 9851
TEST_TIME 68 9898
TEST_TIME 69 9938
TEST_TIME 70 9963
TEST_TIME 71 9908
TEST_TIME 72 9998
TEST_TIME 73 10001
TEST_TIME 74 9998
TEST_TIME 75 9986
TEST_TIME 76 9920
TEST_TIME 77 9881
TEST_TIME 78 9819
TEST_TIME 79 9755
TEST_TIME 80 9725
TEST_TIME 81 9729
TEST_TIME 82 9675
TEST_TIME 83 9602
TEST_TIME 84 9611
TEST_TIME 85 9632
TEST_TIME 86 9649
TEST_TIME 87 9636
TEST_TIME 88 9599
TEST_TIME 89 9597
TEST_TIME 90 9682
TEST_TIME 91 9643
TEST_TIME 92 9656
TEST_TIME 93 9661
TEST_TIME 94 9752
TEST_TIME 95 9726
TEST_TIME 96 9741
TEST_TIME 97 9789
TEST_TIME 98 9694
TEST_TIME 99 9666
TEST_TIME 100 9659
TEST_TIME 101 9642
TEST_TIME 102 9597
TEST_TIME 103 9539
TEST_TIME 104 9504
TEST_TIME 105 9515
TEST_TIME 106 9474
TEST_TIME 107 9426
TEST_TIME 108 9383
TEST_TIME 109 9379
TEST_TIME 110 9322
TEST_TIME 111 9325
TEST_TIME 112 9319
TEST_TIME 113 9354
TEST_TIME 114 9341
TEST_TIME 115 9336
TEST_TIME 116 9329
TEST_TIME 117 9292
TEST_TIME 118 9312
TEST_TIME 119 9362
TEST_TIME 120 9356

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 21-10-2022 07:38:33 to 21-10-2022 07:38:36.

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 1568224 1568224 0 0.00 0 18709 14 0 0
doc_list_biud 1568218 1568218 0 0.00 0 34563 37 0 0
sp_add_invoice_to_stock 5328 5242 86 1.61 64 205735 24240 0 86
sp_cancel_adding_invoice 5558 5558 0 0.00 5 86236 1118 0 0
sp_cancel_client_order 1760 1760 0 0.00 6 17590 389 0 0
sp_cancel_customer_reserve 543946 543946 0 0.00 1 19963 89 0 0
sp_cancel_pay_from_customer 755 755 0 0.00 4 3664 119 0 0
sp_cancel_pay_to_supplier 461 461 0 0.00 3 1138 100 0 0
sp_cancel_supplier_invoice 6188 6183 5 0.08 3 34571 940 0 5
sp_cancel_supplier_order 431 431 0 0.00 11 1259193 142867 0 0
sp_cancel_write_off 9303 9303 0 0.00 1 9423 160 0 0
sp_client_order 21963 21963 0 0.00 5 61399 430 0 0
sp_customer_reserve 486885 486885 0 0.00 0 102901 232 0 0
sp_fill_shopping_cart 33626 33626 0 0.00 1 9544 74 0 0
sp_fill_shopping_cart_clo_res 486854 486854 0 0.00 0 16820 16 0 0
sp_get_clo_for_invoice 5203 5203 0 0.00 0 19974 261 0 0
sp_kill_cost_storno 16077 16077 0 0.00 0 9415 66 0 0
sp_kill_qstorno_ret_qs2qd 1103809 1103809 0 0.00 0 34561 39 0 0
sp_kill_qty_storno 582947 582947 0 0.00 0 34562 91 0 0
sp_lock_selected_doc 21869 21869 0 0.00 0 8843 6 0 0
sp_make_cost_storno 29359 29359 0 0.00 0 18691 71 0 0
sp_multiply_rows_for_pdistr 29357 29357 0 0.00 0 7795 36 0 0
sp_multiply_rows_for_qdistr 21962 21962 0 0.00 0 8554 156 0 0
sp_pay_from_customer 7509 7509 0 0.00 5 11597 167 0 0
sp_pay_to_supplier 6081 6081 0 0.00 7 10729 178 0 0
sp_payment_common 13590 13590 0 0.00 2 10251 46 0 0
sp_qd_handle_on_cancel_clo 1760 1760 0 0.00 0 17581 326 0 0
sp_qd_handle_on_invoice_upd_sts 10759 10759 0 0.00 0 14292 484 0 0
sp_qd_handle_on_reserve_upd_sts 10565 10565 0 0.00 0 18252 68 0 0
sp_reserve_write_off 10567 10567 0 0.00 8 18315 271 0 0
sp_supplier_invoice 5862 5862 0 0.00 10 81865 2844 0 0
sp_supplier_order 5812 5812 0 0.00 8 31490 1279 0 0
srv_aggregate_perf_data 129 129 0 0.00 2342 206082 14312 0 0
srv_fill_mon_cache_memory 71 71 0 0.00 836 157759 35593 0 0
srv_find_qd_qs_mism 1068193 1068193 0 0.00 0 18403 11 0 0
srv_make_invnt_saldo 528 528 0 0.00 36 76336 3968 0 0
srv_make_money_saldo 977 977 0 0.00 1 9921 298 0 0
srv_recalc_idx_stat 5 5 0 0.00 69635 358965 187958 0 0
t$perf-abend:sp_add_invoice_to_stock 86 86 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5248 5248 0 0.00 0 11008 836 0 0
t$perf-norm:sp_cancel_adding_invoice 432 432 0 0.00 1 22876 416 0 0
t$perf-norm:sp_cancel_client_order 1760 1760 0 0.00 0 4870 25 0 0
t$perf-norm:sp_cancel_customer_reserve 764 764 0 0.00 0 3726 30 0 0
t$perf-norm:sp_cancel_pay_from_customer 755 755 0 0.00 0 438 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 461 461 0 0.00 0 748 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 454 454 0 0.00 0 445 24 0 0
t$perf-norm:sp_cancel_supplier_order 431 431 0 0.00 0 91534 6548 0 0
t$perf-norm:sp_cancel_write_off 1051 1051 0 0.00 0 1897 19 0 0
t$perf-norm:sp_client_order 21964 21964 0 0.00 0 9077 35 0 0
t$perf-norm:sp_customer_reserve 1082 1082 0 0.00 0 4018 16 0 0
t$perf-norm:sp_pay_from_customer 7509 7509 0 0.00 0 8751 19 0 0
t$perf-norm:sp_pay_to_supplier 6081 6081 0 0.00 0 6851 20 0 0
t$perf-norm:sp_reserve_write_off 10568 10568 0 0.00 0 6465 22 0 0
t$perf-norm:sp_supplier_invoice 5862 5862 0 0.00 0 6373 30 0 0
t$perf-norm:sp_supplier_order 5813 5813 0 0.00 0 3523 27 0 0
t$perf-norm:srv_aggregate_perf_data 129 129 0 0.00 0 1540 26 0 0
t$perf-norm:srv_fill_mon_cache_memory 71 71 0 0.00 0 483 17 0 0
t$perf-norm:srv_make_invnt_saldo 528 528 0 0.00 0 1032 15 0 0
t$perf-norm:srv_make_money_saldo 977 977 0 0.00 0 3219 7 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 5 184 91 0 0
v_add_invoice_to_stock 10564 10392 172 1.62 0 3845 24 0 172
v_all_customers 40038 40038 0 0.00 0 131 3 0 0
v_all_suppliers 32039 32039 0 0.00 0 168 4 0 0
v_all_wares 43921 43921 0 0.00 0 71 4 0 0
v_cancel_adding_invoice 864 864 0 0.00 0 297 12 0 0
v_cancel_client_order 3520 3520 0 0.00 0 1997 9 0 0
v_cancel_customer_prepayment 1510 1510 0 0.00 0 3012 18 0 0
v_cancel_customer_reserve 1528 1528 0 0.00 0 1484 11 0 0
v_cancel_payment_to_supplier 922 922 0 0.00 0 633 10 0 0
v_cancel_supplier_invoice 918 908 10 1.08 0 2405 27 0 10
v_cancel_supplier_order 862 862 0 0.00 0 1464 12 0 0
v_cancel_write_off 2102 2102 0 0.00 0 3399 27 0 0
v_max_id_clo_ord 5809 5809 0 0.00 0 5981 6 0 0
v_max_id_clo_res 1081 1081 0 0.00 0 7147 30 0 0
v_max_id_ord_sup 5856 5856 0 0.00 0 4479 7 0 0
v_max_non_paid_invoice 1725 1725 0 0.00 0 10236 13 0 0
v_max_non_paid_realizn 3987 3987 0 0.00 0 540 7 0 0
v_min_id_clo_ord 5809 5809 0 0.00 0 4604 8 0 0
v_min_id_clo_res 1081 1081 0 0.00 0 2608 12 0 0
v_min_id_ord_sup 5856 5856 0 0.00 0 8144 10 0 0
v_min_non_paid_invoice 6081 6081 0 0.00 0 6987 19 0 0
v_min_non_paid_realizn 7509 7509 0 0.00 0 5850 17 0 0
v_our_firm 10926 10926 0 0.00 0 59 4 0 0
v_reserve_write_off 21130 21130 0 0.00 0 6319 11 0 0
x_lock_depdocs_on_canc_invoice 11287 11287 0 0.00 0 10153 118 0 0
x_lock_depdocs_on_canc_sup_ord 431 431 0 0.00 0 2980 302 0 0
x_make_qstorno_1000_1200 5812 5812 0 0.00 2 31455 1124 0 0
x_make_qstorno_1200_2000 5862 5862 0 0.00 5 81793 2651 0 0
x_make_qstorno_2100_3300 486884 486884 0 0.00 0 102892 205 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 21-10-2022 07:38:37 to 21-10-2022 07:38:42.

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
05:07:24 4 8192000000 8799449200 8810975232 5370048 0.065 2 2 2 0 5471808 65984 35776
05:08:25 23638 8192000000 9714223184 10012844032 795335024 8.849 100 88 177 0 824348208 7590064 21423120
05:09:56 14988 8192000000 9754656512 10064584704 834492384 9.244 100 84 168 0 865731536 11176464 20062688
05:11:21 16322 8192000000 9800473856 10122133504 873284688 9.633 100 80 158 0 908432960 17195120 17953152
05:12:46 13142 8192000000 9853258256 10188947456 923899184 10.135 100 84 168 0 970490000 28307776 18283040
05:14:06 4609 8192000000 9912266480 10253402112 956038048 10.450 100 91 173 1 1019604928 45830384 17736496
05:15:13 1209 8192000000 9971611232 10322964480 990663936 10.788 100 90 178 0 1095015664 86148000 18203728
05:16:18 1688 8192000000 10005629600 10362576896 1011327296 10.988 100 87 173 1 1121066000 92336928 17401776
05:17:22 1040 8192000000 10015881072 10380218368 1037802832 11.244 100 92 181 0 1137636576 82137360 17696384
05:18:25 1108 8192000000 10024315488 10402357248 1055504512 11.413 100 97 185 3 1143940448 69692864 18743072
05:19:27 981 8192000000 10053549568 10432176128 1066442832 11.518 100 97 189 0 1174460384 89031632 18985920
05:20:29 1253 8192000000 10040210752 10415964160 1075553232 11.605 100 95 183 1 1167875888 73087136 19235520
05:21:31 16481 8192000000 10022660960 10409414656 1078449760 11.633 100 57 113 0 1148696288 59139296 11107232
05:22:48 1055 8192000000 10060818032 10449059840 1084567232 11.691 100 92 179 1 1181035472 79159040 17309200
05:23:50 1315 8192000000 10045629200 10435334144 1087456592 11.718 100 87 172 1 1168865456 63712928 17695936
05:24:52 1223 8192000000 10077397104 10465185792 1098005200 11.819 100 95 184 2 1201890944 85618048 18267696
05:25:54 1507 8192000000 10061131520 10449027072 1099686112 11.835 100 92 182 0 1184513664 65838208 18989344
05:26:57 1163 8192000000 10086459632 10478645248 1098834208 11.827 100 96 192 0 1209951696 92288096 18829392
05:27:59 840 8192000000 10060117632 10450259968 1103114768 11.867 100 95 177 3 1181058080 59856672 18086640
05:29:00 1301 8192000000 10069324976 10458013696 1088932240 11.733 100 89 172 2 1192696432 85238592 18525600
05:30:03 1095 8192000000 10063333200 10448769024 1079674736 11.644 100 98 183 2 1184966016 85465984 19825296
05:31:05 1190 8192000000 10059709136 10444308480 1084323920 11.689 100 96 180 3 1181282912 76391008 20567984
05:32:07 1449 8192000000 10015131920 10393018368 1062952240 11.485 100 91 163 2 1129453072 51251072 15249760
05:33:10 3848 8192000000 9980453776 10356178944 1050713504 11.368 99 73 140 1 1094297792 29851488 13732800
05:34:16 4398 8192000000 9976858000 10346733568 1030652960 11.175 99 80 149 1 1099993408 55848896 13491552
05:35:21 3175 8192000000 9980585184 10363695104 1053549008 11.395 100 65 121 3 1105628384 40793360 11286016
05:36:24 1801 8192000000 9955998496 10338189312 1016761056 11.041 98 55 108 2 1076198400 46705280 12732064
05:37:28 17672 8192000000 9978259152 10355568640 1044171280 11.305 100 65 114 1 1096747504 42134352 10441872
05:39:03 6657 8192000000 10009229152 10387816448 1056408896 11.422 99 66 121 0 1124906160 56158112 12339152
05:40:17 5443 8192000000 10043110192 10432028672 1082685712 11.673 100 67 131 1 1165013024 68668528 13658784
05:41:28 6934 8192000000 10107390000 10501746688 1101797808 11.855 100 67 126 3 1216852288 101648432 13406048
05:42:54 10501 8192000000 10140293712 10533724160 1116822624 11.997 100 65 131 1 1259118080 127963968 14331488
05:44:09 3851 8192000000 10205262992 10597433344 1128874912 12.111 100 87 171 1 1317588032 169520096 19193024
05:45:26 2434 8192000000 10229372480 10632335360 1137645088 12.193 100 82 161 0 1331832224 176362448 17824688
05:46:32 1331 8192000000 10243893312 10641141760 1148949920 12.300 100 92 170 2 1355245664 188845696 17450048
05:47:37 5445 8192000000 10269712224 10679062528 1152847488 12.336 100 68 136 1 1373961616 205952112 15162016
05:48:48 916 8192000000 10289402016 10710528000 1159736672 12.401 100 95 184 0 1404893392 222602320 22554400
05:49:52 1117 8192000000 10289299472 10710335488 1165115792 12.451 100 89 167 1 1400790880 216089600 19585488
05:50:57 1112 8192000000 10295940576 10696187904 1166582496 12.465 100 96 186 0 1407905776 218979040 22344240
05:52:01 7950 8192000000 10269798848 10712948736 1177151600 12.564 100 44 82 0 1390424688 203527312 9745776
05:53:13 7396 8192000000 10315194992 10740641792 1181249168 12.602 100 52 101 0 1424365168 230789696 12326304
05:54:25 1028 8192000000 10382603936 10799116288 1187527200 12.660 100 86 171 1 1494635072 287474064 19633808
05:55:33 1054 8192000000 10383265984 10829840384 1188270048 12.667 100 91 179 0 1492193296 283694512 20228736
05:56:36 825 8192000000 10385708192 10873434112 1188276304 12.667 100 97 188 0 1494588592 284089408 22222880
05:57:41 858 8192000000 10380654928 10793492480 1198239200 12.760 100 98 188 1 1489580880 268079840 23261840
05:58:44 1531 8192000000 10356956016 10810777600 1175395056 12.547 100 82 159 0 1456326704 261987776 18943872
05:59:49 945 8192000000 10393142992 10813890560 1193841616 12.719 100 91 182 0 1495660960 280712240 21107104
06:00:51 2296 8192000000 10386537904 10808651776 1187564768 12.661 100 67 126 3 1491602688 288918640 15119280
06:01:55 2381 8192000000 10394244672 10826493952 1178453648 12.576 100 57 114 0 1501456416 308920496 14082272
06:03:17 5311 8192000000 10444989520 10885931008 1177036768 12.563 100 49 98 0 1551975008 362243952 12694288
06:04:38 7393 8192000000 10385358848 10885468160 1176844432 12.561 100 43 86 0 1500620784 312552816 11223536
06:05:57 11446 8192000000 10434786048 10876182528 1180751888 12.597 100 51 101 1 1534381664 341738976 11890800
06:07:10 19845 8192000000 10441827120 10934644736 1165461584 12.454 100 35 69 0 1556212704 381556832 9194288
06:08:40 9036 8192000000 10380271920 10874273792 1152542912 12.333 100 40 74 0 1481235424 318542336 10150176
06:10:05 35365 8192000000 10210206144 10696515584 1157538992 12.380 100 23 46 1 1307689856 145392592 4758272
06:11:41 66720 8192000000 10064119488 10532876288 1068816512 11.541 93 13 24 1 1103488144 32316656 2354976
06:13:56 63105 8192000000 10123490208 10559520768 1131854304 12.139 98 33 62 0 1222937856 85197392 5886160
06:16:03 76473 8192000000 10120735136 10550239232 1117262384 12.001 97 35 62 3 1198277088 73472800 7541904
06:18:21 104913 8192000000 10120999616 10589073408 1116188624 11.991 98 13 26 0 1222776096 103943520 2643952
06:21:09 157758 8192000000 10051318576 10499584000 1088169936 11.725 96 18 36 0 1130243648 38004048 4069664
06:24:55 7166 8192000000 10096925712 10520875008 1136831376 12.186 100 82 155 0 1183347968 30303936 16212656
06:26:05 57729 8192000000 10070235312 10498830336 1094437728 11.785 97 47 86 1 1149994656 46840800 8716128
06:28:04 72006 8192000000 10091829200 10554376192 1073247680 11.583 95 26 52 0 1177354272 97919200 6187392
06:30:21 24620 8192000000 10152363824 10587512832 1138157680 12.198 100 57 101 1 1262001216 112403616 11439920
06:31:47 62222 8192000000 10112442368 10593820672 1116602064 11.995 98 13 26 0 1230092640 110298048 3192528
06:33:52 42809 8192000000 10118387952 10611277824 1126552656 12.089 100 16 31 0 1238345104 108253792 3538656
06:35:37 54023 8192000000 10047428704 10504679424 1106066032 11.895 98 16 32 0 1144411248 35145264 3199952
06:37:40 37986 8192000000 10069408864 10537099264 1127375712 12.097 100 16 26 1 1167194448 37735712 2083024
06:40:26 27500 8192000000 10056862128 10505236480 1113811952 11.968 98 16 32 0 1142021984 25181408 3028624
06:41:54 41212 8192000000 10083026512 10559414272 1100512880 11.843 97 17 34 0 1159761488 55751312 3497296
06:43:37 9821 8192000000 10068446416 10489720832 1120229424 12.029 100 72 136 1 1164015632 29765632 14020576
06:44:47 20779 8192000000 10126282768 10560892928 1118415120 12.012 99 42 84 1 1207590960 80503472 8672368
06:46:10 70609 8192000000 10065720368 10558476288 1115822384 11.988 99 11 22 0 1160635008 42961040 1851584
06:48:23 10571 8192000000 10115633744 10543632384 1130999504 12.131 99 47 89 2 1191181856 50880624 9301728
06:49:37 68653 8192000000 10134552752 10619117568 1133624560 12.156 100 17 35 0 1201743296 64601184 3517552
06:51:46 23216 8192000000 10087289088 10516205568 1105112448 11.886 97 37 73 1 1150352560 38062368 7177744
06:53:12 85761 8192000000 10064108128 10528436224 1133063840 12.150 100 31 63 0 1182555136 42469168 7022128
06:56:05 39021 8192000000 10096678288 10533617664 1125324560 12.077 100 40 78 0 1196570720 63194848 8051312
06:57:44 85515 8192000000 10103552720 10590773248 1115261168 11.982 98 15 30 0 1193368368 74942864 3164336
07:00:11 45363 8192000000 10116922000 10575765504 1099718608 11.835 97 22 43 0 1199745248 94639232 5387408
07:02:18 57073 8192000000 10066763856 10532503552 1105312544 11.888 98 26 51 2 1160607808 49947136 5348128
07:04:21 48835 8192000000 10094453568 10555527168 1128374544 12.106 99 23 46 0 1196293264 63186880 4731840
07:06:24 81214 8192000000 10043945136 10535260160 1097300688 11.812 98 15 30 0 1165197216 64749312 3147216
07:08:50 40201 8192000000 10101601936 10556063744 1111713776 11.949 98 43 86 0 1200691376 80078384 8899216
07:10:35 50451 8192000000 10123243616 10557075456 1052786256 11.387 93 32 64 0 1151583456 92719472 6077728
07:12:29 23513 8192000000 10121267104 10557829120 1123518288 12.060 100 54 107 0 1198025744 63774000 10733456
07:13:57 123307 8192000000 10134029696 10612543488 1126518144 12.089 99 12 24 0 1212296336 82925504 2852688
07:17:01 14990 8192000000 10074228896 10544316416 1130224016 12.123 99 24 48 1 1186386688 51236784 4925888
07:18:29 69696 8192000000 10077406800 10540523520 1128926464 12.111 99 23 41 0 1173750448 40435280 4388704
07:20:46 57527 8192000000 10099956656 10558914560 1136372992 12.181 99 24 48 0 1189489888 47424576 5692320
07:22:51 55647 8192000000 10069224896 10560393216 1132972224 12.149 99 19 38 0 1172958848 36091808 3894816
07:24:47 50875 8192000000 10061009232 10519662592 1103658848 11.872 97 13 26 0 1128562432 22299040 2604544
07:26:38 58197 8192000000 10080335344 10541391872 1126395408 12.087 99 8 14 0 1166142016 38143760 1602848
07:28:38 24648 8192000000 10076113216 10519396352 1117897568 12.007 98 29 50 1 1180604816 57759680 4947568
07:30:04 38677 8192000000 10104786544 10560143360 1128235712 12.105 99 31 62 0 1193182096 58198848 6747536
07:31:51 15709 8192000000 10101905168 10556657664 1116568896 11.995 98 49 97 1 1189700656 63143056 9988704
07:33:08 81911 8192000000 10139610704 10576138240 1093707072 11.778 95 31 62 0 1176271040 76068880 6495088
07:35:31 100805 8192000000 10162310272 10631323648 1131351248 12.134 99 24 48 0 1314243952 177242208 5650496

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 21-10-2022 07:38:44 to 21-10-2022 07:39:00.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 86 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 21-10-2022 07:39:01 to 21-10-2022 07:39:03.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          225700
MON$OLDEST_ACTIVE               229816
MON$OLDEST_SNAPSHOT             229816
MON$NEXT_TRANSACTION            229816
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-30 07:30:08.6030 GMT
MON$PAGES                       2067488
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             788
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2861 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2861 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2861 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2861 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.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 ---
:62: 
:63: mscs-test-161.budzdorov.ru    Fri Oct 21 07:37:41 2022
:64:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:65: 
--- end of diff output ---

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

21.10.22 07:39:04. There are 3 logs in /var/tmp/logs-oltp40 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-10-21 07:39:07 No severe PSQL-related problems occured
21.10.22 07:39:08. End of report.