Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 17.04.2022 04: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 16209560 kB
MemAvailable 31432472 kB
Buffers 20 kB
Cached 15405640 kB
SwapCached 2688 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.2
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-04-17 04:37:16
DTS_END                         2022-04-17 04:37:16.9550
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_RESERVE_WRITE_OFF(34:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/49818
TRN_ID                          217780
ATT_ID                          621
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: 8765.49 24031 1051859
customer order: creation 166.78 468 20014
customer order: refuse 13.91 439 1669
order to supplier: creation 43.31 1364 5197
order to supplier: removal 3.38 161039 405
invoice (draft): creation 43.71 3328 5245
invoice (draft): removal 49.10 1005 5892
invoice accept: apply 40.21 26688 4825
invoice accept: cancel 44.21 1268 5305
customer reserve: creation 3766.10 251 451932
customer reserve: removal 4306.88 99 516826
realization accept: apply 79.78 271 9574
realization accept: cancel 82.35 172 9882
payment from customer: creation 57.12 185 6854
payment from customer: removal 5.73 135 687
payment to supplier: creation 47.05 211 5646
payment to supplier: removal 3.48 151 418
service: total inventory turnovers 3.94 3939 473
service: total monetary turnovers 7.53 358 904
service: aggregate perf. data 0.89 16468 107
service: refresh index statistics 0.03 262778 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 17-04-2022 04:38:25 to 17-04-2022 04:38:27.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8619
TEST_TIME 2 7205
TEST_TIME 3 6714
TEST_TIME 4 6951
TEST_TIME 5 7096
TEST_TIME 6 6653
TEST_TIME 7 6581
TEST_TIME 8 6558
TEST_TIME 9 6570
TEST_TIME 10 7024
TEST_TIME 11 7027
TEST_TIME 12 7107
TEST_TIME 13 7440
TEST_TIME 14 7557
TEST_TIME 15 7663
TEST_TIME 16 7665
TEST_TIME 17 7848
TEST_TIME 18 8003
TEST_TIME 19 8391
TEST_TIME 20 8290
TEST_TIME 21 8363
TEST_TIME 22 8369
TEST_TIME 23 8378
TEST_TIME 24 8402
TEST_TIME 25 8745
TEST_TIME 26 9027
TEST_TIME 27 9111
TEST_TIME 28 9277
TEST_TIME 29 9697
TEST_TIME 30 9652
TEST_TIME 31 10221
TEST_TIME 32 10252
TEST_TIME 33 11008
TEST_TIME 34 11405
TEST_TIME 35 11284
TEST_TIME 36 11030
TEST_TIME 37 11059
TEST_TIME 38 10895
TEST_TIME 39 10642
TEST_TIME 40 10648
TEST_TIME 41 10542
TEST_TIME 42 10495
TEST_TIME 43 10369
TEST_TIME 44 10246
TEST_TIME 45 10144
TEST_TIME 46 10005
TEST_TIME 47 9936
TEST_TIME 48 9928
TEST_TIME 49 9919
TEST_TIME 50 9803
TEST_TIME 51 9636
TEST_TIME 52 9565
TEST_TIME 53 9644
TEST_TIME 54 9745
TEST_TIME 55 9683
TEST_TIME 56 9690
TEST_TIME 57 9666
TEST_TIME 58 9630
TEST_TIME 59 9659
TEST_TIME 60 9808
TEST_TIME 61 9743
TEST_TIME 62 9770
TEST_TIME 63 9867
TEST_TIME 64 9936
TEST_TIME 65 9903
TEST_TIME 66 9771
TEST_TIME 67 9702
TEST_TIME 68 9708
TEST_TIME 69 9581
TEST_TIME 70 9481
TEST_TIME 71 9437
TEST_TIME 72 9385
TEST_TIME 73 9436
TEST_TIME 74 9365
TEST_TIME 75 9403
TEST_TIME 76 9351
TEST_TIME 77 9336
TEST_TIME 78 9306
TEST_TIME 79 9238
TEST_TIME 80 9251
TEST_TIME 81 9355
TEST_TIME 82 9299
TEST_TIME 83 9277
TEST_TIME 84 9368
TEST_TIME 85 9380
TEST_TIME 86 9310
TEST_TIME 87 9268
TEST_TIME 88 9233
TEST_TIME 89 9168
TEST_TIME 90 9195
TEST_TIME 91 9159
TEST_TIME 92 9106
TEST_TIME 93 9058
TEST_TIME 94 9005
TEST_TIME 95 9033
TEST_TIME 96 9032
TEST_TIME 97 9000
TEST_TIME 98 9003
TEST_TIME 99 9005
TEST_TIME 100 8966
TEST_TIME 101 9015
TEST_TIME 102 9021
TEST_TIME 103 9029
TEST_TIME 104 9024
TEST_TIME 105 9079
TEST_TIME 106 9076
TEST_TIME 107 9046
TEST_TIME 108 9055
TEST_TIME 109 9018
TEST_TIME 110 9004
TEST_TIME 111 8983
TEST_TIME 112 8954
TEST_TIME 113 8903
TEST_TIME 114 8896
TEST_TIME 115 8877
TEST_TIME 116 8864
TEST_TIME 117 8865
TEST_TIME 118 8841
TEST_TIME 119 8835
TEST_TIME 120 8791

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 17-04-2022 04:38:28 to 17-04-2022 04:38:32.

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 1467971 1467971 0 0.00 0 61473 14 0 0
doc_list_biud 1467962 1467962 0 0.00 0 63181 41 0 0
sp_add_invoice_to_stock 4956 4825 131 2.64 32 241142 26688 0 131
sp_cancel_adding_invoice 5305 5305 0 0.00 6 89449 1268 0 0
sp_cancel_client_order 1669 1669 0 0.00 7 16056 440 0 0
sp_cancel_customer_reserve 516826 516826 0 0.00 1 63194 100 0 0
sp_cancel_pay_from_customer 687 687 0 0.00 4 5848 135 0 0
sp_cancel_pay_to_supplier 418 418 0 0.00 4 6276 151 0 0
sp_cancel_supplier_invoice 5902 5892 10 0.16 2 17169 1006 0 10
sp_cancel_supplier_order 405 405 0 0.00 12 1403422 161040 0 0
sp_cancel_write_off 9882 9882 0 0.00 2 15753 172 0 0
sp_client_order 20014 20014 0 0.00 6 32732 468 0 0
sp_customer_reserve 451932 451932 0 0.00 1 119920 252 0 0
sp_fill_shopping_cart 30445 30445 0 0.00 2 27725 89 0 0
sp_fill_shopping_cart_clo_res 451899 451899 0 0.00 0 13767 19 0 0
sp_get_clo_for_invoice 4781 4781 0 0.00 0 33431 303 0 0
sp_kill_cost_storno 16292 16292 0 0.00 0 61454 76 0 0
sp_kill_qstorno_ret_qs2qd 1049831 1049831 0 0.00 0 61753 43 0 0
sp_kill_qty_storno 554333 554333 0 0.00 0 63180 99 0 0
sp_lock_selected_doc 19939 19939 0 0.00 0 6293 6 0 0
sp_make_cost_storno 26855 26855 0 0.00 0 17027 79 0 0
sp_multiply_rows_for_pdistr 26853 26853 0 0.00 0 7418 35 0 0
sp_multiply_rows_for_qdistr 20014 20014 0 0.00 0 16225 179 0 0
sp_pay_from_customer 6854 6854 0 0.00 6 14083 186 0 0
sp_pay_to_supplier 5646 5646 0 0.00 7 17343 212 0 0
sp_payment_common 12499 12499 0 0.00 2 8117 43 0 0
sp_qd_handle_on_cancel_clo 1669 1669 0 0.00 1 13730 341 0 0
sp_qd_handle_on_invoice_upd_sts 10085 10085 0 0.00 0 47401 517 0 0
sp_qd_handle_on_reserve_upd_sts 9574 9574 0 0.00 0 45250 59 0 0
sp_reserve_write_off 9574 9574 0 0.00 8 45327 271 0 0
sp_supplier_invoice 5245 5245 0 0.00 11 132106 3329 0 0
sp_supplier_order 5197 5197 0 0.00 9 55939 1364 0 0
srv_aggregate_perf_data 107 107 0 0.00 2270 106964 16468 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 748 155937 37912 0 0
srv_find_qd_qs_mism 1004362 1004362 0 0.00 0 15330 10 0 0
srv_make_invnt_saldo 473 473 0 0.00 54 89076 3939 0 0
srv_make_money_saldo 904 904 0 0.00 1 15935 359 0 0
srv_recalc_idx_stat 4 4 0 0.00 72473 426526 262779 0 0
t$perf-abend:sp_add_invoice_to_stock 131 131 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4834 4834 0 0.00 1 12657 1023 0 0
t$perf-norm:sp_cancel_adding_invoice 426 426 0 0.00 1 6985 538 0 0
t$perf-norm:sp_cancel_client_order 1669 1669 0 0.00 0 2940 28 0 0
t$perf-norm:sp_cancel_customer_reserve 652 652 0 0.00 0 3804 38 0 0
t$perf-norm:sp_cancel_pay_from_customer 687 687 0 0.00 0 783 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 418 418 0 0.00 0 154 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 402 402 0 0.00 0 757 31 0 0
t$perf-norm:sp_cancel_supplier_order 405 405 0 0.00 0 114821 6412 0 0
t$perf-norm:sp_cancel_write_off 930 930 0 0.00 0 1666 17 0 0
t$perf-norm:sp_client_order 20014 20014 0 0.00 0 7274 35 0 0
t$perf-norm:sp_customer_reserve 1028 1028 0 0.00 0 372 13 0 0
t$perf-norm:sp_pay_from_customer 6854 6854 0 0.00 0 5356 19 0 0
t$perf-norm:sp_pay_to_supplier 5646 5646 0 0.00 0 7091 18 0 0
t$perf-norm:sp_reserve_write_off 9574 9574 0 0.00 0 6206 23 0 0
t$perf-norm:sp_supplier_invoice 5245 5245 0 0.00 0 6174 33 0 0
t$perf-norm:sp_supplier_order 5197 5197 0 0.00 0 2727 31 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 1131 33 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 1303 22 0 0
t$perf-norm:srv_make_invnt_saldo 473 473 0 0.00 0 344 12 0 0
t$perf-norm:srv_make_money_saldo 904 904 0 0.00 0 454 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 7 208 79 0 0
v_add_invoice_to_stock 9818 9556 262 2.66 0 17050 32 0 262
v_all_customers 36652 36652 0 0.00 0 128 4 0 0
v_all_suppliers 29264 29264 0 0.00 0 185 4 0 0
v_all_wares 40028 40028 0 0.00 0 184 4 0 0
v_cancel_adding_invoice 852 852 0 0.00 0 109 14 0 0
v_cancel_client_order 3338 3338 0 0.00 0 15953 22 0 0
v_cancel_customer_prepayment 1374 1374 0 0.00 0 5828 20 0 0
v_cancel_customer_reserve 1304 1304 0 0.00 0 3414 13 0 0
v_cancel_payment_to_supplier 836 836 0 0.00 0 4032 20 0 0
v_cancel_supplier_invoice 824 804 20 2.42 0 1620 26 0 20
v_cancel_supplier_order 810 810 0 0.00 0 5644 22 0 0
v_cancel_write_off 1860 1860 0 0.00 0 15445 45 0 0
v_max_id_clo_ord 5196 5196 0 0.00 0 960 5 0 0
v_max_id_clo_res 1028 1028 0 0.00 0 547 8 0 0
v_max_id_ord_sup 5234 5234 0 0.00 0 8288 8 0 0
v_max_non_paid_invoice 1444 1444 0 0.00 0 320 8 0 0
v_max_non_paid_realizn 3589 3589 0 0.00 0 255 7 0 0
v_min_id_clo_ord 5196 5196 0 0.00 0 1799 8 0 0
v_min_id_clo_res 1028 1028 0 0.00 0 974 9 0 0
v_min_id_ord_sup 5234 5234 0 0.00 0 6347 12 0 0
v_min_non_paid_invoice 5646 5646 0 0.00 0 6895 12 0 0
v_min_non_paid_realizn 6853 6853 0 0.00 0 8113 18 0 0
v_our_firm 9904 9904 0 0.00 0 63 5 0 0
v_reserve_write_off 19144 19144 0 0.00 0 16810 15 0 0
x_lock_depdocs_on_canc_invoice 10795 10795 0 0.00 0 10360 117 0 0
x_lock_depdocs_on_canc_sup_ord 405 405 0 0.00 0 6142 354 0 0
x_make_qstorno_1000_1200 5197 5197 0 0.00 2 55925 1186 0 0
x_make_qstorno_1200_2000 5245 5245 0 0.00 4 131238 3081 0 0
x_make_qstorno_2100_3300 451931 451931 0 0.00 0 119918 220 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 17-04-2022 04:38:33 to 17-04-2022 04:38:37.

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
02:07:20 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:21 34903 8192000000 9716265456 10015027200 790613408 8.801 100 92 184 0 821732320 8029200 23089712
02:10:04 22796 8192000000 9754050352 10064703488 828227648 9.181 100 84 168 0 862565584 13321248 21016688
02:11:33 11378 8192000000 9806591664 10129039360 873594128 9.636 100 80 161 0 919190032 27136976 18458928
02:12:47 13094 8192000000 9872660912 10209509376 929650336 10.191 100 83 164 0 983778576 36684272 17443968
02:14:09 4162 8192000000 9941636176 10290077696 977810784 10.663 100 86 171 1 1058370016 62642144 17917088
02:15:15 3384 8192000000 9981300448 10336915456 1006413040 10.941 100 85 157 3 1092087632 70079888 15594704
02:16:21 1590 8192000000 10005971008 10376114176 1032449072 11.192 100 91 173 2 1129672016 79554912 17668032
02:17:24 1606 8192000000 10027402640 10397814784 1049491296 11.356 100 93 181 0 1145310944 77691216 18128432
02:18:29 978 8192000000 10035404336 10408677376 1056183328 11.420 100 95 187 1 1154714560 79865520 18665712
02:19:31 1207 8192000000 10037198672 10413342720 1062032288 11.476 100 96 178 1 1155044752 74412368 18600096
02:20:33 1129 8192000000 10032212832 10410414080 1067981552 11.533 100 95 178 2 1154545296 67991776 18571968
02:21:36 4859 8192000000 10041021200 10423169024 1077790512 11.626 100 81 146 1 1174690144 83610400 13289232
02:22:42 1192 8192000000 10054060224 10441424896 1081058048 11.658 100 95 180 2 1172489840 73227568 18204224
02:23:45 1709 8192000000 10047732688 10448457728 1084831536 11.693 100 80 157 2 1174159504 73609984 15717984
02:24:48 1162 8192000000 10031675728 10420584448 1085908688 11.704 100 77 151 0 1153213088 51495392 15809008
02:25:51 1884 8192000000 10059323008 10452434944 1088559600 11.729 100 85 166 2 1179737840 74039888 17138352
02:26:54 2811 8192000000 10030303504 10415673344 1087750496 11.721 100 76 148 3 1156270224 54676064 13843664
02:27:58 1366 8192000000 10047236000 10433564672 1092606096 11.767 100 93 184 2 1165187216 53825264 18755856
02:29:01 1160 8192000000 10051187744 10434568192 1072636704 11.577 99 91 175 0 1172097584 81427472 18033408
02:30:03 1309 8192000000 10035342192 10420441088 1077943760 11.628 100 87 168 1 1154802720 59990720 16868240
02:31:05 1315 8192000000 10024628576 10411237376 1068518016 11.538 99 86 163 1 1148175744 62079152 17578576
02:32:07 1075 8192000000 9993699888 10363129856 1030003856 11.168 100 98 186 2 1114108880 64433552 19671472
02:33:09 2031 8192000000 10010070816 10381008896 1035580208 11.222 100 82 162 3 1125352432 73153040 16619184
02:34:12 1254 8192000000 10016410080 10384863232 1034716768 11.214 100 96 178 1 1134781632 82474912 17589952
02:35:14 3463 8192000000 9994021760 10365968384 1020803744 11.080 99 71 136 0 1112891216 78227712 13859760
02:36:19 3157 8192000000 9967635200 10338357248 1030107856 11.169 100 80 140 2 1088191072 45798000 12285216
02:37:23 2667 8192000000 9983334400 10357112832 1039518592 11.260 99 60 111 1 1098999184 47548192 11932400
02:38:36 11939 8192000000 9989659824 10373054464 1055950752 11.418 99 57 112 0 1107518880 40224448 11343680
02:39:53 15004 8192000000 10063741152 10454507520 1083650208 11.682 100 67 134 0 1190011328 93713200 12647920
02:41:13 2957 8192000000 10108240032 10494713856 1103788208 11.874 100 83 159 0 1227750784 108315440 15647136
02:42:19 1037 8192000000 10136228048 10520641536 1118686128 12.015 100 93 181 0 1254308192 117606304 18015760
02:43:26 5618 8192000000 10157012064 10552115200 1128220192 12.105 100 64 127 0 1274399552 133046224 13133136
02:44:36 6110 8192000000 10206429488 10612715520 1139567568 12.211 100 67 131 1 1327120976 173876896 13676512
02:45:46 6139 8192000000 10247531104 10648846336 1151120624 12.320 100 70 137 0 1358341440 191546496 15674320
02:46:55 1421 8192000000 10263185600 10666696704 1159460608 12.398 100 94 179 0 1374810896 195919824 19430464
02:48:01 3836 8192000000 10267866032 10681995264 1162494464 12.427 100 76 143 0 1379612320 201586256 15531600
02:49:11 3422 8192000000 10326274112 10738089984 1173874000 12.533 100 79 154 0 1435656160 245032800 16749360
02:50:19 1103 8192000000 10354150144 10768179200 1169347312 12.491 100 96 182 0 1464889280 274170240 21371728
02:51:22 1334 8192000000 10332613040 10753060864 1161593616 12.418 100 85 170 0 1447014240 266115888 19304736
02:52:28 1704 8192000000 10348112128 10752729088 1157142080 12.376 99 91 176 1 1456403456 278990544 20270832
02:53:33 1219 8192000000 10397037808 10814935040 1171356192 12.510 100 89 175 1 1500500320 308021104 21123024
02:54:42 1040 8192000000 10394192512 10814652416 1182098416 12.610 100 97 190 1 1502127056 297122576 22906064
02:55:45 1322 8192000000 10412475104 10853146624 1186196048 12.648 100 92 179 0 1519356864 312848112 20312704
02:56:48 823 8192000000 10432736736 10853261312 1180273776 12.593 100 97 184 2 1539449648 336259184 22916688
02:57:52 968 8192000000 10477035440 10880458752 1176207744 12.555 100 96 186 1 1580312768 381408256 22696768
02:58:57 771 8192000000 10478925872 10891649024 1175292512 12.546 100 95 191 2 1586316592 386976352 24047728
03:00:00 1009 8192000000 10542072832 10961473536 1193238800 12.713 100 96 190 1 1645867584 430103552 22525232
03:01:03 739 8192000000 10523080976 10985394176 1179229232 12.583 100 98 192 0 1630333616 427246928 23857456
03:02:07 998 8192000000 10580909808 11055144960 1200628784 12.782 100 95 186 0 1683574208 459384176 23561248
03:03:09 2972 8192000000 10563003840 11032276992 1192768592 12.709 100 60 120 0 1666475296 458300320 15406384
03:04:14 11587 8192000000 10541471136 11012628480 1184134880 12.629 100 48 96 0 1666066384 469562048 12369456
03:05:33 23469 8192000000 10526962592 11043586048 1182494208 12.613 100 38 76 1 1642884720 450403728 9986784
03:07:31 16850 8192000000 10461442496 10957697024 1172557488 12.521 100 30 58 1 1576048720 395827568 7663664
03:09:02 25188 8192000000 10380539760 10853306368 1150491888 12.314 99 21 42 0 1488165248 332117280 5556080
03:10:28 87260 8192000000 10062388896 10552643584 1080255104 11.650 94 14 28 0 1192519632 109372240 2892288
03:12:57 75016 8192000000 10153461232 10594025472 1108015536 11.914 97 43 82 1 1220827200 104551424 8260240
03:15:17 96643 8192000000 10103575520 10542460928 1120441136 12.031 99 52 98 3 1200990544 70258352 10291056
03:18:05 78240 8192000000 10085795904 10575740928 1123725520 12.062 99 25 50 1 1183809600 55050448 5033632
03:20:51 95174 8192000000 10088606832 10539270144 1116620000 11.995 98 23 45 1 1160333968 39116080 4597888
03:23:28 41509 8192000000 10101117232 10544934912 1122015232 12.046 98 40 74 0 1177329920 46243424 9071264
03:25:17 65142 8192000000 10091471568 10602602496 1137576256 12.193 100 19 38 3 1204927488 63211504 4139728
03:27:28 56112 8192000000 10121865392 10580836352 1122062896 12.046 99 10 20 0 1204034256 79883520 2087840
03:31:02 71730 8192000000 10113243360 10595110912 1138723936 12.204 100 13 26 0 1254772592 112927152 3121504
03:33:22 53103 8192000000 10136186848 10583900160 1148072512 12.291 100 18 36 0 1217774400 65855808 3846080
03:35:25 89949 8192000000 10151282000 10643185664 1140874640 12.224 99 19 37 1 1242594272 98231088 3488544
03:37:58 102530 8192000000 10127264144 10615631872 1118254688 12.010 97 14 28 1 1208622496 87854896 2512912
03:40:41 46583 8192000000 10090876848 10564169728 1102964816 11.866 96 19 38 1 1205692864 98586224 4141824
03:42:38 79429 8192000000 10083928208 10518618112 1116218512 11.991 98 44 86 2 1170139904 45220768 8700624
03:44:59 50218 8192000000 10060322944 10539982848 1106910448 11.903 98 8 14 0 1163474000 54959776 1603776
03:46:53 52109 8192000000 10136506304 10637549568 1120000400 12.027 99 6 12 0 1224200752 102711392 1488960
03:48:46 155937 8192000000 10095752592 10636636160 1104338288 11.879 97 8 16 1 1181204064 75465472 1400304
03:52:31 63807 8192000000 10060201680 10511224832 1121344512 12.040 99 13 26 0 1169586560 45473104 2768944
03:54:36 46427 8192000000 10062156128 10509455360 1108933616 11.922 99 34 66 0 1157116944 41394864 6788464
03:56:29 27321 8192000000 10131775728 10562301952 1113108496 11.962 98 39 77 1 1202158800 80722352 8327952
03:58:00 84968 8192000000 10092606176 10570444800 1112164816 11.953 98 12 24 0 1183418544 68148304 3105424
04:00:30 84429 8192000000 10085152096 10514804736 1125359536 12.078 99 26 52 0 1196140000 65615424 5165040
04:02:59 59038 8192000000 10075297808 10528264192 1085937120 11.704 96 37 74 0 1151063824 57306256 7820448
04:05:30 37662 8192000000 10087205120 10534682624 1120751040 12.034 99 32 64 1 1186405040 59730224 5923776
04:07:09 34486 8192000000 10091363424 10572500992 1114777632 11.978 99 37 74 2 1181278768 59144112 7357024
04:08:45 45322 8192000000 10062360016 10536513536 1124817936 12.072 99 7 14 0 1159956256 33845040 1293280
04:10:37 25943 8192000000 10047281360 10522263552 1129508272 12.117 100 15 25 3 1155743296 23685456 2549568
04:12:03 43120 8192000000 10090523744 10544971776 1122539536 12.051 98 23 46 0 1157954464 29826976 5587952
04:13:48 48268 8192000000 10097043408 10544463872 1138355280 12.200 100 14 28 0 1178464416 37288544 2820592
04:15:37 24281 8192000000 10116902320 10565099520 1133185120 12.151 98 32 64 0 1211250368 70796496 7268752
04:17:16 16138 8192000000 10132935984 10576781312 1153834992 12.345 100 38 74 1 1225847968 63977184 8035792
04:18:32 63401 8192000000 10105556640 10590515200 1110318992 11.935 97 27 54 3 1243250160 126912320 6018848
04:20:36 70974 8192000000 10116121360 10556649472 1103407200 11.870 97 28 56 1 1180663024 71361888 5893936
04:22:48 21471 8192000000 10142305920 10605056000 1129704944 12.119 100 50 100 3 1237740720 97981616 10054160
04:24:11 26723 8192000000 10154027760 10581999616 1123421504 12.059 99 48 96 0 1218015936 84905984 9688448
04:25:42 91589 8192000000 10094572272 10542231552 1121082880 12.037 99 37 73 0 1215672064 86314016 8275168
04:28:14 106675 8192000000 10052388992 10536431616 1133583312 12.155 100 9 19 0 1178646544 42975984 2087248
04:31:32 48521 8192000000 10069137648 10523156480 1127925488 12.102 99 21 40 2 1158500528 26499360 4075680
04:33:21 17740 8192000000 10084019072 10521903104 1127235696 12.095 99 61 120 2 1175122032 36029536 11856800
04:34:45 40603 8192000000 10085978160 10531766272 1108015728 11.914 96 26 52 0 1161984672 48422704 5546240
04:36:29 36797 8192000000 10173714352 10627792896 1133754496 12.157 99 45 89 1 1247116784 104024816 9337472

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 17-04-2022 04:38:39 to 17-04-2022 04:38:54.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 131 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 17-04-2022 04:38:55 to 17-04-2022 04:38:57.

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          214249
MON$OLDEST_ACTIVE               217986
MON$OLDEST_SNAPSHOT             217986
MON$NEXT_TRANSACTION            217986
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                       2070624
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             768
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2761 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2761 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2761 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2761 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 ---
:197: 
:198: mscs-test-161.budzdorov.ru    Sun Apr 17 04:37:28 2022
:199:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:200: 
:201: 
:202: mscs-test-161.budzdorov.ru    Sun Apr 17 04:37:30 2022
:203:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:204: 
:205: 
:206: mscs-test-161.budzdorov.ru    Sun Apr 17 04:37:42 2022
:207:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:208: 
--- end of diff output ---

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

17.04.22 04:38:59. 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-04-17 04:39:01 No severe PSQL-related problems occured
17.04.22 04:39:02. End of report.