Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 17.06.2022 10: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 15678600 kB
MemAvailable 31298532 kB
Buffers 0 kB
Cached 16053032 kB
SwapCached 2452 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-06-17 10:37:16
DTS_END                         2022-06-17 10:37:16.8800
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/52626
TRN_ID                          211609
ATT_ID                          557
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: 9051.32 29574 1086158
customer order: creation 164.08 513 19689
customer order: refuse 13.93 479 1671
order to supplier: creation 43.98 1502 5277
order to supplier: removal 3.55 187030 426
invoice (draft): creation 43.58 3689 5229
invoice (draft): removal 52.48 1114 6298
invoice accept: apply 39.20 27274 4704
invoice accept: cancel 47.33 1428 5680
customer reserve: creation 3745.15 249 449418
customer reserve: removal 4608.13 112 552976
realization accept: apply 79.70 301 9564
realization accept: cancel 85.40 164 10248
payment from customer: creation 57.63 196 6915
payment from customer: removal 5.40 156 648
payment to supplier: creation 46.76 229 5611
payment to supplier: removal 3.43 130 411
service: total inventory turnovers 3.40 5606 408
service: total monetary turnovers 7.42 436 890
service: aggregate perf. data 0.76 21337 91
service: refresh index statistics 0.03 339530 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 17-06-2022 10:39:18 to 17-06-2022 10:39:20.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5392
TEST_TIME 2 4611
TEST_TIME 3 4602
TEST_TIME 4 4998
TEST_TIME 5 5312
TEST_TIME 6 4968
TEST_TIME 7 5149
TEST_TIME 8 5327
TEST_TIME 9 5761
TEST_TIME 10 6037
TEST_TIME 11 6211
TEST_TIME 12 6222
TEST_TIME 13 6447
TEST_TIME 14 6490
TEST_TIME 15 6734
TEST_TIME 16 6770
TEST_TIME 17 6970
TEST_TIME 18 6954
TEST_TIME 19 6942
TEST_TIME 20 7482
TEST_TIME 21 7408
TEST_TIME 22 7487
TEST_TIME 23 7542
TEST_TIME 24 7583
TEST_TIME 25 7803
TEST_TIME 26 7707
TEST_TIME 27 7657
TEST_TIME 28 7529
TEST_TIME 29 7562
TEST_TIME 30 8065
TEST_TIME 31 8420
TEST_TIME 32 8928
TEST_TIME 33 9017
TEST_TIME 34 9221
TEST_TIME 35 9669
TEST_TIME 36 9963
TEST_TIME 37 10121
TEST_TIME 38 10318
TEST_TIME 39 10310
TEST_TIME 40 10623
TEST_TIME 41 10712
TEST_TIME 42 10782
TEST_TIME 43 10707
TEST_TIME 44 10619
TEST_TIME 45 10492
TEST_TIME 46 10448
TEST_TIME 47 10474
TEST_TIME 48 10512
TEST_TIME 49 10484
TEST_TIME 50 10336
TEST_TIME 51 10216
TEST_TIME 52 10097
TEST_TIME 53 10093
TEST_TIME 54 10090
TEST_TIME 55 10068
TEST_TIME 56 9990
TEST_TIME 57 9908
TEST_TIME 58 9835
TEST_TIME 59 9762
TEST_TIME 60 9719
TEST_TIME 61 9770
TEST_TIME 62 9871
TEST_TIME 63 9816
TEST_TIME 64 9760
TEST_TIME 65 9683
TEST_TIME 66 9625
TEST_TIME 67 9623
TEST_TIME 68 9598
TEST_TIME 69 9488
TEST_TIME 70 9508
TEST_TIME 71 9414
TEST_TIME 72 9407
TEST_TIME 73 9340
TEST_TIME 74 9288
TEST_TIME 75 9302
TEST_TIME 76 9352
TEST_TIME 77 9368
TEST_TIME 78 9413
TEST_TIME 79 9427
TEST_TIME 80 9412
TEST_TIME 81 9400
TEST_TIME 82 9440
TEST_TIME 83 9417
TEST_TIME 84 9569
TEST_TIME 85 9523
TEST_TIME 86 9434
TEST_TIME 87 9400
TEST_TIME 88 9426
TEST_TIME 89 9392
TEST_TIME 90 9327
TEST_TIME 91 9357
TEST_TIME 92 9286
TEST_TIME 93 9275
TEST_TIME 94 9280
TEST_TIME 95 9286
TEST_TIME 96 9240
TEST_TIME 97 9223
TEST_TIME 98 9213
TEST_TIME 99 9198
TEST_TIME 100 9265
TEST_TIME 101 9281
TEST_TIME 102 9316
TEST_TIME 103 9429
TEST_TIME 104 9438
TEST_TIME 105 9404
TEST_TIME 106 9477
TEST_TIME 107 9537
TEST_TIME 108 9475
TEST_TIME 109 9410
TEST_TIME 110 9384
TEST_TIME 111 9382
TEST_TIME 112 9329
TEST_TIME 113 9320
TEST_TIME 114 9280
TEST_TIME 115 9228
TEST_TIME 116 9212
TEST_TIME 117 9177
TEST_TIME 118 9171
TEST_TIME 119 9140
TEST_TIME 120 9070

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 17-06-2022 10:39:21 to 17-06-2022 10:39:25.

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 1500050 1500050 0 0.00 0 25518 14 0 0
doc_list_biud 1500034 1500034 0 0.00 0 45393 47 0 0
sp_add_invoice_to_stock 4821 4704 117 2.42 66 244799 27275 0 117
sp_cancel_adding_invoice 5680 5680 0 0.00 4 95437 1428 0 0
sp_cancel_client_order 1671 1671 0 0.00 7 9152 480 0 0
sp_cancel_customer_reserve 552976 552976 0 0.00 1 45395 112 0 0
sp_cancel_pay_from_customer 648 648 0 0.00 3 13355 156 0 0
sp_cancel_pay_to_supplier 411 411 0 0.00 4 2415 130 0 0
sp_cancel_supplier_invoice 6305 6298 7 0.11 2 17852 1115 0 7
sp_cancel_supplier_order 426 426 0 0.00 10 1773482 187030 0 0
sp_cancel_write_off 10248 10248 0 0.00 2 20000 164 0 0
sp_client_order 19689 19689 0 0.00 5 30884 513 0 0
sp_customer_reserve 449418 449418 0 0.00 1 166247 250 0 0
sp_fill_shopping_cart 30180 30180 0 0.00 1 17479 89 0 0
sp_fill_shopping_cart_clo_res 449386 449386 0 0.00 0 26337 20 0 0
sp_get_clo_for_invoice 4661 4661 0 0.00 0 48296 383 0 0
sp_kill_cost_storno 16987 16987 0 0.00 0 16589 83 0 0
sp_kill_qstorno_ret_qs2qd 1122924 1122924 0 0.00 0 45390 48 0 0
sp_kill_qty_storno 591523 591523 0 0.00 0 45392 111 0 0
sp_lock_selected_doc 19864 19864 0 0.00 0 9130 8 0 0
sp_make_cost_storno 26751 26751 0 0.00 0 19672 91 0 0
sp_multiply_rows_for_pdistr 26750 26750 0 0.00 0 25514 43 0 0
sp_multiply_rows_for_qdistr 19689 19689 0 0.00 0 11918 175 0 0
sp_pay_from_customer 6915 6915 0 0.00 5 25525 196 0 0
sp_pay_to_supplier 5611 5611 0 0.00 7 20456 230 0 0
sp_payment_common 12526 12526 0 0.00 2 6917 51 0 0
sp_qd_handle_on_cancel_clo 1671 1671 0 0.00 1 9127 399 0 0
sp_qd_handle_on_invoice_upd_sts 10341 10341 0 0.00 0 18637 618 0 0
sp_qd_handle_on_reserve_upd_sts 9563 9563 0 0.00 0 16082 69 0 0
sp_reserve_write_off 9564 9564 0 0.00 8 19698 302 0 0
sp_supplier_invoice 5229 5229 0 0.00 8 103617 3689 0 0
sp_supplier_order 5277 5277 0 0.00 7 47624 1502 0 0
srv_aggregate_perf_data 91 91 0 0.00 2532 259469 21338 0 0
srv_fill_mon_cache_memory 73 73 0 0.00 835 181825 31823 0 0
srv_find_qd_qs_mism 1039007 1039007 0 0.00 0 13108 10 0 0
srv_make_invnt_saldo 408 408 0 0.00 89 81135 5607 0 0
srv_make_money_saldo 890 890 0 0.00 1 19451 436 0 0
srv_recalc_idx_stat 4 4 0 0.00 108250 479312 339531 0 0
t$perf-abend:sp_add_invoice_to_stock 117 117 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4707 4707 0 0.00 1 31696 1059 0 0
t$perf-norm:sp_cancel_adding_invoice 431 431 0 0.00 1 11145 576 0 0
t$perf-norm:sp_cancel_client_order 1671 1671 0 0.00 0 2913 33 0 0
t$perf-norm:sp_cancel_customer_reserve 696 696 0 0.00 0 4523 35 0 0
t$perf-norm:sp_cancel_pay_from_customer 648 648 0 0.00 0 857 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 411 411 0 0.00 0 355 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 408 408 0 0.00 0 3167 44 0 0
t$perf-norm:sp_cancel_supplier_order 426 426 0 0.00 0 120747 8589 0 0
t$perf-norm:sp_cancel_write_off 954 954 0 0.00 0 3298 27 0 0
t$perf-norm:sp_client_order 19689 19689 0 0.00 0 6840 42 0 0
t$perf-norm:sp_customer_reserve 975 975 0 0.00 0 2584 16 0 0
t$perf-norm:sp_pay_from_customer 6916 6916 0 0.00 0 3977 21 0 0
t$perf-norm:sp_pay_to_supplier 5611 5611 0 0.00 0 6346 26 0 0
t$perf-norm:sp_reserve_write_off 9565 9565 0 0.00 0 7969 28 0 0
t$perf-norm:sp_supplier_invoice 5230 5230 0 0.00 0 5283 43 0 0
t$perf-norm:sp_supplier_order 5277 5277 0 0.00 0 4466 35 0 0
t$perf-norm:srv_aggregate_perf_data 91 91 0 0.00 0 421 25 0 0
t$perf-norm:srv_fill_mon_cache_memory 73 73 0 0.00 0 399 10 0 0
t$perf-norm:srv_make_invnt_saldo 408 408 0 0.00 0 1371 26 0 0
t$perf-norm:srv_make_money_saldo 890 890 0 0.00 0 4587 10 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 156 63 0 0
v_add_invoice_to_stock 9546 9312 234 2.45 0 6624 29 0 234
v_all_customers 36438 36438 0 0.00 0 62 4 0 0
v_all_suppliers 29610 29610 0 0.00 0 98 4 0 0
v_all_wares 39375 39375 0 0.00 0 73 4 0 0
v_cancel_adding_invoice 862 862 0 0.00 0 1944 22 0 0
v_cancel_client_order 3342 3342 0 0.00 0 3668 14 0 0
v_cancel_customer_prepayment 1296 1296 0 0.00 0 2846 17 0 0
v_cancel_customer_reserve 1392 1392 0 0.00 0 1522 13 0 0
v_cancel_payment_to_supplier 822 822 0 0.00 0 1010 14 0 0
v_cancel_supplier_invoice 830 816 14 1.68 0 1044 24 0 14
v_cancel_supplier_order 852 852 0 0.00 0 957 15 0 0
v_cancel_write_off 1908 1908 0 0.00 0 851 23 0 0
v_max_id_clo_ord 5276 5276 0 0.00 0 5218 7 0 0
v_max_id_clo_res 974 974 0 0.00 0 7634 25 0 0
v_max_id_ord_sup 5216 5216 0 0.00 0 3834 7 0 0
v_max_non_paid_invoice 1298 1298 0 0.00 0 356 8 0 0
v_max_non_paid_realizn 3510 3510 0 0.00 0 1102 7 0 0
v_min_id_clo_ord 5276 5276 0 0.00 0 1566 9 0 0
v_min_id_clo_res 974 974 0 0.00 0 1006 9 0 0
v_min_id_ord_sup 5216 5216 0 0.00 0 7520 10 0 0
v_min_non_paid_invoice 5611 5611 0 0.00 0 6909 21 0 0
v_min_non_paid_realizn 6915 6915 0 0.00 0 6011 20 0 0
v_our_firm 9746 9746 0 0.00 0 59 5 0 0
v_reserve_write_off 19126 19126 0 0.00 0 4291 12 0 0
x_lock_depdocs_on_canc_invoice 11570 11570 0 0.00 0 23009 138 0 0
x_lock_depdocs_on_canc_sup_ord 426 426 0 0.00 0 18680 531 0 0
x_make_qstorno_1000_1200 5277 5277 0 0.00 3 47610 1310 0 0
x_make_qstorno_1200_2000 5229 5229 0 0.00 4 103593 3458 0 0
x_make_qstorno_2100_3300 449417 449417 0 0.00 0 166243 216 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 17-06-2022 10:39:26 to 17-06-2022 10:39:30.

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
08:07:20 2 8192000000 8796669024 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:21 32602 8192000000 9717568240 10014240768 798961664 8.886 100 88 176 0 827936496 7767728 21207104
08:09:57 25618 8192000000 9764903712 10072576000 839324592 9.293 100 91 182 1 873138416 12626480 21187344
08:11:28 10808 8192000000 9795019488 10112737280 861538800 9.516 100 84 168 0 908475232 27410240 19526192
08:12:48 7624 8192000000 9865739168 10196701184 916094416 10.058 100 89 175 0 966965792 31814992 19056384
08:13:57 6543 8192000000 9910983568 10251878400 952330768 10.414 100 85 168 0 1027863872 57674576 17858528
08:15:06 3681 8192000000 9981314576 10333634560 993832368 10.819 100 95 186 1 1096020944 83322560 18866016
08:16:12 1476 8192000000 10007716560 10371149824 1018551424 11.058 100 92 181 0 1123383328 86564720 18267184
08:17:15 1257 8192000000 10021746448 10393800704 1037816032 11.244 100 96 190 1 1143840704 87608944 18415728
08:18:19 1056 8192000000 10017802960 10387853312 1055986816 11.418 100 96 185 1 1133894240 59360752 18546672
08:19:21 1426 8192000000 10039133920 10412945408 1063787712 11.493 100 87 165 1 1153345008 73968544 15588752
08:20:25 5428 8192000000 10057520288 10452910080 1067985520 11.533 100 63 126 1 1177870800 97749584 12135696
08:21:33 1071 8192000000 10039695824 10423001088 1077272576 11.621 100 95 189 1 1160069200 63397872 19398752
08:22:35 1254 8192000000 10065878880 10452103168 1083512816 11.681 100 91 177 1 1183695408 81938256 18244336
08:23:37 1456 8192000000 10051647392 10438230016 1089180560 11.735 100 94 184 2 1171792448 63869264 18742624
08:24:43 2059 8192000000 10038495984 10420572160 1094108224 11.782 100 86 159 4 1157983088 47959776 15915088
08:25:46 7582 8192000000 10045717696 10428399616 1090935632 11.752 100 87 161 3 1165863168 59252320 15675216
08:26:54 1261 8192000000 10059204128 10446344192 1101432560 11.851 100 98 192 1 1183735008 62855952 19446496
08:27:56 1228 8192000000 10073618192 10459955200 1094491616 11.785 100 97 186 0 1195837472 83348512 17997344
08:28:58 974 8192000000 10052935584 10445369344 1098712944 11.825 100 86 164 0 1175596368 59675920 17207504
08:30:00 1107 8192000000 10049051408 10437451776 1086907184 11.713 100 95 178 1 1168571040 64133536 17530320
08:31:02 1395 8192000000 10038011184 10414993408 1076742832 11.616 100 97 183 2 1160452240 65736320 17973088
08:32:05 1054 8192000000 10025989952 10404245504 1059063280 11.448 100 96 180 0 1147249328 70198960 17987088
08:33:08 1319 8192000000 10057665728 10441453568 1054483632 11.404 100 94 186 0 1177219952 104401072 18335248
08:34:11 1170 8192000000 10011531408 10387337216 1040668288 11.271 100 88 164 6 1129809552 72706784 16434480
08:35:13 1628 8192000000 9982070112 10359652352 1038567056 11.251 100 76 141 3 1105932224 52728336 14636832
08:36:16 952 8192000000 9987053904 10361798656 1037367664 11.239 100 91 170 2 1110230192 53556896 19305632
08:37:18 1766 8192000000 10000444112 10382667776 1057836288 11.436 100 78 152 0 1120905280 46883152 16185840
08:38:24 12690 8192000000 9996556224 10378817536 1057766784 11.435 100 62 109 1 1115662208 46344320 11551104
08:39:42 1079 8192000000 10019057856 10396942336 1065199760 11.506 99 86 164 1 1140991840 58301488 17490592
08:40:47 8907 8192000000 10094363712 10482696192 1098691264 11.825 100 72 135 1 1214932464 101607552 14633648
08:42:00 1439 8192000000 10125402256 10513702912 1113150832 11.962 100 80 158 0 1244003312 113639328 17213152
08:43:05 3007 8192000000 10157728608 10542628864 1117598480 12.004 99 88 166 0 1267733216 133371296 16763440
08:44:16 1301 8192000000 10212294480 10601213952 1124842448 12.073 100 91 176 1 1326249312 181535408 19871456
08:45:20 2366 8192000000 10228187168 10625351680 1129112496 12.113 100 94 173 0 1338856512 190552192 19191824
08:46:26 6575 8192000000 10210773888 10625843200 1126250800 12.086 99 66 130 1 1314142432 172269760 15621872
08:47:38 1840 8192000000 10245933680 10643726336 1148019168 12.291 100 83 162 2 1361725952 195409632 18297152
08:48:43 1512 8192000000 10271111696 10672836608 1155452640 12.361 100 88 164 2 1390468144 216291232 18724272
08:49:47 1228 8192000000 10304302048 10707877888 1169059008 12.488 100 95 185 2 1415356960 225745696 20552256
08:50:51 1449 8192000000 10308600784 10716295168 1174222576 12.536 100 93 180 1 1430481696 234804704 21454416
08:51:57 1193 8192000000 10361457760 10765959168 1180681376 12.597 100 88 173 0 1474362528 274187008 19494144
08:53:04 1641 8192000000 10383722128 10791464960 1189484944 12.679 100 98 189 3 1495855488 283792144 22578400
08:54:09 2566 8192000000 10387352336 10814910464 1188096928 12.666 100 84 164 0 1495521104 287702464 19721712
08:55:15 2004 8192000000 10433409424 10847412224 1194062704 12.721 100 94 185 0 1540753760 324845232 21845824
08:56:21 929 8192000000 10435129328 10884440064 1194377856 12.724 99 96 192 1 1546523520 328377840 23767824
08:57:27 1637 8192000000 10447737456 10905227264 1194825552 12.728 100 96 181 3 1554421824 338570096 21026176
08:58:37 883 8192000000 10492064528 10903302144 1206500064 12.837 100 97 183 2 1594643312 366153440 21989808
08:59:43 1485 8192000000 10513317680 10930831360 1201785728 12.793 100 92 180 1 1621193760 397824336 21583696
09:00:50 3319 8192000000 10519973056 10969944064 1208165680 12.852 100 95 183 0 1625829024 395609216 22054128
09:01:58 1232 8192000000 10566272288 11017703424 1220353232 12.965 100 95 184 0 1669163568 426560352 22249984
09:03:01 2975 8192000000 10579050672 11002388480 1210283344 12.872 100 75 148 0 1677888272 449175792 18429136
09:04:10 3644 8192000000 10637771360 11070951424 1205477056 12.827 100 74 140 1 1746176368 523378080 17321232
09:05:35 953 8192000000 10703219344 11138166784 1204541136 12.818 100 90 176 2 1805032208 578563280 21927792
09:06:45 1786 8192000000 10690214800 11188588544 1201037344 12.786 100 76 151 0 1790490512 570360224 19092944
09:07:49 6528 8192000000 10659450288 11182383104 1198232288 12.760 100 64 128 0 1788160384 573141456 16786640
09:09:04 9660 8192000000 10582475536 11073085440 1187552112 12.661 100 46 90 0 1686552800 487086224 11914464
09:10:18 14347 8192000000 10528629680 11025580032 1185225424 12.639 100 39 78 0 1667126208 471901840 9998944
09:11:38 39162 8192000000 10422019408 10907725824 1153652736 12.344 99 25 50 0 1528555424 367886848 7015840
09:14:11 32189 8192000000 10229058048 10745098240 1121284000 12.039 97 25 50 0 1370257648 243139312 5834336
09:16:27 27250 8192000000 10231624192 10713108480 1157032528 12.375 100 27 55 0 1331015008 167576432 6406048
09:17:55 36491 8192000000 10139965408 10660122624 1134845520 12.167 99 20 40 1 1224769360 85532352 4391488
09:19:51 88380 8192000000 10067879984 10521604096 1083364720 11.680 95 8 16 2 1115294576 30488896 1440960
09:22:39 78096 8192000000 10108008528 10598207488 1127020448 12.093 99 16 32 0 1205609440 74982160 3606832
09:25:11 181825 8192000000 10071726160 10511966208 1042213856 11.286 92 17 34 0 1074136560 27883984 4038720
09:29:13 51810 8192000000 10072342448 10500960256 1083361776 11.679 97 51 92 1 1138160032 43842880 10955376
09:31:09 77560 8192000000 10083368656 10533343232 1100079136 11.838 98 41 78 1 1154754048 46086352 8588560
09:33:31 102096 8192000000 10072019536 10514743296 1113975104 11.970 99 21 36 4 1151040576 33349104 3716368
09:36:17 834 8192000000 10133423776 10544734208 1140992496 12.225 100 81 145 2 1214415824 59051728 14371600
09:37:19 33039 8192000000 10178309184 10610151424 1143209296 12.246 100 65 128 1 1270187680 112817488 14160896
09:38:53 31483 8192000000 10100051984 10601693184 1127348496 12.096 99 18 36 0 1237091072 105840384 3902192
09:40:50 30461 8192000000 10068829552 10528284672 1130404256 12.125 100 13 26 1 1169773248 36446544 2922448
09:42:21 31878 8192000000 10106746400 10567118848 1134490688 12.164 99 11 22 1 1191059808 53920976 2648144
09:44:11 55471 8192000000 10090404032 10555297792 1137328128 12.190 99 38 72 2 1204289920 59677728 7284064
09:46:20 28840 8192000000 10069560192 10513989632 1132915104 12.149 100 29 51 2 1167987744 29426656 5645984
09:48:03 42756 8192000000 10023758544 10507956224 1103819856 11.874 99 9 17 0 1133546448 28067504 1659088
09:49:49 68839 8192000000 10081012448 10527371264 1123813568 12.063 99 23 46 1 1172015344 42899856 5301920
09:51:59 65714 8192000000 10124037520 10585726976 1087253088 11.717 95 19 37 2 1223701936 132098992 4349856
09:54:15 22832 8192000000 10109994016 10547560448 1124494288 12.069 99 34 66 2 1190029472 59147248 6387936
09:55:40 19752 8192000000 10068766624 10526670848 1085543312 11.700 97 29 58 4 1142926416 51222016 6161088
09:57:03 36686 8192000000 10094703920 10535907328 1113140816 11.962 99 37 72 0 1164615024 44274128 7200080
09:58:41 25981 8192000000 10130823296 10570182656 1132304720 12.143 100 31 61 1 1233343568 94515344 6523504
10:00:26 85884 8192000000 10070024736 10526519296 1127158976 12.095 99 9 18 0 1207124016 78034864 1930176
10:02:53 82700 8192000000 10129481472 10579386368 1124145568 12.066 100 62 122 1 1200610512 63046160 13418784
10:05:47 29431 8192000000 10087394336 10597244928 1121061056 12.037 99 14 28 1 1194089024 70295392 2732576
10:07:20 42762 8192000000 10099413520 10549624832 1125995536 12.084 99 19 38 0 1191774592 62129968 3649088
10:09:02 54435 8192000000 10093945200 10568433664 1118333264 12.011 98 14 28 1 1171511920 50193792 2984864
10:10:57 45899 8192000000 10097025440 10561335296 1136843424 12.186 100 17 34 0 1190706048 50363664 3498960
10:12:45 25682 8192000000 10110918112 10545311744 1118984112 12.017 98 27 52 1 1181379696 56222368 6173216
10:14:12 30583 8192000000 10168753440 10612224000 1140630672 12.221 99 35 70 3 1257389168 109218928 7539568
10:15:43 32202 8192000000 10188292880 10622418944 1129301872 12.115 98 36 71 0 1259281424 122348288 7631264
10:17:19 46717 8192000000 10134461216 10629730304 1122149152 12.047 98 28 56 0 1251496192 123155184 6191856
10:19:12 39120 8192000000 10165997440 10610044928 1135305968 12.171 100 28 53 1 1246283904 105385344 5592592
10:20:52 28132 8192000000 10113500272 10598719488 1140893280 12.224 100 40 78 2 1209869168 60862256 8113632
10:22:20 23027 8192000000 10124408368 10572853248 1116296528 11.992 98 31 56 1 1204853168 82592016 5964624
10:23:46 56344 8192000000 10064709024 10574319616 1109856592 11.931 98 9 18 2 1192962272 81135408 1970272
10:25:46 86562 8192000000 10115028768 10555793408 1139537888 12.211 100 32 64 0 1206213488 59700848 6974752
10:28:15 68850 8192000000 10092623776 10565410816 1145808256 12.270 100 16 32 1 1178484976 29204512 3472208
10:30:34 120400 8192000000 10056176016 10511548416 1126187744 12.085 98 13 26 0 1161351808 32664656 2499408
10:33:50 33073 8192000000 10069468544 10512912384 1112409040 11.955 97 30 60 1 1149309968 30378000 6522928
10:35:24 79601 8192000000 10103909472 10558107648 1123949072 12.064 98 20 39 1 1179597536 51121168 4527296

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 17-06-2022 10:39:31 to 17-06-2022 10:39:47.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 117 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 17-06-2022 10:39:48 to 17-06-2022 10:39:50.

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          211608
MON$OLDEST_ACTIVE               215239
MON$OLDEST_SNAPSHOT             215239
MON$NEXT_TRANSACTION            215239
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                       2108160
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             762
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2780 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2780 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2780 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2780 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 ---
:111: 
:112: mscs-test-161.budzdorov.ru    Fri Jun 17 10:37:20 2022
:113:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:114: 
:115: 
:116: mscs-test-161.budzdorov.ru    Fri Jun 17 10:37:31 2022
:117:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:118: 
:119: 
:120: mscs-test-161.budzdorov.ru    Fri Jun 17 10:37:32 2022
:121:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:122: 
--- end of diff output ---

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

17.06.22 10:39:51. 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-06-17 10:39:54 No severe PSQL-related problems occured
17.06.22 10:39:55. End of report.