Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 30.07.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 15532524 kB
MemAvailable 31044148 kB
Buffers 0 kB
Cached 16201844 kB
SwapCached 2512 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-07-30 07:37:17
DTS_END                         2022-07-30 07:37:17.1640
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(68:17)
IP                              ::1/57770
TRN_ID                          218895
ATT_ID                          634
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: 9204.53 25848 1104544
customer order: creation 169.70 463 20364
customer order: refuse 14.38 453 1725
order to supplier: creation 45.72 1369 5486
order to supplier: removal 3.49 180746 419
invoice (draft): creation 44.21 3070 5305
invoice (draft): removal 51.73 1084 6207
invoice accept: apply 42.12 27861 5054
invoice accept: cancel 46.86 1343 5623
customer reserve: creation 3908.22 265 468986
customer reserve: removal 4584.73 107 550168
realization accept: apply 82.65 273 9918
realization accept: cancel 85.13 172 10215
payment from customer: creation 56.50 191 6780
payment from customer: removal 5.61 122 673
payment to supplier: creation 47.56 195 5707
payment to supplier: removal 3.54 141 425
service: total inventory turnovers 4.03 4556 484
service: total monetary turnovers 7.43 382 892
service: aggregate perf. data 0.90 16964 108
service: refresh index statistics 0.04 277197 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 30-07-2022 07:39:25 to 30-07-2022 07:39:28.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6322
TEST_TIME 2 4487
TEST_TIME 3 4608
TEST_TIME 4 5127
TEST_TIME 5 5299
TEST_TIME 6 5133
TEST_TIME 7 5175
TEST_TIME 8 5324
TEST_TIME 9 5323
TEST_TIME 10 5284
TEST_TIME 11 5594
TEST_TIME 12 5631
TEST_TIME 13 5770
TEST_TIME 14 5780
TEST_TIME 15 6211
TEST_TIME 16 6477
TEST_TIME 17 6512
TEST_TIME 18 6578
TEST_TIME 19 6631
TEST_TIME 20 6792
TEST_TIME 21 7134
TEST_TIME 22 7135
TEST_TIME 23 7107
TEST_TIME 24 7511
TEST_TIME 25 7691
TEST_TIME 26 7712
TEST_TIME 27 7813
TEST_TIME 28 7994
TEST_TIME 29 7902
TEST_TIME 30 8090
TEST_TIME 31 8360
TEST_TIME 32 8627
TEST_TIME 33 9036
TEST_TIME 34 9350
TEST_TIME 35 9769
TEST_TIME 36 9786
TEST_TIME 37 10136
TEST_TIME 38 10617
TEST_TIME 39 10549
TEST_TIME 40 10508
TEST_TIME 41 10378
TEST_TIME 42 10305
TEST_TIME 43 10366
TEST_TIME 44 10405
TEST_TIME 45 10277
TEST_TIME 46 10184
TEST_TIME 47 10037
TEST_TIME 48 9913
TEST_TIME 49 9844
TEST_TIME 50 9725
TEST_TIME 51 9735
TEST_TIME 52 9698
TEST_TIME 53 9610
TEST_TIME 54 9508
TEST_TIME 55 9652
TEST_TIME 56 9712
TEST_TIME 57 9607
TEST_TIME 58 9504
TEST_TIME 59 9483
TEST_TIME 60 9583
TEST_TIME 61 9519
TEST_TIME 62 9459
TEST_TIME 63 9434
TEST_TIME 64 9460
TEST_TIME 65 9469
TEST_TIME 66 9486
TEST_TIME 67 9596
TEST_TIME 68 9556
TEST_TIME 69 9549
TEST_TIME 70 9512
TEST_TIME 71 9599
TEST_TIME 72 9628
TEST_TIME 73 9578
TEST_TIME 74 9660
TEST_TIME 75 9660
TEST_TIME 76 9607
TEST_TIME 77 9502
TEST_TIME 78 9450
TEST_TIME 79 9500
TEST_TIME 80 9438
TEST_TIME 81 9392
TEST_TIME 82 9363
TEST_TIME 83 9386
TEST_TIME 84 9372
TEST_TIME 85 9365
TEST_TIME 86 9271
TEST_TIME 87 9237
TEST_TIME 88 9245
TEST_TIME 89 9243
TEST_TIME 90 9218
TEST_TIME 91 9209
TEST_TIME 92 9204
TEST_TIME 93 9178
TEST_TIME 94 9220
TEST_TIME 95 9336
TEST_TIME 96 9353
TEST_TIME 97 9329
TEST_TIME 98 9369
TEST_TIME 99 9370
TEST_TIME 100 9414
TEST_TIME 101 9443
TEST_TIME 102 9371
TEST_TIME 103 9338
TEST_TIME 104 9349
TEST_TIME 105 9346
TEST_TIME 106 9327
TEST_TIME 107 9342
TEST_TIME 108 9295
TEST_TIME 109 9252
TEST_TIME 110 9286
TEST_TIME 111 9336
TEST_TIME 112 9303
TEST_TIME 113 9300
TEST_TIME 114 9354
TEST_TIME 115 9446
TEST_TIME 116 9428
TEST_TIME 117 9430
TEST_TIME 118 9351
TEST_TIME 119 9285
TEST_TIME 120 9226

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 30-07-2022 07:39:29 to 30-07-2022 07:39: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 1537422 1537422 0 0.00 0 21120 14 0 0
doc_list_biud 1537418 1537418 0 0.00 0 35740 44 0 0
sp_add_invoice_to_stock 5185 5054 131 2.52 39 194163 27861 0 131
sp_cancel_adding_invoice 5623 5623 0 0.00 7 79574 1343 0 0
sp_cancel_client_order 1725 1725 0 0.00 5 10724 454 0 0
sp_cancel_customer_reserve 550168 550168 0 0.00 1 26178 107 0 0
sp_cancel_pay_from_customer 673 673 0 0.00 4 2601 122 0 0
sp_cancel_pay_to_supplier 425 425 0 0.00 3 3578 142 0 0
sp_cancel_supplier_invoice 6220 6207 13 0.20 3 13030 1084 0 13
sp_cancel_supplier_order 419 419 0 0.00 9 1480357 180747 0 0
sp_cancel_write_off 10215 10215 0 0.00 2 10726 173 0 0
sp_client_order 20364 20364 0 0.00 5 25893 463 0 0
sp_customer_reserve 468986 468986 0 0.00 1 88011 266 0 0
sp_fill_shopping_cart 31152 31152 0 0.00 1 19861 82 0 0
sp_fill_shopping_cart_clo_res 468971 468971 0 0.00 0 27602 18 0 0
sp_get_clo_for_invoice 5014 5014 0 0.00 0 34638 271 0 0
sp_kill_cost_storno 16936 16936 0 0.00 0 7690 80 0 0
sp_kill_qstorno_ret_qs2qd 1117177 1117177 0 0.00 0 27470 46 0 0
sp_kill_qty_storno 589282 589282 0 0.00 0 35740 107 0 0
sp_lock_selected_doc 20600 20600 0 0.00 0 6575 7 0 0
sp_make_cost_storno 27414 27414 0 0.00 0 21081 78 0 0
sp_multiply_rows_for_pdistr 27412 27412 0 0.00 0 13336 41 0 0
sp_multiply_rows_for_qdistr 20364 20364 0 0.00 0 22752 158 0 0
sp_pay_from_customer 6780 6780 0 0.00 5 13362 191 0 0
sp_pay_to_supplier 5707 5707 0 0.00 7 16909 196 0 0
sp_payment_common 12487 12487 0 0.00 2 16105 45 0 0
sp_qd_handle_on_cancel_clo 1725 1725 0 0.00 0 10679 382 0 0
sp_qd_handle_on_invoice_upd_sts 10630 10630 0 0.00 0 35738 588 0 0
sp_qd_handle_on_reserve_upd_sts 9918 9918 0 0.00 0 13158 62 0 0
sp_reserve_write_off 9918 9918 0 0.00 8 16658 273 0 0
sp_supplier_invoice 5305 5305 0 0.00 7 89745 3070 0 0
sp_supplier_order 5486 5486 0 0.00 7 23052 1370 0 0
srv_aggregate_perf_data 108 108 0 0.00 2342 145701 16965 0 0
srv_fill_mon_cache_memory 75 75 0 0.00 675 121187 29411 0 0
srv_find_qd_qs_mism 1056564 1056564 0 0.00 0 10737 11 0 0
srv_make_invnt_saldo 484 484 0 0.00 69 96083 4557 0 0
srv_make_money_saldo 892 892 0 0.00 1 13898 383 0 0
srv_recalc_idx_stat 5 5 0 0.00 80353 495422 277197 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 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5056 5056 0 0.00 1 11288 955 0 0
t$perf-norm:sp_cancel_adding_invoice 417 417 0 0.00 2 7719 507 0 0
t$perf-norm:sp_cancel_client_order 1725 1725 0 0.00 0 3294 34 0 0
t$perf-norm:sp_cancel_customer_reserve 644 644 0 0.00 0 2647 34 0 0
t$perf-norm:sp_cancel_pay_from_customer 673 673 0 0.00 0 1938 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 425 425 0 0.00 0 604 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 402 402 0 0.00 0 2309 43 0 0
t$perf-norm:sp_cancel_supplier_order 419 419 0 0.00 0 110290 7830 0 0
t$perf-norm:sp_cancel_write_off 971 971 0 0.00 0 2307 22 0 0
t$perf-norm:sp_client_order 20364 20364 0 0.00 0 4164 38 0 0
t$perf-norm:sp_customer_reserve 991 991 0 0.00 0 1196 15 0 0
t$perf-norm:sp_pay_from_customer 6780 6780 0 0.00 0 2839 20 0 0
t$perf-norm:sp_pay_to_supplier 5707 5707 0 0.00 0 4863 21 0 0
t$perf-norm:sp_reserve_write_off 9918 9918 0 0.00 0 6030 24 0 0
t$perf-norm:sp_supplier_invoice 5305 5305 0 0.00 0 6007 34 0 0
t$perf-norm:sp_supplier_order 5487 5487 0 0.00 0 4456 35 0 0
t$perf-norm:srv_aggregate_perf_data 108 108 0 0.00 0 687 24 0 0
t$perf-norm:srv_fill_mon_cache_memory 75 75 0 0.00 0 1191 39 0 0
t$perf-norm:srv_make_invnt_saldo 484 484 0 0.00 0 4277 28 0 0
t$perf-norm:srv_make_money_saldo 892 892 0 0.00 0 555 6 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 3 196 65 0 0
v_add_invoice_to_stock 10274 10012 262 2.55 0 3905 24 0 262
v_all_customers 36814 36814 0 0.00 0 272 4 0 0
v_all_suppliers 30024 30024 0 0.00 0 70 5 0 0
v_all_wares 40726 40726 0 0.00 0 91 4 0 0
v_cancel_adding_invoice 834 834 0 0.00 0 1487 20 0 0
v_cancel_client_order 3450 3450 0 0.00 0 2966 11 0 0
v_cancel_customer_prepayment 1346 1346 0 0.00 0 2102 12 0 0
v_cancel_customer_reserve 1288 1288 0 0.00 0 2121 15 0 0
v_cancel_payment_to_supplier 850 850 0 0.00 0 2451 19 0 0
v_cancel_supplier_invoice 830 804 26 3.13 0 4049 28 0 26
v_cancel_supplier_order 838 838 0 0.00 0 2440 14 0 0
v_cancel_write_off 1942 1942 0 0.00 0 1955 25 0 0
v_max_id_clo_ord 5486 5486 0 0.00 0 832 5 0 0
v_max_id_clo_res 991 991 0 0.00 0 2827 26 0 0
v_max_id_ord_sup 5303 5303 0 0.00 0 1966 6 0 0
v_max_non_paid_invoice 1484 1484 0 0.00 0 390 9 0 0
v_max_non_paid_realizn 3609 3609 0 0.00 0 7138 10 0 0
v_min_id_clo_ord 5486 5486 0 0.00 0 1921 8 0 0
v_min_id_clo_res 990 990 0 0.00 0 1725 10 0 0
v_min_id_ord_sup 5303 5303 0 0.00 0 4528 9 0 0
v_min_non_paid_invoice 5707 5707 0 0.00 0 16093 14 0 0
v_min_non_paid_realizn 6780 6780 0 0.00 0 7926 15 0 0
v_our_firm 10254 10254 0 0.00 0 56 5 0 0
v_reserve_write_off 19836 19836 0 0.00 0 4892 12 0 0
x_lock_depdocs_on_canc_invoice 11428 11428 0 0.00 0 15571 125 0 0
x_lock_depdocs_on_canc_sup_ord 419 419 0 0.00 0 3690 369 0 0
x_make_qstorno_1000_1200 5486 5486 0 0.00 2 22405 1194 0 0
x_make_qstorno_1200_2000 5305 5305 0 0.00 2 87570 2853 0 0
x_make_qstorno_2100_3300 468986 468986 0 0.00 0 87999 235 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 30-07-2022 07:39:33 to 30-07-2022 07:39: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
05:07:20 1 8192000000 8796669104 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
05:08:21 35329 8192000000 9725082336 10022985728 797894352 8.875 100 90 180 0 827555248 7876816 21784080
05:10:01 17497 8192000000 9759075520 10067382272 833221264 9.232 100 87 174 0 866950800 13021472 20708064
05:11:22 11390 8192000000 9794931424 10112536576 860652528 9.507 100 81 162 0 901225328 22006112 18566688
05:12:37 11290 8192000000 9856976880 10189656064 911279392 10.010 100 76 152 0 966725936 38838992 16607552
05:13:52 5685 8192000000 9912255856 10254835712 953342576 10.424 100 78 155 0 1022904976 52906880 16655520
05:15:00 2069 8192000000 9960554528 10310348800 988058800 10.763 100 95 182 2 1076138592 68634496 19445296
05:16:04 1646 8192000000 10002675024 10357747712 1004019264 10.917 100 92 181 0 1110965776 89164448 17782064
05:17:10 1006 8192000000 10004333680 10374578176 1027012064 11.140 100 96 186 1 1125811120 81303120 17495936
05:18:17 1055 8192000000 10012832288 10382663680 1045809312 11.320 100 93 177 2 1136501568 72664832 18027424
05:19:21 940 8192000000 10046624832 10422595584 1061839440 11.474 100 96 187 3 1165184112 84176400 19168272
05:20:23 1552 8192000000 10041608016 10420113408 1066011408 11.514 100 93 174 1 1161357232 78240288 17105536
05:21:26 1051 8192000000 10042800400 10427351040 1073146384 11.582 100 96 178 0 1169371456 77360128 18864944
05:22:28 1099 8192000000 10035614192 10416291840 1077413152 11.623 100 86 163 1 1153587664 61975408 14199104
05:23:31 1070 8192000000 10039626816 10426482688 1086507936 11.709 100 81 154 0 1161850304 60014624 15327744
05:24:35 1015 8192000000 10052781200 10438025216 1084815488 11.693 100 94 182 0 1178575456 75534720 18225248
05:25:38 1310 8192000000 10049562800 10442829824 1090559936 11.748 100 78 151 2 1170274624 61998528 17716160
05:26:40 1127 8192000000 10055318448 10449330176 1081341984 11.660 99 82 156 1 1179416864 82180064 15894816
05:27:44 955 8192000000 10064832352 10450706432 1093136992 11.772 100 95 182 1 1183166832 70744000 19285840
05:28:48 941 8192000000 10062395552 10451255296 1094077952 11.781 100 86 164 1 1180332400 68134736 18119712
05:29:49 6877 8192000000 10020462928 10423943168 1084730272 11.693 100 65 122 2 1143640896 45257936 13652688
05:30:59 2571 8192000000 9982280928 10357755904 1030425376 11.173 99 78 150 4 1104089920 58933360 14731184
05:32:03 2757 8192000000 10007577088 10387140608 1050376608 11.364 100 78 148 3 1123210384 58671632 14162144
05:33:07 980 8192000000 10008571392 10388033536 1054625280 11.405 100 92 181 0 1137639696 63075680 19938736
05:34:10 1008 8192000000 9987554704 10360995840 1019871696 11.071 97 89 169 4 1113441104 75985184 17584224
05:35:12 837 8192000000 9929898640 10299318272 994316704 10.823 98 92 169 1 1055877056 41859408 19700944
05:36:14 3974 8192000000 9977935936 10350182400 1023209776 11.103 100 61 120 0 1080921360 45948352 11763232
05:37:20 47463 8192000000 9982911872 10365816832 1033584800 11.203 99 53 106 0 1091563360 47400640 10577920
05:39:17 2376 8192000000 10019925952 10399993856 1064567120 11.500 100 83 156 0 1143432912 62890912 15974880
05:40:32 2457 8192000000 10056955104 10444976128 1077698464 11.626 100 68 132 0 1180770064 89044448 14027152
05:41:38 4223 8192000000 10060432576 10442211328 1087443920 11.718 100 82 148 3 1174079712 72672048 13963744
05:42:48 993 8192000000 10118141888 10501185536 1103595520 11.872 100 84 156 3 1231090256 112270992 15223744
05:43:52 5795 8192000000 10125174928 10520760320 1116724272 11.996 100 62 117 0 1244060752 114447440 12889040
05:45:08 4708 8192000000 10153378416 10560131072 1126180736 12.085 100 83 150 4 1270214944 128211872 15822336
05:46:19 4339 8192000000 10194942144 10593259520 1145819312 12.270 100 71 138 0 1312892512 152106928 14966272
05:47:27 1616 8192000000 10220844560 10618982400 1149510080 12.305 100 87 167 1 1331963152 164914560 17538512
05:48:35 3011 8192000000 10264784608 10662604800 1163589760 12.437 100 85 157 6 1377379488 196069392 17720336
05:49:43 1033 8192000000 10319836080 10720079872 1174930224 12.543 100 95 182 0 1430191696 234029232 21232240
05:50:47 1942 8192000000 10327085264 10736234496 1179370928 12.584 100 84 165 0 1444286352 245882480 19032944
05:51:55 1549 8192000000 10354012208 10790916096 1186426352 12.650 100 91 171 0 1466387264 259841680 20119232
05:53:01 1064 8192000000 10385680384 10804678656 1199918352 12.776 100 97 195 1 1496347840 274871168 21558320
05:54:05 1990 8192000000 10396010832 10809212928 1196816960 12.747 100 91 176 1 1505210832 287941344 20452528
05:55:13 669 8192000000 10429446288 10837782528 1195177296 12.732 100 96 186 4 1535246304 318473280 21595728
05:56:17 1051 8192000000 10464420112 10900615168 1199153168 12.768 100 98 192 1 1572018784 350084288 22781328
05:57:22 939 8192000000 10447233264 10889646080 1189774784 12.681 100 94 183 0 1548410752 335595680 23040288
05:58:27 1620 8192000000 10511544336 10931200000 1198886000 12.766 100 88 176 0 1615622432 395058640 21677792
05:59:30 1417 8192000000 10529933920 10937315328 1193223760 12.713 100 95 188 0 1634166384 418432240 22510384
06:00:36 985 8192000000 10570300512 11001757696 1194513616 12.725 100 93 183 1 1678178688 460867392 22797680
06:01:39 4085 8192000000 10550203184 11010781184 1188234064 12.667 100 67 134 1 1658231904 452460448 17537392
06:02:57 5549 8192000000 10515444192 10979717120 1195761168 12.737 100 71 138 0 1636595312 423653808 17180336
06:04:09 831 8192000000 10573789904 11015454720 1204498048 12.818 100 87 170 0 1680471808 452047392 23926368
06:05:14 1514 8192000000 10543479072 11007508480 1177353984 12.566 99 72 138 0 1648988464 455266960 16367520
06:06:24 2309 8192000000 10568218352 11007959040 1168146464 12.480 100 70 138 1 1670084048 483698976 18238608
06:07:33 3888 8192000000 10591201568 11029594112 1166165840 12.461 99 59 118 0 1691223856 509876048 15181968
06:08:45 5247 8192000000 10545828272 10999812096 1172373920 12.519 100 53 106 0 1655922800 470217664 13331216
06:09:56 11957 8192000000 10505755344 10987110400 1166804560 12.467 100 38 76 0 1614902496 438119456 9978480
06:11:19 17256 8192000000 10406430720 10920267776 1171967680 12.515 100 35 70 0 1515952736 335063952 8921104
06:12:48 35453 8192000000 10273889472 10735898624 1145338048 12.266 100 23 47 1 1382392368 231718880 5335440
06:14:29 25337 8192000000 10197204656 10762055680 1138070144 12.197 100 17 34 1 1334852736 192378080 4404512
06:17:27 41886 8192000000 10085596896 10519269376 1138645136 12.203 100 40 70 0 1186537472 39157888 8734448
06:19:13 103259 8192000000 10104180576 10571579392 1085180112 11.697 95 22 43 1 1163352704 73412000 4760592
06:21:59 41002 8192000000 10068705248 10504724480 1117538800 12.004 99 32 64 0 1151470672 27472608 6459264
06:23:48 82787 8192000000 10077540400 10536480768 1128811040 12.110 100 32 60 1 1170229312 34855264 6563008
06:26:14 11833 8192000000 10089970576 10521620480 1117738352 12.006 99 68 123 4 1173003568 42435536 12829680
06:27:28 6879 8192000000 10112642624 10549354496 1137876528 12.196 100 53 103 0 1195449600 47679856 9893216
06:28:38 21868 8192000000 10086118544 10522419200 1127954240 12.102 99 35 62 1 1177285856 43400832 5930784
06:30:02 11424 8192000000 10118354288 10558373888 1141324368 12.228 100 36 68 2 1205282592 56239264 7718960
06:31:24 8427 8192000000 10158866144 10603085824 1143175248 12.245 100 54 108 0 1264635696 109752752 11707696
06:32:37 49242 8192000000 10136414912 10633826304 1139476336 12.211 100 20 39 1 1244604208 100426048 4701824
06:34:31 25280 8192000000 10107898032 10559881216 1127314480 12.096 99 19 36 0 1204140160 72984448 3841232
06:36:01 58953 8192000000 10132419328 10604199936 1114990912 11.980 98 18 34 0 1221650640 102598672 4061056
06:38:20 43080 8192000000 10049412352 10489446400 1102243296 11.859 98 33 63 0 1134703696 25819312 6641088
06:40:05 48595 8192000000 10117489920 10581770240 1138052688 12.197 100 27 54 0 1217320912 73650816 5617408
06:42:43 44341 8192000000 10158781072 10615431168 1151298480 12.322 100 22 44 0 1245272000 89345120 4628400
06:44:28 36242 8192000000 10077183520 10543775744 1133158448 12.151 99 23 42 0 1213209456 75921120 4129888
06:46:06 2123 8192000000 10098832944 10519760896 1136021648 12.178 100 77 142 1 1188286512 38249904 14014960
06:47:09 5724 8192000000 10111712800 10547036160 1149511744 12.305 100 55 110 0 1214362240 52919872 11930624
06:48:15 54118 8192000000 10121108672 10613608448 1126363888 12.087 98 22 42 0 1215768272 84572912 4831472
06:50:10 15138 8192000000 10107954256 10546429952 1141905264 12.233 100 32 61 0 1204443136 56734384 5803488
06:51:27 94598 8192000000 10056504128 10507382784 1086643312 11.711 96 13 24 1 1137266976 47988656 2635008
06:54:02 115882 8192000000 10098665152 10544181248 1100213392 11.840 98 37 69 1 1178615440 71433280 6968768
06:57:11 11718 8192000000 10087024880 10505113600 1117064192 11.999 99 71 134 1 1179488800 48469376 13955232
06:58:25 6405 8192000000 10072773360 10500145152 1112347872 11.955 99 46 88 1 1167028640 45367680 9313088
06:59:34 44211 8192000000 10118100416 10564853760 1086099840 11.706 97 32 64 0 1203413088 110048496 7264752
07:01:21 81616 8192000000 10040422992 10524418048 1086722592 11.711 98 25 50 0 1149086896 56784912 5579392
07:03:47 55456 8192000000 10043695056 10514460672 1079133424 11.639 97 8 16 0 1140206928 59289072 1784432
07:05:59 39256 8192000000 10066935328 10505961472 1111853856 11.950 100 34 63 2 1161398784 42967088 6577840
07:07:40 39991 8192000000 10116664608 10571464704 1129321088 12.115 100 22 44 0 1205455440 71279024 4855328
07:09:21 27901 8192000000 10141532608 10603466752 1129130192 12.113 99 35 70 0 1227349712 90538864 7680656
07:10:51 24035 8192000000 10178538560 10624028672 1147053328 12.282 100 46 92 1 1272008432 115204912 9750192
07:12:16 51339 8192000000 10115310720 10609389568 1142724016 12.241 100 10 20 0 1223974288 79096528 2153744
07:14:08 46062 8192000000 10121063168 10560294912 1141550688 12.230 100 35 68 0 1205287632 57513104 6223840
07:15:54 58513 8192000000 10125354832 10564448256 1121014320 12.037 98 20 39 0 1184761168 60263552 3483296
07:17:58 54334 8192000000 10095967584 10531213312 1120541392 12.032 97 22 44 0 1165963280 41126912 4294976
07:20:02 61741 8192000000 10129785840 10615046144 1141606480 12.231 99 32 58 0 1226171536 78872512 5692544
07:22:06 121187 8192000000 10113751040 10619613184 1103616672 11.872 96 28 54 2 1161005856 51896656 5492528
07:25:14 9047 8192000000 10066886960 10491191296 1126378224 12.087 99 77 138 3 1156630624 16186704 14065696
07:26:24 18711 8192000000 10164468272 10597732352 1148899664 12.299 100 64 123 1 1248229840 86231808 13098368
07:27:43 48979 8192000000 10103311776 10596282368 1108099872 11.914 97 18 36 0 1162232992 50525696 3607424
07:29:34 103108 8192000000 10160267120 10611494912 1144953536 12.262 99 19 35 0 1256335056 107464576 3916944
07:32:17 102921 8192000000 10098003792 10548019200 1098882208 11.827 96 8 16 0 1164428864 64145808 1400848
07:35:03 71615 8192000000 10101990928 10578796544 1146051424 12.272 100 7 14 0 1202172608 54748064 1373120

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 30-07-2022 07:39:39 to 30-07-2022 07:39:56.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 30-07-2022 07:39:57 to 30-07-2022 07:39:59.

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          217518
MON$OLDEST_ACTIVE               219024
MON$OLDEST_SNAPSHOT             219024
MON$NEXT_TRANSACTION            219024
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                       2090304
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             767
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2811 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2811 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2811 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2811 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 ---
:286: 
:287: mscs-test-161.budzdorov.ru    Sat Jul 30 07:37:24 2022
:288:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:289: 
:290: 
:291: mscs-test-161.budzdorov.ru    Sat Jul 30 07:37:24 2022
:292:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:293: 
:294: 
:295: mscs-test-161.budzdorov.ru    Sat Jul 30 07:37:37 2022
:296:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:297: 
--- end of diff output ---

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

30.07.22 07:40:00. 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-07-30 07:40:02 No severe PSQL-related problems occured
30.07.22 07:40:03. End of report.