Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 05.01.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 16155092 kB
MemAvailable 31332020 kB
Buffers 20 kB
Cached 15497692 kB
SwapCached 1200 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.1
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-01-05 04:37:11
DTS_END                         2022-01-05 04:37:11.4210
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/60988
TRN_ID                          219968
ATT_ID                          618
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: 9032.97 20036 1083956
customer order: creation 171.24 408 20549
customer order: refuse 14.23 395 1707
order to supplier: creation 43.22 1327 5186
order to supplier: removal 3.45 152062 414
invoice (draft): creation 44.38 3196 5325
invoice (draft): removal 50.45 926 6054
invoice accept: apply 41.45 24416 4974
invoice accept: cancel 46.08 1139 5530
customer reserve: creation 3848.13 232 461775
customer reserve: removal 4474.87 91 536984
realization accept: apply 81.53 294 9784
realization accept: cancel 85.77 149 10292
payment from customer: creation 58.23 180 6988
payment from customer: removal 6.03 125 723
payment to supplier: creation 48.32 196 5798
payment to supplier: removal 3.44 111 413
service: total inventory turnovers 4.12 3752 494
service: total monetary turnovers 7.06 297 847
service: aggregate perf. data 0.94 14013 113
service: refresh index statistics 0.05 197410 6

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 05-01-2022 04:38:38 to 05-01-2022 04:38:40.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 10367
TEST_TIME 2 9031
TEST_TIME 3 7730
TEST_TIME 4 7249
TEST_TIME 5 6860
TEST_TIME 6 6853
TEST_TIME 7 6741
TEST_TIME 8 6545
TEST_TIME 9 6736
TEST_TIME 10 6932
TEST_TIME 11 7050
TEST_TIME 12 7097
TEST_TIME 13 7136
TEST_TIME 14 7509
TEST_TIME 15 7700
TEST_TIME 16 7681
TEST_TIME 17 7873
TEST_TIME 18 7923
TEST_TIME 19 8005
TEST_TIME 20 7941
TEST_TIME 21 7836
TEST_TIME 22 8047
TEST_TIME 23 8347
TEST_TIME 24 8279
TEST_TIME 25 8355
TEST_TIME 26 8522
TEST_TIME 27 8654
TEST_TIME 28 9445
TEST_TIME 29 9672
TEST_TIME 30 10094
TEST_TIME 31 10963
TEST_TIME 32 11215
TEST_TIME 33 10961
TEST_TIME 34 10967
TEST_TIME 35 10885
TEST_TIME 36 10810
TEST_TIME 37 10657
TEST_TIME 38 10575
TEST_TIME 39 10507
TEST_TIME 40 10421
TEST_TIME 41 10441
TEST_TIME 42 10356
TEST_TIME 43 10274
TEST_TIME 44 10139
TEST_TIME 45 10144
TEST_TIME 46 10052
TEST_TIME 47 10209
TEST_TIME 48 10272
TEST_TIME 49 10146
TEST_TIME 50 9985
TEST_TIME 51 9956
TEST_TIME 52 9891
TEST_TIME 53 9799
TEST_TIME 54 9785
TEST_TIME 55 9799
TEST_TIME 56 9767
TEST_TIME 57 9707
TEST_TIME 58 9804
TEST_TIME 59 9743
TEST_TIME 60 9641
TEST_TIME 61 9701
TEST_TIME 62 9681
TEST_TIME 63 9578
TEST_TIME 64 9601
TEST_TIME 65 9546
TEST_TIME 66 9621
TEST_TIME 67 9567
TEST_TIME 68 9545
TEST_TIME 69 9584
TEST_TIME 70 9588
TEST_TIME 71 9582
TEST_TIME 72 9660
TEST_TIME 73 9673
TEST_TIME 74 9632
TEST_TIME 75 9780
TEST_TIME 76 9825
TEST_TIME 77 9787
TEST_TIME 78 9761
TEST_TIME 79 9648
TEST_TIME 80 9595
TEST_TIME 81 9673
TEST_TIME 82 9657
TEST_TIME 83 9625
TEST_TIME 84 9567
TEST_TIME 85 9528
TEST_TIME 86 9452
TEST_TIME 87 9425
TEST_TIME 88 9435
TEST_TIME 89 9431
TEST_TIME 90 9386
TEST_TIME 91 9395
TEST_TIME 92 9368
TEST_TIME 93 9291
TEST_TIME 94 9298
TEST_TIME 95 9274
TEST_TIME 96 9285
TEST_TIME 97 9287
TEST_TIME 98 9240
TEST_TIME 99 9276
TEST_TIME 100 9274
TEST_TIME 101 9320
TEST_TIME 102 9332
TEST_TIME 103 9310
TEST_TIME 104 9294
TEST_TIME 105 9334
TEST_TIME 106 9324
TEST_TIME 107 9334
TEST_TIME 108 9369
TEST_TIME 109 9372
TEST_TIME 110 9394
TEST_TIME 111 9329
TEST_TIME 112 9281
TEST_TIME 113 9269
TEST_TIME 114 9233
TEST_TIME 115 9198
TEST_TIME 116 9189
TEST_TIME 117 9148
TEST_TIME 118 9118
TEST_TIME 119 9131
TEST_TIME 120 9066

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 05-01-2022 04:38:41 to 05-01-2022 04:38:45.

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 1509293 1509293 0 0.00 0 29586 13 0 0
doc_list_biud 1509271 1509271 0 0.00 0 31482 38 0 0
sp_add_invoice_to_stock 5077 4974 103 2.02 49 217005 24416 0 103
sp_cancel_adding_invoice 5530 5530 0 0.00 5 71210 1140 0 0
sp_cancel_client_order 1707 1707 0 0.00 6 10184 396 0 0
sp_cancel_customer_reserve 536984 536984 0 0.00 1 31348 92 0 0
sp_cancel_pay_from_customer 723 723 0 0.00 4 5161 126 0 0
sp_cancel_pay_to_supplier 413 413 0 0.00 4 5373 112 0 0
sp_cancel_supplier_invoice 6063 6054 9 0.14 3 16751 926 0 9
sp_cancel_supplier_order 414 414 0 0.00 11 1453531 152063 0 0
sp_cancel_write_off 10292 10292 0 0.00 2 21722 149 0 0
sp_client_order 20549 20549 0 0.00 6 23892 409 0 0
sp_customer_reserve 461775 461775 0 0.00 0 105542 232 0 0
sp_fill_shopping_cart 31051 31051 0 0.00 1 14062 77 0 0
sp_fill_shopping_cart_clo_res 461753 461753 0 0.00 0 18938 18 0 0
sp_get_clo_for_invoice 4937 4937 0 0.00 0 32029 250 0 0
sp_kill_cost_storno 16958 16958 0 0.00 0 6551 65 0 0
sp_kill_qstorno_ret_qs2qd 1090728 1090728 0 0.00 0 31296 40 0 0
sp_kill_qty_storno 575696 575696 0 0.00 0 31482 92 0 0
sp_lock_selected_doc 20376 20376 0 0.00 0 8894 9 0 0
sp_make_cost_storno 27507 27507 0 0.00 0 14365 80 0 0
sp_multiply_rows_for_pdistr 27506 27506 0 0.00 0 9883 38 0 0
sp_multiply_rows_for_qdistr 20549 20549 0 0.00 0 9704 139 0 0
sp_pay_from_customer 6988 6988 0 0.00 6 15106 180 0 0
sp_pay_to_supplier 5798 5798 0 0.00 7 14688 197 0 0
sp_payment_common 12785 12785 0 0.00 2 10689 54 0 0
sp_qd_handle_on_cancel_clo 1707 1707 0 0.00 0 8266 312 0 0
sp_qd_handle_on_invoice_upd_sts 10464 10464 0 0.00 1 31481 500 0 0
sp_qd_handle_on_reserve_upd_sts 9781 9781 0 0.00 0 21969 76 0 0
sp_reserve_write_off 9784 9784 0 0.00 8 22032 294 0 0
sp_supplier_invoice 5325 5325 0 0.00 10 87138 3196 0 0
sp_supplier_order 5186 5186 0 0.00 8 57144 1327 0 0
srv_aggregate_perf_data 113 113 0 0.00 2603 149787 14014 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 807 158260 38281 0 0
srv_find_qd_qs_mism 1035537 1035537 0 0.00 0 29585 10 0 0
srv_make_invnt_saldo 494 494 0 0.00 38 76309 3753 0 0
srv_make_money_saldo 847 847 0 0.00 1 7515 297 0 0
srv_recalc_idx_stat 6 6 0 0.00 46169 414785 197410 0 0
t$perf-abend:sp_add_invoice_to_stock 103 103 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4983 4983 0 0.00 1 12072 861 0 0
t$perf-norm:sp_cancel_adding_invoice 398 398 0 0.00 2 5789 403 0 0
t$perf-norm:sp_cancel_client_order 1707 1707 0 0.00 0 1420 31 0 0
t$perf-norm:sp_cancel_customer_reserve 647 647 0 0.00 0 3185 34 0 0
t$perf-norm:sp_cancel_pay_from_customer 723 723 0 0.00 0 3230 20 0 0
t$perf-norm:sp_cancel_pay_to_supplier 413 413 0 0.00 0 6841 32 0 0
t$perf-norm:sp_cancel_supplier_invoice 372 372 0 0.00 0 1850 39 0 0
t$perf-norm:sp_cancel_supplier_order 414 414 0 0.00 0 99398 6141 0 0
t$perf-norm:sp_cancel_write_off 991 991 0 0.00 0 4605 22 0 0
t$perf-norm:sp_client_order 20549 20549 0 0.00 0 8394 36 0 0
t$perf-norm:sp_customer_reserve 939 939 0 0.00 0 3218 24 0 0
t$perf-norm:sp_pay_from_customer 6989 6989 0 0.00 0 8486 23 0 0
t$perf-norm:sp_pay_to_supplier 5798 5798 0 0.00 0 4208 20 0 0
t$perf-norm:sp_reserve_write_off 9784 9784 0 0.00 0 9520 25 0 0
t$perf-norm:sp_supplier_invoice 5325 5325 0 0.00 0 8726 37 0 0
t$perf-norm:sp_supplier_order 5186 5186 0 0.00 0 5406 31 0 0
t$perf-norm:srv_aggregate_perf_data 113 113 0 0.00 0 5545 62 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 100 7 0 0
t$perf-norm:srv_make_invnt_saldo 494 494 0 0.00 0 1098 16 0 0
t$perf-norm:srv_make_money_saldo 847 847 0 0.00 0 226 4 0 0
t$perf-norm:srv_recalc_idx_stat 6 6 0 0.00 4 419 131 0 0
v_add_invoice_to_stock 10067 9861 206 2.04 0 4775 26 0 206
v_all_customers 37770 37770 0 0.00 0 89 4 0 0
v_all_suppliers 29442 29442 0 0.00 0 60 4 0 0
v_all_wares 41094 41094 0 0.00 0 49 4 0 0
v_cancel_adding_invoice 796 796 0 0.00 0 2188 17 0 0
v_cancel_client_order 3414 3414 0 0.00 0 4314 11 0 0
v_cancel_customer_prepayment 1446 1446 0 0.00 0 1198 9 0 0
v_cancel_customer_reserve 1294 1294 0 0.00 0 2990 14 0 0
v_cancel_payment_to_supplier 826 826 0 0.00 0 610 10 0 0
v_cancel_supplier_invoice 762 744 18 2.36 0 524 23 0 18
v_cancel_supplier_order 828 828 0 0.00 0 695 11 0 0
v_cancel_write_off 1982 1982 0 0.00 0 3255 23 0 0
v_max_id_clo_ord 5179 5179 0 0.00 0 408 5 0 0
v_max_id_clo_res 939 939 0 0.00 0 5512 24 0 0
v_max_id_ord_sup 5321 5321 0 0.00 0 2089 5 0 0
v_max_non_paid_invoice 1575 1575 0 0.00 0 597 7 0 0
v_max_non_paid_realizn 3630 3630 0 0.00 0 5414 9 0 0
v_min_id_clo_ord 5179 5179 0 0.00 0 3164 8 0 0
v_min_id_clo_res 939 939 0 0.00 0 744 8 0 0
v_min_id_ord_sup 5321 5321 0 0.00 0 3111 8 0 0
v_min_non_paid_invoice 5797 5797 0 0.00 0 6997 23 0 0
v_min_non_paid_realizn 6988 6988 0 0.00 0 10678 23 0 0
v_our_firm 10040 10040 0 0.00 0 75 4 0 0
v_reserve_write_off 19560 19560 0 0.00 0 3364 10 0 0
x_lock_depdocs_on_canc_invoice 11212 11212 0 0.00 0 19823 126 0 0
x_lock_depdocs_on_canc_sup_ord 414 414 0 0.00 0 9538 410 0 0
x_make_qstorno_1000_1200 5186 5186 0 0.00 2 54151 1167 0 0
x_make_qstorno_1200_2000 5325 5325 0 0.00 4 86937 2983 0 0
x_make_qstorno_2100_3300 461775 461775 0 0.00 0 105532 203 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 05-01-2022 04:38:46 to 05-01-2022 04:38:50.

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:15 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:16 24621 8192000000 9716089952 10010517504 795326592 8.849 100 86 172 0 823773104 7496224 20950288
02:09:44 28733 8192000000 9768129104 10076459008 839131328 9.291 100 89 178 0 872350608 12579440 20639840
02:11:18 20020 8192000000 9805998544 10124976128 874479664 9.645 100 82 162 0 913904720 21332384 18092672
02:12:53 7637 8192000000 9885291008 10218672128 922036000 10.116 100 87 174 0 995410880 54360896 19013984
02:14:03 6371 8192000000 9937966192 10280022016 956270496 10.453 100 86 170 0 1048361360 74352512 17738352
02:15:11 1961 8192000000 9956484480 10308665344 994461568 10.825 100 86 172 3 1070930608 58842496 17626544
02:16:16 1321 8192000000 10007842160 10372997120 1030339136 11.172 100 84 161 1 1123547616 76668368 16540112
02:17:19 1167 8192000000 10005364064 10372935680 1044447888 11.307 100 93 183 1 1132591888 67872768 20271232
02:18:21 1640 8192000000 10038292816 10409668608 1054866240 11.407 100 92 177 0 1157651472 85000800 17784432
02:19:24 6079 8192000000 10019005360 10396327936 1064198560 11.497 100 69 134 1 1141472928 63832384 13441984
02:20:32 1002 8192000000 10049950176 10429652992 1066557024 11.519 100 87 168 1 1170284512 86436128 17291360
02:21:35 1726 8192000000 10050649008 10432208896 1074287344 11.593 100 89 169 0 1176508000 86495536 15725120
02:22:38 1204 8192000000 10040608672 10422292480 1080333760 11.651 100 97 187 1 1166610112 67364832 18911520
02:23:41 1259 8192000000 10048415840 10433728512 1081624960 11.663 100 95 180 2 1172068624 72016464 18427200
02:24:43 1051 8192000000 10042451088 10426171392 1085972480 11.704 100 98 184 0 1164994608 60666368 18355760
02:25:45 1235 8192000000 10073354384 10458255360 1091994016 11.762 100 92 182 1 1196599776 85794192 18811568
02:26:47 1415 8192000000 10060407296 10441703424 1087775552 11.722 100 98 188 2 1184122208 76933232 19413424
02:27:49 4596 8192000000 10058336448 10454249472 1094050464 11.781 100 65 123 1 1180297136 74117968 12128704
02:28:54 827 8192000000 10031838384 10412482560 1075549600 11.605 100 97 182 2 1152709120 59646400 17513120
02:29:56 1229 8192000000 10046213152 10423574528 1077644304 11.625 100 94 181 0 1163338000 66153296 19540400
02:30:58 1399 8192000000 10009964752 10384113664 1046491232 11.327 100 96 182 1 1136688064 71118176 19078656
02:32:00 1266 8192000000 10030766624 10407112704 1059570560 11.452 100 98 194 1 1152411232 72908640 19932032
02:33:03 1310 8192000000 10029319440 10399350784 1056764608 11.426 100 97 186 1 1148118832 71612784 19741440
02:34:05 1545 8192000000 9986962000 10357608448 1031653872 11.184 100 82 148 1 1108812336 61865616 15292848
02:35:08 3538 8192000000 9988075184 10363084800 1041901280 11.283 100 66 120 0 1113513248 59920336 11691632
02:36:12 573 8192000000 9999526240 10382192640 1053654032 11.396 100 77 144 1 1123402512 53967968 15780512
02:37:17 2017 8192000000 10020633264 10398318592 1067614208 11.529 100 93 177 0 1147768304 63807664 16346432
02:38:25 1219 8192000000 10017915296 10391240704 1067671696 11.530 100 90 165 1 1136708768 51013152 18023920
02:39:30 2163 8192000000 10061080880 10447048704 1095222448 11.792 100 81 158 4 1182700720 72076752 15401520
02:40:40 14771 8192000000 10032468112 10423554048 1092488256 11.766 99 56 100 3 1152392464 50063152 9841056
02:42:01 1804 8192000000 10119609888 10506690560 1118475776 12.013 100 93 179 1 1232899856 96889760 17534320
02:43:08 3256 8192000000 10156322896 10544844800 1126824688 12.091 100 78 146 0 1264106384 121797664 15484032
02:44:15 10666 8192000000 10136607616 10541383680 1139736256 12.213 100 60 107 0 1257958912 106302816 11919840
02:45:31 3811 8192000000 10207861040 10603798528 1157322544 12.378 100 83 153 1 1326129024 152453088 16353392
02:46:40 1232 8192000000 10239427168 10637099008 1167369104 12.472 100 91 179 0 1350477008 162773536 20334368
02:47:45 1239 8192000000 10271774368 10670317568 1170548176 12.502 100 88 169 0 1380375888 191370576 18457136
02:48:49 1061 8192000000 10295287040 10694615040 1184915568 12.636 100 91 180 1 1407145168 200730128 21499472
02:49:53 2521 8192000000 10342678784 10749317120 1189609712 12.680 100 83 155 1 1459763264 252705472 17448080
02:50:58 1199 8192000000 10351335504 10786557952 1186341232 12.649 100 96 182 1 1464929040 256272176 22315632
02:52:03 872 8192000000 10377556112 10789388288 1197843968 12.756 100 90 166 3 1487189664 270683488 18662208
02:53:06 861 8192000000 10416629616 10828488704 1203977376 12.813 100 97 192 0 1525813840 298426928 23409536
02:54:09 799 8192000000 10445721824 10877775872 1214395232 12.910 100 96 190 0 1553948304 315981024 23572048
02:55:13 1123 8192000000 10473079312 10893115392 1217234640 12.936 100 92 179 0 1580004912 342078144 20692128
02:56:17 1790 8192000000 10476030944 10899816448 1211375408 12.882 100 93 178 1 1585382240 352615104 21391728
02:57:24 1596 8192000000 10515553680 10943287296 1218912992 12.952 100 77 149 0 1622350048 385368944 18068112
02:58:29 1577 8192000000 10561854864 10996330496 1215992544 12.925 100 78 153 0 1664984656 429977456 19014656
02:59:38 905 8192000000 10608683632 11035676672 1217308256 12.937 100 94 185 1 1712754880 472506112 22940512
03:00:42 6605 8192000000 10572890816 11016114176 1193246800 12.714 99 63 127 1 1676491552 467448624 15796128
03:01:50 13835 8192000000 10603082688 11058159616 1188848224 12.673 100 48 97 0 1714901104 512973632 13079248
03:03:06 19518 8192000000 10594624848 11063017472 1156322736 12.369 98 48 92 0 1698878896 529721120 12835040
03:04:32 15043 8192000000 10606673760 11062415360 1164691520 12.447 99 39 78 1 1695646832 520704448 10250864
03:05:50 32636 8192000000 10430855664 10937024512 1144577728 12.259 99 35 70 1 1556300592 402866544 8856320
03:07:57 28290 8192000000 10148624720 10678046720 1127547360 12.098 99 23 46 2 1359705248 226075056 6082832
03:09:26 24675 8192000000 10077472160 10537279488 1131785968 12.138 100 30 59 1 1212641440 74693520 6161952
03:10:53 64109 8192000000 10072405792 10556194816 1105908224 11.894 98 13 26 0 1159327968 50863904 2555840
03:12:59 52120 8192000000 10054102304 10514255872 1102178960 11.858 98 7 13 1 1152148032 49041504 927568
03:14:56 128849 8192000000 10100914656 10557841408 1106819296 11.902 97 16 32 0 1164269360 53730128 3719936
03:18:06 158259 8192000000 10066193760 10518249472 1116024960 11.989 97 18 34 2 1140588992 21273696 3290336
03:21:51 10220 8192000000 10084312112 10509144064 1132948832 12.149 100 42 86 0 1171612224 30297888 8365504
03:23:14 108320 8192000000 10078567264 10586513408 1127376240 12.097 98 10 20 0 1219688496 90433088 1879168
03:26:05 17067 8192000000 10062669616 10495815680 1126628048 12.090 100 20 38 0 1155764816 25107104 4029664
03:27:29 96241 8192000000 10113771680 10580639744 1129015408 12.112 99 32 61 3 1218287296 82125712 7146176
03:30:06 97917 8192000000 10062429136 10482159616 1085204784 11.697 95 24 46 1 1120183136 30180272 4798080
03:32:46 34020 8192000000 10082590464 10520981504 1114318608 11.973 98 23 42 2 1170482496 51096080 5067808
03:34:20 13278 8192000000 10129938592 10546962432 1131789328 12.138 100 56 106 1 1214921376 71835344 11296704
03:35:37 60041 8192000000 10093010112 10564415488 1127078192 12.094 99 14 28 0 1194711280 64532640 3100448
03:37:39 38002 8192000000 10122390288 10601451520 1141772736 12.232 100 15 32 0 1241832272 96572960 3486576
03:39:22 39175 8192000000 10066849504 10565582848 1117035984 11.999 98 9 18 0 1161006656 41763552 2207120
03:41:01 71343 8192000000 10071743248 10516869120 1096211808 11.802 96 17 30 1 1111285888 11933952 3140128
03:43:13 64163 8192000000 10050801248 10484609024 1099070544 11.829 97 19 36 3 1129081152 26273616 3736992
03:45:18 22466 8192000000 10140242544 10568269824 1121337360 12.040 99 45 90 0 1219362144 89075840 8948944
03:46:42 52025 8192000000 10171934304 10644611072 1144928608 12.262 100 23 44 1 1245766416 96317712 4520096
03:48:34 37950 8192000000 10162730944 10595524608 1128705536 12.109 99 23 44 2 1248942704 114743968 5493200
03:50:13 79196 8192000000 10136741472 10588053504 1100517824 11.843 97 16 32 1 1199380688 95435792 3427072
03:52:34 42419 8192000000 10114279648 10569469952 1132935872 12.149 100 19 36 0 1218515664 81665840 3913952
03:54:17 63147 8192000000 10150201728 10608254976 1122493712 12.051 98 20 40 0 1197439632 70797456 4148464
03:57:08 90595 8192000000 10104061520 10638327808 1124907040 12.073 99 37 72 0 1246665376 114250528 7507808
03:59:46 71203 8192000000 10058909648 10552766464 1124841504 12.073 99 11 22 0 1187924528 60647168 2435856
04:01:58 92445 8192000000 10126857216 10597007360 1109993360 11.932 98 21 42 1 1176645600 62305744 4346496
04:04:41 86831 8192000000 10089360560 10579832832 1099733088 11.835 97 24 48 1 1184706272 78991360 5981824
04:07:21 74181 8192000000 10070971040 10534916096 1106295120 11.897 98 25 50 0 1168700224 57245360 5159744
04:09:39 80411 8192000000 10079278992 10526474240 1122156928 12.047 99 15 30 0 1155969184 30971584 2840672
04:12:09 65271 8192000000 10062769216 10538532864 1121105152 12.037 99 13 24 2 1180463504 57228256 2130096
04:14:19 32588 8192000000 10044860208 10494554112 1113602080 11.967 98 15 29 2 1155109648 38398256 3109312
04:15:53 26004 8192000000 10135910112 10579034112 1128376960 12.106 99 65 128 2 1218764272 77483680 12903632
04:17:24 15820 8192000000 10126579632 10569142272 1143658544 12.250 100 66 126 1 1215191472 58799952 12732976
04:18:43 30898 8192000000 10113257104 10581147648 1134060784 12.160 99 30 60 0 1213342928 73192544 6089600
04:20:28 19924 8192000000 10114119872 10543468544 1119329008 12.021 98 35 70 0 1172822736 46600592 6893136
04:21:51 28839 8192000000 10114381856 10589257728 1138762672 12.204 100 41 82 2 1223705696 76478304 8464720
04:23:45 11069 8192000000 10116986336 10535960576 1110697888 11.939 98 51 99 1 1180823600 60362912 9762800
04:24:57 82823 8192000000 10104028128 10579935232 1115455968 11.984 99 20 40 0 1193670704 74218816 3995920
04:27:23 51374 8192000000 10059676064 10518020096 1117637200 12.005 99 24 48 0 1183486464 60159776 5689488
04:29:29 83243 8192000000 10067331472 10537115648 1123243808 12.058 99 13 26 0 1180386912 54710176 2432928
04:31:53 80230 8192000000 10074686464 10540572672 1099459376 11.833 98 10 20 1 1162395072 60903616 2032080
04:34:14 78715 8192000000 10062847616 10514067456 1090244800 11.745 97 20 40 0 1145331824 50344272 4742752
04:36:48 29462 8192000000 9094552720 9345490944 255047840 3.019 23 5 8 1 259255216 3406288 801088

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 05-01-2022 04:38:52 to 05-01-2022 04:39:08.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 103 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 05-01-2022 04:39:09 to 05-01-2022 04:39:11.

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          220462
MON$OLDEST_ACTIVE               220463
MON$OLDEST_SNAPSHOT             220463
MON$NEXT_TRANSACTION            220463
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                       2069152
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             776
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2701 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2701 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2701 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2701 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 ---
--- end of diff output ---

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

05.01.22 04:39:12. 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-01-05 04:39:14 No severe PSQL-related problems occured
05.01.22 04:39:15. End of report.