Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 31.01.2023 13: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 15030544 kB
MemAvailable 30450548 kB
Buffers 0 kB
Cached 16696540 kB
SwapCached 3736 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.3
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3400
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2023-01-31 13:37:27
DTS_END                         2023-01-31 13:37:27.3640
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/34864
TRN_ID                          221872
ATT_ID                          651
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: 8925.77 30875 1071092
customer order: creation 170.05 470 20406
customer order: refuse 15.13 451 1815
order to supplier: creation 45.79 1325 5495
order to supplier: removal 3.32 187782 398
invoice (draft): creation 46.69 3196 5603
invoice (draft): removal 47.73 1112 5727
invoice accept: apply 42.48 28193 5098
invoice accept: cancel 43.54 1446 5225
customer reserve: creation 3980.11 265 477613
customer reserve: removal 4233.11 116 507973
realization accept: apply 84.82 299 10178
realization accept: cancel 79.33 182 9520
payment from customer: creation 61.18 188 7341
payment from customer: removal 5.97 119 716
payment to supplier: creation 49.52 198 5942
payment to supplier: removal 3.75 129 450
service: total inventory turnovers 4.20 4212 504
service: total monetary turnovers 8.10 353 972
service: aggregate perf. data 0.93 16458 112
service: refresh index statistics 0.03 371004 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 31-01-2023 13:39:15 to 31-01-2023 13:39:17.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4841
TEST_TIME 2 4251
TEST_TIME 3 4119
TEST_TIME 4 4018
TEST_TIME 5 4061
TEST_TIME 6 3971
TEST_TIME 7 4309
TEST_TIME 8 4743
TEST_TIME 9 4978
TEST_TIME 10 5312
TEST_TIME 11 5746
TEST_TIME 12 6208
TEST_TIME 13 6239
TEST_TIME 14 6239
TEST_TIME 15 6297
TEST_TIME 16 6480
TEST_TIME 17 6712
TEST_TIME 18 6856
TEST_TIME 19 7001
TEST_TIME 20 7222
TEST_TIME 21 7275
TEST_TIME 22 7314
TEST_TIME 23 7278
TEST_TIME 24 7587
TEST_TIME 25 7562
TEST_TIME 26 7613
TEST_TIME 27 8127
TEST_TIME 28 8226
TEST_TIME 29 8400
TEST_TIME 30 8500
TEST_TIME 31 9030
TEST_TIME 32 9333
TEST_TIME 33 9530
TEST_TIME 34 9570
TEST_TIME 35 9453
TEST_TIME 36 9627
TEST_TIME 37 9805
TEST_TIME 38 10237
TEST_TIME 39 10408
TEST_TIME 40 10349
TEST_TIME 41 10168
TEST_TIME 42 10191
TEST_TIME 43 10268
TEST_TIME 44 10191
TEST_TIME 45 10248
TEST_TIME 46 10108
TEST_TIME 47 9939
TEST_TIME 48 9847
TEST_TIME 49 9781
TEST_TIME 50 9768
TEST_TIME 51 9756
TEST_TIME 52 9628
TEST_TIME 53 9514
TEST_TIME 54 9487
TEST_TIME 55 9447
TEST_TIME 56 9351
TEST_TIME 57 9324
TEST_TIME 58 9331
TEST_TIME 59 9438
TEST_TIME 60 9425
TEST_TIME 61 9339
TEST_TIME 62 9422
TEST_TIME 63 9456
TEST_TIME 64 9527
TEST_TIME 65 9511
TEST_TIME 66 9496
TEST_TIME 67 9585
TEST_TIME 68 9499
TEST_TIME 69 9592
TEST_TIME 70 9665
TEST_TIME 71 9604
TEST_TIME 72 9635
TEST_TIME 73 9691
TEST_TIME 74 9652
TEST_TIME 75 9624
TEST_TIME 76 9529
TEST_TIME 77 9449
TEST_TIME 78 9380
TEST_TIME 79 9368
TEST_TIME 80 9363
TEST_TIME 81 9341
TEST_TIME 82 9290
TEST_TIME 83 9234
TEST_TIME 84 9224
TEST_TIME 85 9172
TEST_TIME 86 9124
TEST_TIME 87 9139
TEST_TIME 88 9128
TEST_TIME 89 9102
TEST_TIME 90 9088
TEST_TIME 91 9095
TEST_TIME 92 9136
TEST_TIME 93 9142
TEST_TIME 94 9124
TEST_TIME 95 9167
TEST_TIME 96 9168
TEST_TIME 97 9211
TEST_TIME 98 9183
TEST_TIME 99 9156
TEST_TIME 100 9102
TEST_TIME 101 9047
TEST_TIME 102 9073
TEST_TIME 103 9041
TEST_TIME 104 9003
TEST_TIME 105 9001
TEST_TIME 106 8986
TEST_TIME 107 9004
TEST_TIME 108 9022
TEST_TIME 109 8994
TEST_TIME 110 8999
TEST_TIME 111 9036
TEST_TIME 112 9006
TEST_TIME 113 9038
TEST_TIME 114 9062
TEST_TIME 115 9076
TEST_TIME 116 9129
TEST_TIME 117 9090
TEST_TIME 118 9041
TEST_TIME 119 9007
TEST_TIME 120 8946

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 31-01-2023 13:39:19 to 31-01-2023 13:39:22.

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 1508412 1508412 0 0.00 0 21548 14 0 0
doc_list_biud 1508406 1508406 0 0.00 0 35961 45 0 0
sp_add_invoice_to_stock 5162 5098 64 1.23 61 199242 28193 0 64
sp_cancel_adding_invoice 5225 5225 0 0.00 7 91900 1446 0 0
sp_cancel_client_order 1815 1815 0 0.00 6 15909 451 0 0
sp_cancel_customer_reserve 507973 507973 0 0.00 1 24908 117 0 0
sp_cancel_pay_from_customer 716 716 0 0.00 4 1642 119 0 0
sp_cancel_pay_to_supplier 450 450 0 0.00 4 3916 129 0 0
sp_cancel_supplier_invoice 5731 5727 4 0.06 3 16499 1112 0 4
sp_cancel_supplier_order 398 398 0 0.00 10 1458194 187782 0 0
sp_cancel_write_off 9520 9520 0 0.00 2 21049 182 0 0
sp_client_order 20406 20406 0 0.00 5 25754 470 0 0
sp_customer_reserve 477613 477613 0 0.00 0 93736 265 0 0
sp_fill_shopping_cart 31491 31491 0 0.00 2 14881 88 0 0
sp_fill_shopping_cart_clo_res 477590 477590 0 0.00 0 19493 21 0 0
sp_get_clo_for_invoice 5062 5062 0 0.00 0 37986 303 0 0
sp_kill_cost_storno 15911 15911 0 0.00 0 7156 80 0 0
sp_kill_qstorno_ret_qs2qd 1031591 1031591 0 0.00 0 24857 51 0 0
sp_kill_qty_storno 545897 545897 0 0.00 0 35961 116 0 0
sp_lock_selected_doc 21194 21194 0 0.00 0 10277 7 0 0
sp_make_cost_storno 28523 28523 0 0.00 0 19755 88 0 0
sp_multiply_rows_for_pdistr 28522 28522 0 0.00 0 10374 37 0 0
sp_multiply_rows_for_qdistr 20406 20406 0 0.00 0 11532 180 0 0
sp_pay_from_customer 7341 7341 0 0.00 5 27559 189 0 0
sp_pay_to_supplier 5942 5942 0 0.00 6 20537 199 0 0
sp_payment_common 13283 13283 0 0.00 2 10943 49 0 0
sp_qd_handle_on_cancel_clo 1815 1815 0 0.00 0 15884 378 0 0
sp_qd_handle_on_invoice_upd_sts 10286 10286 0 0.00 0 35960 590 0 0
sp_qd_handle_on_reserve_upd_sts 10178 10178 0 0.00 0 18744 73 0 0
sp_reserve_write_off 10178 10178 0 0.00 7 25371 299 0 0
sp_supplier_invoice 5603 5603 0 0.00 11 63313 3196 0 0
sp_supplier_order 5495 5495 0 0.00 6 25976 1325 0 0
srv_aggregate_perf_data 112 112 0 0.00 2596 108268 16458 0 0
srv_fill_mon_cache_memory 74 74 0 0.00 730 158769 29798 0 0
srv_find_qd_qs_mism 1019940 1019940 0 0.00 0 10583 11 0 0
srv_make_invnt_saldo 504 504 0 0.00 46 73920 4212 0 0
srv_make_money_saldo 972 972 0 0.00 1 9755 354 0 0
srv_recalc_idx_stat 4 4 0 0.00 270520 445944 371004 0 0
t$perf-abend:sp_add_invoice_to_stock 64 64 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 4 4 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5101 5101 0 0.00 1 35080 985 0 0
t$perf-norm:sp_cancel_adding_invoice 441 441 0 0.00 1 7600 526 0 0
t$perf-norm:sp_cancel_client_order 1815 1815 0 0.00 0 4120 32 0 0
t$perf-norm:sp_cancel_customer_reserve 722 722 0 0.00 0 2998 41 0 0
t$perf-norm:sp_cancel_pay_from_customer 716 716 0 0.00 0 1610 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 450 450 0 0.00 0 704 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 402 402 0 0.00 0 639 32 0 0
t$perf-norm:sp_cancel_supplier_order 398 398 0 0.00 0 89989 8543 0 0
t$perf-norm:sp_cancel_write_off 1012 1012 0 0.00 0 4510 25 0 0
t$perf-norm:sp_client_order 20406 20406 0 0.00 0 11468 39 0 0
t$perf-norm:sp_customer_reserve 1059 1059 0 0.00 0 967 16 0 0
t$perf-norm:sp_pay_from_customer 7341 7341 0 0.00 0 6394 20 0 0
t$perf-norm:sp_pay_to_supplier 5942 5942 0 0.00 0 2930 20 0 0
t$perf-norm:sp_reserve_write_off 10178 10178 0 0.00 0 7140 26 0 0
t$perf-norm:sp_supplier_invoice 5603 5603 0 0.00 0 8934 35 0 0
t$perf-norm:sp_supplier_order 5495 5495 0 0.00 0 10704 34 0 0
t$perf-norm:srv_aggregate_perf_data 112 112 0 0.00 0 151 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 74 74 0 0.00 0 1955 49 0 0
t$perf-norm:srv_make_invnt_saldo 504 504 0 0.00 0 4788 30 0 0
t$perf-norm:srv_make_money_saldo 972 972 0 0.00 0 17944 25 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 180 51 0 0
v_add_invoice_to_stock 10250 10122 128 1.24 0 4730 27 0 128
v_all_customers 37702 37702 0 0.00 0 72 4 0 0
v_all_suppliers 31010 31010 0 0.00 0 249 5 0 0
v_all_wares 40806 40806 0 0.00 0 69 4 0 0
v_cancel_adding_invoice 882 882 0 0.00 0 1751 17 0 0
v_cancel_client_order 3630 3630 0 0.00 0 3027 11 0 0
v_cancel_customer_prepayment 1432 1432 0 0.00 0 1469 13 0 0
v_cancel_customer_reserve 1444 1444 0 0.00 0 2728 16 0 0
v_cancel_payment_to_supplier 900 900 0 0.00 0 1007 13 0 0
v_cancel_supplier_invoice 812 804 8 0.98 0 7500 38 0 8
v_cancel_supplier_order 796 796 0 0.00 0 956 13 0 0
v_cancel_write_off 2024 2024 0 0.00 0 2858 30 0 0
v_max_id_clo_ord 5494 5494 0 0.00 0 240 5 0 0
v_max_id_clo_res 1059 1059 0 0.00 0 4406 23 0 0
v_max_id_ord_sup 5592 5592 0 0.00 0 4667 8 0 0
v_max_non_paid_invoice 1523 1523 0 0.00 0 3367 11 0 0
v_max_non_paid_realizn 3762 3762 0 0.00 0 6170 10 0 0
v_min_id_clo_ord 5494 5494 0 0.00 0 1527 10 0 0
v_min_id_clo_res 1059 1059 0 0.00 0 2289 13 0 0
v_min_id_ord_sup 5592 5592 0 0.00 0 3206 9 0 0
v_min_non_paid_invoice 5942 5942 0 0.00 0 7810 18 0 0
v_min_non_paid_realizn 7341 7341 0 0.00 0 10933 19 0 0
v_our_firm 10262 10262 0 0.00 0 62 5 0 0
v_reserve_write_off 20354 20354 0 0.00 0 5257 11 0 0
x_lock_depdocs_on_canc_invoice 10550 10550 0 0.00 0 15217 143 0 0
x_lock_depdocs_on_canc_sup_ord 398 398 0 0.00 0 14667 442 0 0
x_make_qstorno_1000_1200 5495 5495 0 0.00 3 25841 1141 0 0
x_make_qstorno_1200_2000 5603 5603 0 0.00 4 63302 2958 0 0
x_make_qstorno_2100_3300 477613 477613 0 0.00 0 93726 231 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 31-01-2023 13:39:23 to 31-01-2023 13:39:27.

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
11:07:31 963 8192000000 8924191584 8980733952 4611184 0.056 3 1 2 0 4708800 61840 35776
11:08:33 32152 8192000000 9723983408 10023763968 799391904 8.890 100 84 167 0 831672224 12054720 20225600
11:10:08 19160 8192000000 9771298384 10081787904 843860368 9.339 100 85 171 0 875838048 12116992 19860688
11:11:42 12232 8192000000 9806383312 10123665408 872117328 9.621 100 91 182 0 915136032 22423024 20595680
11:13:03 9604 8192000000 9867608880 10200752128 920511312 10.101 100 81 161 0 980098800 42795760 16791728
11:14:23 3827 8192000000 9926051152 10270531584 951918128 10.410 100 81 160 0 1040696288 72089888 16688272
11:15:32 2529 8192000000 9950276256 10300219392 989635536 10.778 100 93 179 1 1063821328 56114016 18071776
11:16:36 1241 8192000000 9988070176 10347327488 1015104256 11.025 100 85 169 0 1113768736 82238416 16426064
11:17:46 1473 8192000000 10025511392 10392702976 1038883872 11.254 100 89 173 0 1141455776 85840384 16731520
11:18:49 784 8192000000 10026528656 10401357824 1059715552 11.454 100 93 177 2 1141869728 64016496 18137680
11:19:52 1060 8192000000 10046656976 10422628352 1077238592 11.621 100 96 188 1 1169614048 73060848 19314608
11:20:56 1025 8192000000 10066696208 10445742080 1080182080 11.649 100 91 174 1 1185859712 88819936 16857696
11:21:59 1205 8192000000 10065157456 10446798848 1082894464 11.675 100 98 194 0 1185451520 83569344 18987712
11:23:06 1014 8192000000 10071543680 10457677824 1087587568 11.720 100 97 188 2 1192291648 86640688 18063392
11:24:09 1056 8192000000 10047177024 10432335872 1092056768 11.762 100 96 180 0 1167600144 56977184 18566192
11:25:12 1118 8192000000 10063572880 10448293888 1090936160 11.752 100 98 180 2 1181069120 72680944 17452016
11:26:15 904 8192000000 10072388880 10463473664 1099012144 11.828 100 96 191 1 1195278656 76249424 20017088
11:27:16 1164 8192000000 10066060032 10460086272 1095548832 11.795 100 87 169 1 1191494336 78821376 17124128
11:28:20 1320 8192000000 10069809152 10458783744 1094715968 11.787 100 99 181 4 1191636640 78867232 18053440
11:29:22 1220 8192000000 10062004256 10452664320 1091009488 11.752 100 96 179 0 1185548336 75767776 18771072
11:30:24 1125 8192000000 10056178464 10444238848 1090459472 11.747 100 86 158 0 1174133552 67211840 16462240
11:31:26 4204 8192000000 10048873648 10436284416 1088446832 11.728 100 73 141 1 1172971712 70933920 13590960
11:32:32 2797 8192000000 10008971216 10393145344 1071087680 11.562 100 75 126 2 1134238880 49876896 13274304
11:33:36 1287 8192000000 10039429760 10421432320 1062051056 11.476 100 88 170 4 1162969488 83584304 17334128
11:34:38 1434 8192000000 10015773280 10389467136 1044249392 11.305 100 92 175 0 1140082032 78782320 17050320
11:35:41 1175 8192000000 10026738640 10406797312 1064883696 11.503 100 98 182 1 1149164192 65175840 19104656
11:36:43 1130 8192000000 9998526176 10370113536 1033891168 11.206 100 96 182 3 1121578528 69404608 18282752
11:37:49 14888 8192000000 9990009920 10363064320 1041344432 11.278 100 80 137 1 1104874752 50823040 12707280
11:39:12 14857 8192000000 10024213984 10409799680 1071933216 11.571 100 74 134 3 1142061072 56339264 13788592
11:40:41 10864 8192000000 10045486736 10434727936 1082955344 11.676 100 59 116 1 1167853904 72745664 12152896
11:41:56 20004 8192000000 10085850048 10485202944 1094678320 11.787 100 46 89 1 1212310144 108011504 9620320
11:43:26 4070 8192000000 10128930352 10522386432 1108452768 11.918 100 65 125 1 1242295520 120145008 13697744
11:44:33 2387 8192000000 10184630592 10575859712 1112251136 11.954 100 84 150 3 1291875648 162827568 16796944
11:45:39 1312 8192000000 10187776896 10587201536 1122373840 12.049 100 91 176 0 1303653888 160846656 20433392
11:46:45 3628 8192000000 10233824560 10630955008 1131770976 12.138 100 72 142 0 1341760272 194508720 15480576
11:47:58 2888 8192000000 10240192032 10651979776 1150456416 12.314 100 89 170 0 1358788160 190280768 18050976
11:49:05 1288 8192000000 10277958368 10679644160 1164837216 12.449 100 89 169 0 1393616240 209672256 19106768
11:50:09 2083 8192000000 10303588176 10708832256 1162922752 12.431 100 88 173 0 1412928640 231100736 18905152
11:51:18 4439 8192000000 10324467792 10728980480 1176598368 12.558 100 75 146 1 1440539024 248020736 15919920
11:52:27 904 8192000000 10357337696 10774953984 1190297632 12.686 100 92 171 0 1469492400 259743312 19451456
11:53:35 1842 8192000000 10381706912 10815717376 1187424912 12.659 100 92 183 0 1487519152 277311616 22782624
11:54:40 1694 8192000000 10391042720 10829377536 1184700512 12.634 100 89 172 1 1502039888 297166096 20173280
11:55:49 897 8192000000 10415036048 10825494528 1188694208 12.671 100 98 186 1 1521650304 311414752 21541344
11:56:52 3496 8192000000 10414315360 10851991552 1191643248 12.699 100 79 154 0 1523307472 313252992 18411232
11:58:01 1623 8192000000 10448997888 10863300608 1188262976 12.667 100 88 169 0 1555588736 347883152 19442608
11:59:07 1050 8192000000 10479805552 10898546688 1187419504 12.659 100 99 186 0 1585020336 375364192 22236640
12:00:15 825 8192000000 10508419520 10914979840 1187581408 12.661 100 98 190 1 1613411664 402290864 23539392
12:01:19 880 8192000000 10522216608 10955939840 1194564912 12.726 100 95 190 1 1624717616 407137440 23015264
12:02:25 1213 8192000000 10542832720 10958217216 1190601264 12.689 100 94 186 0 1649357072 436526672 22229136
12:03:28 7985 8192000000 10511015328 10993778688 1181279712 12.602 100 55 110 1 1621199936 425556400 14363824
12:04:47 8826 8192000000 10490704528 10966368256 1180825472 12.598 100 61 122 2 1598915536 401871120 16218944
12:06:17 6636 8192000000 10433708064 10878738432 1130138304 12.123 98 50 100 0 1515952240 372459088 13354848
12:07:29 5748 8192000000 10440787664 10925899776 1151544112 12.324 99 54 106 0 1544823472 379150576 14128784
12:08:38 18117 8192000000 10300325392 10787082240 1153674736 12.344 100 41 79 2 1419260144 255907216 9678192
12:10:04 9034 8192000000 10265311248 10760548352 1165613424 12.456 100 49 95 1 1406182528 229038080 11531024
12:11:15 3927 8192000000 10292689264 10721943552 1164104528 12.442 100 53 102 0 1394325040 218059216 12161296
12:12:20 22465 8192000000 10343856928 10779951104 1163704944 12.438 100 34 66 0 1437978512 265887712 8385856
12:13:58 35212 8192000000 10280006144 10838196224 1150748304 12.317 100 18 36 0 1444404064 289165120 4490640
12:15:41 32758 8192000000 10107057584 10666168320 1136342560 12.181 99 21 40 0 1221946592 81588288 4015744
12:17:14 54517 8192000000 10087700448 10535596032 1092681200 11.768 96 25 45 2 1157540208 60279984 4579024
12:19:11 10486 8192000000 10124671760 10558623744 1134097408 12.160 100 39 76 0 1213516592 71631024 7788160
12:20:22 100330 8192000000 10068626288 10528854016 1072477184 11.576 94 10 20 0 1101820368 26466832 2876352
12:23:04 35654 8192000000 10058797904 10482200576 1114468400 11.975 99 34 60 1 1153123504 33780544 4874560
12:24:41 39847 8192000000 10107194160 10533654528 1121385712 12.040 99 61 120 0 1179079024 45082864 12610448
12:26:46 22708 8192000000 10086901072 10515755008 1107290928 11.907 98 46 89 1 1169145584 52386048 9468608
12:28:12 2841 8192000000 10082928048 10498301952 1123253280 12.058 100 87 154 5 1173923376 35932480 14737616
12:29:16 77589 8192000000 10093662768 10509484032 1089232496 11.735 95 31 54 1 1151955936 56729504 5993936
12:31:36 71135 8192000000 10173994384 10613452800 1124652352 12.071 98 34 64 0 1247164848 115302464 7210032
12:33:50 23336 8192000000 10218525456 10673332224 1152329968 12.331 100 44 74 2 1327095184 165042768 9722448
12:35:19 52983 8192000000 10222349632 10687021056 1147756688 12.288 100 22 44 0 1332894416 179283904 5853824
12:38:24 24757 8192000000 10168427104 10629296128 1142309264 12.237 100 23 46 0 1259543168 111821360 5412544
12:39:50 62069 8192000000 10102726992 10602545152 1107957360 11.913 97 10 20 0 1170529312 60190608 2381344
12:41:52 40000 8192000000 10089551936 10552786944 1134298784 12.162 100 24 44 0 1192019360 52535952 5184624
12:43:38 28684 8192000000 10141287200 10584678400 1148582976 12.296 100 33 66 0 1227820864 72980592 6257296
12:45:07 7991 8192000000 10082752832 10506731520 1129514560 12.117 100 67 117 4 1170251872 28968720 11768592
12:46:16 59930 8192000000 10097556528 10542006272 1123276240 12.058 98 32 60 0 1211565408 82251728 6037440
12:48:17 84960 8192000000 10088019040 10572582912 1103847024 11.874 96 12 24 0 1153885408 46835760 3202624
12:50:42 158768 8192000000 10050600208 10517561344 1057439280 11.432 92 19 38 0 1106787872 44450272 4898320
12:54:22 54372 8192000000 10082405552 10510807040 1125157648 12.076 99 47 93 0 1165527648 30875216 9494784
12:56:20 35392 8192000000 10105751312 10563420160 1106918384 11.903 98 28 55 0 1218533728 106362672 5252672
12:58:16 50108 8192000000 10067104064 10531635200 1099721440 11.835 98 13 24 1 1183740176 80991664 3027072
13:00:27 79481 8192000000 10053235120 10543579136 1110917264 11.941 99 15 32 0 1173460256 59367328 3175664
13:03:08 62938 8192000000 10058701376 10560446464 1120998080 12.036 99 13 26 0 1170542816 46791136 2753600
13:05:39 24562 8192000000 10061282928 10508455936 1122820352 12.054 99 25 44 0 1156205296 28774368 4610576
13:07:06 26307 8192000000 10106614768 10552156160 1111183024 11.944 98 51 102 1 1187019376 65109344 10727008
13:08:47 41712 8192000000 10098804592 10556854272 1133811536 12.157 100 42 84 0 1205109888 62617664 8680688
13:10:33 21886 8192000000 10086812144 10521509888 1123493920 12.060 99 40 80 0 1173760464 41722592 8543952
13:12:01 15622 8192000000 10104654032 10540900352 1109523408 11.928 99 45 90 0 1200347104 81841024 8982672
13:13:27 76279 8192000000 10064749904 10538983424 1099547904 11.833 98 16 31 0 1149924096 46388416 3987776
13:15:44 12445 8192000000 10081265488 10508775424 1122638128 12.052 100 45 90 1 1162721456 30918480 9164848
13:16:58 83729 8192000000 10102925632 10555154432 1134315664 12.162 100 35 70 1 1225631344 83774752 7540928
13:19:22 727 8192000000 10056004144 10495762432 1121805088 12.044 100 87 162 3 1157544528 16562896 19176544
13:20:23 66480 8192000000 10060113136 10520596480 1110449280 11.937 98 17 34 0 1164956144 50902448 3604416
13:22:59 13886 8192000000 10101321936 10535587840 1138102464 12.198 100 44 83 0 1194277152 46872432 9302256
13:24:15 58817 8192000000 10138716672 10613542912 1143414576 12.248 100 30 60 0 1237174912 86938240 6822096
13:26:36 21653 8192000000 10104918192 10540351488 1081476656 11.662 95 37 72 0 1165142256 75970608 7694992
13:27:58 27537 8192000000 10116653328 10624790528 1134806528 12.167 100 42 84 0 1253905664 110141536 8957600
13:29:27 28047 8192000000 10120228016 10550824960 1100905760 11.846 97 35 65 0 1177415408 69819600 6690048
13:30:57 11170 8192000000 10147812576 10571792384 1135991120 12.178 100 60 117 1 1238172480 89821328 12360032
13:32:15 67012 8192000000 10120015952 10617663488 1134635904 12.165 100 27 54 0 1216191808 75856656 5699248
13:34:55 118193 8192000000 10058868240 10512650240 1119971200 12.027 99 34 66 0 1185231488 58345072 6915216

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 31-01-2023 13:39:29 to 31-01-2023 13:39:46.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 64 v_add_invoice_to_stock 335544517
except 4 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 31-01-2023 13:39:47 to 31-01-2023 13:39:49.

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          220008
MON$OLDEST_ACTIVE               222638
MON$OLDEST_SNAPSHOT             222638
MON$NEXT_TRANSACTION            222638
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                       2068512
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             777
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2894 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2894 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2894 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2894 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 ---
:264: 
:265: mscs-test-161.budzdorov.ru    Tue Jan 31 13:37:30 2023
:266:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:267: 
:268: 
:269: mscs-test-161.budzdorov.ru    Tue Jan 31 13:37:35 2023
:270:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:271: 
--- end of diff output ---

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

31.01.23 13:39:50. 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
2023-01-31 13:39:53 No severe PSQL-related problems occured
31.01.23 13:39:54. End of report.