Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 25.01.2023 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 15047632 kB
MemAvailable 30366324 kB
Buffers 20 kB
Cached 16591864 kB
SwapCached 1552 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-25 04:37:27
DTS_END                         2023-01-25 04:37:27.2390
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(134:18) ==> SP_CANCEL_SUPPLIER_INVOICE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/50156
TRN_ID                          210386
ATT_ID                          593
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: 8501.83 21461 1020220
customer order: creation 161.75 514 19410
customer order: refuse 13.93 452 1671
order to supplier: creation 43.17 1472 5180
order to supplier: removal 3.28 178884 394
invoice (draft): creation 43.88 3558 5266
invoice (draft): removal 48.09 1146 5771
invoice accept: apply 38.81 28563 4657
invoice accept: cancel 42.71 1373 5125
customer reserve: creation 3603.88 270 432465
customer reserve: removal 4232.21 108 507865
realization accept: apply 79.58 298 9549
realization accept: cancel 68.53 182 8224
payment from customer: creation 55.73 187 6688
payment from customer: removal 5.35 151 642
payment to supplier: creation 45.53 195 5464
payment to supplier: removal 3.36 155 403
service: total inventory turnovers 3.98 3945 477
service: total monetary turnovers 7.17 411 860
service: aggregate perf. data 0.87 15964 104
service: refresh index statistics 0.04 191385 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 25-01-2023 04:38:49 to 25-01-2023 04:38:51.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9259
TEST_TIME 2 7193
TEST_TIME 3 6515
TEST_TIME 4 6008
TEST_TIME 5 5853
TEST_TIME 6 6015
TEST_TIME 7 5740
TEST_TIME 8 6048
TEST_TIME 9 6206
TEST_TIME 10 6035
TEST_TIME 11 6222
TEST_TIME 12 6136
TEST_TIME 13 6078
TEST_TIME 14 6049
TEST_TIME 15 6435
TEST_TIME 16 6501
TEST_TIME 17 6860
TEST_TIME 18 6728
TEST_TIME 19 7223
TEST_TIME 20 7218
TEST_TIME 21 7529
TEST_TIME 22 7865
TEST_TIME 23 7848
TEST_TIME 24 8059
TEST_TIME 25 8260
TEST_TIME 26 8517
TEST_TIME 27 8550
TEST_TIME 28 8574
TEST_TIME 29 8798
TEST_TIME 30 8849
TEST_TIME 31 9213
TEST_TIME 32 9211
TEST_TIME 33 9416
TEST_TIME 34 9528
TEST_TIME 35 10140
TEST_TIME 36 10019
TEST_TIME 37 10367
TEST_TIME 38 10225
TEST_TIME 39 10231
TEST_TIME 40 10065
TEST_TIME 41 9884
TEST_TIME 42 9766
TEST_TIME 43 9721
TEST_TIME 44 9704
TEST_TIME 45 9895
TEST_TIME 46 9774
TEST_TIME 47 9686
TEST_TIME 48 9565
TEST_TIME 49 9541
TEST_TIME 50 9487
TEST_TIME 51 9377
TEST_TIME 52 9316
TEST_TIME 53 9337
TEST_TIME 54 9271
TEST_TIME 55 9243
TEST_TIME 56 9221
TEST_TIME 57 9121
TEST_TIME 58 9044
TEST_TIME 59 9030
TEST_TIME 60 9032
TEST_TIME 61 8965
TEST_TIME 62 8869
TEST_TIME 63 8882
TEST_TIME 64 9025
TEST_TIME 65 9020
TEST_TIME 66 8980
TEST_TIME 67 8942
TEST_TIME 68 8970
TEST_TIME 69 8995
TEST_TIME 70 9018
TEST_TIME 71 9066
TEST_TIME 72 9017
TEST_TIME 73 9058
TEST_TIME 74 9057
TEST_TIME 75 9029
TEST_TIME 76 8985
TEST_TIME 77 8998
TEST_TIME 78 9013
TEST_TIME 79 8912
TEST_TIME 80 8924
TEST_TIME 81 8901
TEST_TIME 82 8818
TEST_TIME 83 8801
TEST_TIME 84 8816
TEST_TIME 85 8803
TEST_TIME 86 8768
TEST_TIME 87 8787
TEST_TIME 88 8710
TEST_TIME 89 8746
TEST_TIME 90 8712
TEST_TIME 91 8704
TEST_TIME 92 8713
TEST_TIME 93 8742
TEST_TIME 94 8739
TEST_TIME 95 8723
TEST_TIME 96 8783
TEST_TIME 97 8774
TEST_TIME 98 8756
TEST_TIME 99 8806
TEST_TIME 100 8819
TEST_TIME 101 8842
TEST_TIME 102 8857
TEST_TIME 103 8808
TEST_TIME 104 8757
TEST_TIME 105 8774
TEST_TIME 106 8711
TEST_TIME 107 8716
TEST_TIME 108 8729
TEST_TIME 109 8689
TEST_TIME 110 8662
TEST_TIME 111 8619
TEST_TIME 112 8610
TEST_TIME 113 8585
TEST_TIME 114 8574
TEST_TIME 115 8601
TEST_TIME 116 8571
TEST_TIME 117 8569
TEST_TIME 118 8600
TEST_TIME 119 8572
TEST_TIME 120 8526

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 25-01-2023 04:38:53 to 25-01-2023 04:38:56.

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 1419421 1419421 0 0.00 0 19194 14 0 0
doc_list_biud 1419400 1419400 0 0.00 0 67927 45 0 0
sp_add_invoice_to_stock 4769 4657 112 2.34 51 256385 28563 0 112
sp_cancel_adding_invoice 5125 5125 0 0.00 6 79962 1374 0 0
sp_cancel_client_order 1671 1671 0 0.00 6 9356 452 0 0
sp_cancel_customer_reserve 507865 507865 0 0.00 1 67929 109 0 0
sp_cancel_pay_from_customer 642 642 0 0.00 4 7787 151 0 0
sp_cancel_pay_to_supplier 403 403 0 0.00 4 5488 156 0 0
sp_cancel_supplier_invoice 5780 5771 9 0.15 3 22717 1146 0 9
sp_cancel_supplier_order 394 394 0 0.00 7 1607078 178884 0 0
sp_cancel_write_off 8224 8224 0 0.00 2 5866 182 0 0
sp_client_order 19410 19410 0 0.00 5 35902 514 0 0
sp_customer_reserve 432465 432465 0 0.00 1 51631 271 0 0
sp_fill_shopping_cart 29843 29843 0 0.00 1 34876 91 0 0
sp_fill_shopping_cart_clo_res 432418 432418 0 0.00 0 20055 22 0 0
sp_get_clo_for_invoice 4607 4607 0 0.00 0 39783 341 0 0
sp_kill_cost_storno 14394 14394 0 0.00 0 8542 89 0 0
sp_kill_qstorno_ret_qs2qd 1030119 1030119 0 0.00 0 67923 48 0 0
sp_kill_qty_storno 543205 543205 0 0.00 0 67927 110 0 0
sp_lock_selected_doc 19593 19593 0 0.00 0 12604 9 0 0
sp_make_cost_storno 26307 26307 0 0.00 0 19187 83 0 0
sp_multiply_rows_for_pdistr 26307 26307 0 0.00 0 12191 42 0 0
sp_multiply_rows_for_qdistr 19410 19410 0 0.00 0 35857 205 0 0
sp_pay_from_customer 6688 6688 0 0.00 5 18414 187 0 0
sp_pay_to_supplier 5464 5464 0 0.00 7 12288 196 0 0
sp_payment_common 12152 12152 0 0.00 2 11671 47 0 0
sp_qd_handle_on_cancel_clo 1671 1671 0 0.00 1 9342 373 0 0
sp_qd_handle_on_invoice_upd_sts 9731 9731 0 0.00 0 41548 559 0 0
sp_qd_handle_on_reserve_upd_sts 9549 9549 0 0.00 0 21032 70 0 0
sp_reserve_write_off 9549 9549 0 0.00 8 21068 299 0 0
sp_supplier_invoice 5266 5266 0 0.00 8 140074 3558 0 0
sp_supplier_order 5180 5180 0 0.00 8 47294 1472 0 0
srv_aggregate_perf_data 104 104 0 0.00 2298 153140 15965 0 0
srv_fill_mon_cache_memory 72 72 0 0.00 817 133773 34386 0 0
srv_find_qd_qs_mism 974738 974738 0 0.00 0 11384 11 0 0
srv_make_invnt_saldo 477 477 0 0.00 56 95281 3945 0 0
srv_make_money_saldo 860 860 0 0.00 1 14935 411 0 0
srv_recalc_idx_stat 5 5 0 0.00 28577 358239 191386 0 0
t$perf-abend:sp_add_invoice_to_stock 112 112 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 4662 4662 0 0.00 1 17050 1066 0 0
t$perf-norm:sp_cancel_adding_invoice 383 383 0 0.00 2 17653 627 0 0
t$perf-norm:sp_cancel_client_order 1671 1671 0 0.00 0 2343 32 0 0
t$perf-norm:sp_cancel_customer_reserve 635 635 0 0.00 0 1080 28 0 0
t$perf-norm:sp_cancel_pay_from_customer 642 642 0 0.00 0 1471 22 0 0
t$perf-norm:sp_cancel_pay_to_supplier 403 403 0 0.00 0 523 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 392 392 0 0.00 0 1004 42 0 0
t$perf-norm:sp_cancel_supplier_order 394 394 0 0.00 0 90999 9057 0 0
t$perf-norm:sp_cancel_write_off 924 924 0 0.00 0 1009 18 0 0
t$perf-norm:sp_client_order 19410 19410 0 0.00 0 17384 43 0 0
t$perf-norm:sp_customer_reserve 953 953 0 0.00 0 2305 21 0 0
t$perf-norm:sp_pay_from_customer 6688 6688 0 0.00 0 3519 19 0 0
t$perf-norm:sp_pay_to_supplier 5465 5465 0 0.00 0 12695 27 0 0
t$perf-norm:sp_reserve_write_off 9549 9549 0 0.00 0 18099 27 0 0
t$perf-norm:sp_supplier_invoice 5266 5266 0 0.00 0 5134 34 0 0
t$perf-norm:sp_supplier_order 5180 5180 0 0.00 0 3394 33 0 0
t$perf-norm:srv_aggregate_perf_data 104 104 0 0.00 0 413 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 72 72 0 0.00 0 1937 38 0 0
t$perf-norm:srv_make_invnt_saldo 477 477 0 0.00 0 922 19 0 0
t$perf-norm:srv_make_money_saldo 860 860 0 0.00 0 1536 8 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 5 186 70 0 0
v_add_invoice_to_stock 9426 9202 224 2.37 0 14748 29 0 224
v_all_customers 35180 35180 0 0.00 0 79 4 0 0
v_all_suppliers 29084 29084 0 0.00 0 143 4 0 0
v_all_wares 38813 38813 0 0.00 0 123 4 0 0
v_cancel_adding_invoice 766 766 0 0.00 0 792 17 0 0
v_cancel_client_order 3342 3342 0 0.00 0 3021 13 0 0
v_cancel_customer_prepayment 1284 1284 0 0.00 0 3132 17 0 0
v_cancel_customer_reserve 1270 1270 0 0.00 0 1903 15 0 0
v_cancel_payment_to_supplier 806 806 0 0.00 0 1778 17 0 0
v_cancel_supplier_invoice 802 784 18 2.24 0 16042 46 0 18
v_cancel_supplier_order 788 788 0 0.00 0 1095 13 0 0
v_cancel_write_off 1848 1848 0 0.00 0 2325 24 0 0
v_max_id_clo_ord 5176 5176 0 0.00 0 2120 6 0 0
v_max_id_clo_res 953 953 0 0.00 0 6265 17 0 0
v_max_id_ord_sup 5260 5260 0 0.00 0 6426 11 0 0
v_max_non_paid_invoice 1358 1358 0 0.00 0 1847 10 0 0
v_max_non_paid_realizn 3593 3593 0 0.00 0 4047 9 0 0
v_min_id_clo_ord 5176 5176 0 0.00 0 1413 8 0 0
v_min_id_clo_res 953 953 0 0.00 0 2516 14 0 0
v_min_id_ord_sup 5260 5260 0 0.00 0 7897 14 0 0
v_min_non_paid_invoice 5463 5463 0 0.00 0 6232 12 0 0
v_min_non_paid_realizn 6688 6688 0 0.00 0 11667 23 0 0
v_our_firm 9822 9822 0 0.00 0 143 5 0 0
v_reserve_write_off 19096 19096 0 0.00 0 12209 11 0 0
x_lock_depdocs_on_canc_invoice 10504 10504 0 0.00 0 29545 126 0 0
x_lock_depdocs_on_canc_sup_ord 394 394 0 0.00 0 3926 371 0 0
x_make_qstorno_1000_1200 5180 5180 0 0.00 3 47281 1297 0 0
x_make_qstorno_1200_2000 5266 5266 0 0.00 3 132641 3294 0 0
x_make_qstorno_2100_3300 432465 432465 0 0.00 0 46128 235 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 25-01-2023 04:38:57 to 25-01-2023 04:39:01.

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:30 913 8192000000 8930218304 8986243072 18845024 0.229 4 3 6 0 19701488 250016 606448
02:08:32 23923 8192000000 9707111680 10003705856 789620288 8.791 100 89 178 1 818547680 7362496 21564896
02:10:10 15192 8192000000 9760971904 10069196800 835976512 9.259 100 91 182 0 869106048 11751296 21378240
02:11:27 14678 8192000000 9791846880 10104426496 859344240 9.494 100 84 168 0 899124160 19559728 20220192
02:12:47 9141 8192000000 9837871280 10165071872 894572320 9.844 100 80 156 0 946901040 35258928 17069792
02:14:04 5989 8192000000 9892095472 10234998784 943415008 10.327 100 82 160 0 1012230640 51848992 16966640
02:15:14 2330 8192000000 9937033840 10287300608 978749568 10.672 100 82 161 0 1057480736 62773840 15957328
02:16:19 1085 8192000000 9985082320 10342436864 1008805584 10.964 100 94 189 1 1098726800 71157216 18764000
02:17:23 1028 8192000000 10002149312 10367406080 1030609088 11.174 100 91 172 2 1126378976 77194112 18575776
02:18:26 907 8192000000 10034361424 10404556800 1046799856 11.330 100 93 181 1 1154482512 89228336 18454320
02:19:28 985 8192000000 10036439904 10410188800 1057687040 11.434 100 97 192 1 1160225472 83422000 19116432
02:20:31 1952 8192000000 10040819504 10416377856 1067452464 11.528 100 86 165 1 1158958464 76342608 15163392
02:21:34 2823 8192000000 10046329872 10421989376 1067675504 11.530 100 91 168 3 1157766672 74501232 15589936
02:22:38 1148 8192000000 10051947600 10435047424 1072197728 11.573 100 95 180 1 1168602352 78098736 18305888
02:23:41 5224 8192000000 10042398656 10426429440 1078720784 11.635 100 78 149 2 1171122832 78474512 13927536
02:24:48 987 8192000000 10046595040 10430414848 1086930384 11.713 100 84 164 1 1168344000 65179392 16234224
02:25:50 1045 8192000000 10062151104 10445967360 1090004304 11.743 100 90 178 0 1188840368 81261648 17574416
02:26:52 7796 8192000000 10046867632 10438656000 1090439040 11.747 100 66 122 3 1165638992 63058160 12141792
02:28:02 5845 8192000000 10041684432 10432163840 1090826384 11.751 100 66 126 1 1163600752 59821680 12952688
02:29:09 1954 8192000000 10045930304 10435506176 1091580560 11.758 100 74 141 1 1161481632 56256464 13644608
02:30:13 1091 8192000000 10044701584 10424733696 1077523936 11.624 100 96 182 1 1164862960 68276368 19062656
02:31:18 1048 8192000000 10017995024 10398703616 1069412736 11.546 100 96 179 3 1139212368 51780144 18019488
02:32:20 1128 8192000000 10047703024 10416050176 1053219936 11.392 99 97 190 1 1166293824 94180672 18893216
02:33:23 999 8192000000 10002515376 10383638528 1050330944 11.364 100 73 135 1 1126568112 62949808 13287360
02:34:25 974 8192000000 10002513424 10379096064 1048308544 11.344 100 94 183 1 1123096016 55740064 19047408
02:35:27 1156 8192000000 9970518464 10343931904 1026875296 11.138 100 79 148 0 1090392832 48072320 15445216
02:36:29 1327 8192000000 9963050384 10330738688 1010680672 10.982 99 88 166 2 1084715776 58419184 15615920
02:37:32 1339 8192000000 10010835648 10379898880 1047068928 11.333 99 91 173 5 1122128368 57542000 17517440
02:38:39 24309 8192000000 10022996096 10410967040 1068277504 11.536 100 68 134 0 1159829840 78000576 13551760
02:40:14 2172 8192000000 10028551856 10418135040 1086107648 11.706 100 71 129 3 1153209952 54195440 12906864
02:41:21 4128 8192000000 10090858128 10478727168 1101028256 11.847 100 70 132 2 1203343968 89437184 12878528
02:42:30 8740 8192000000 10119751568 10520424448 1111953408 11.951 100 59 114 1 1241927120 117384192 12589520
02:43:47 27408 8192000000 10154480688 10558406656 1121746496 12.043 99 57 106 2 1265294448 131377248 12170704
02:45:19 1740 8192000000 10208943472 10619822080 1142344768 12.238 100 77 150 1 1315830240 157438656 16046816
02:46:25 4871 8192000000 10220084128 10620731392 1158800464 12.392 100 73 138 3 1331664544 158320496 14543584
02:47:33 1194 8192000000 10270568784 10665422848 1167889840 12.477 100 92 176 1 1384004864 197189936 18925088
02:48:37 2711 8192000000 10278017616 10682220544 1169059696 12.488 100 84 159 3 1393408560 206834608 17514256
02:49:52 2053 8192000000 10331505488 10733113344 1171706784 12.513 100 87 168 0 1447604848 255925216 19972848
02:51:01 1198 8192000000 10390471904 10795433984 1191112176 12.694 100 89 171 1 1499883296 289764464 19006656
02:52:06 990 8192000000 10424579040 10840244224 1196875600 12.747 100 93 184 0 1534214752 316076640 21262512
02:53:10 1196 8192000000 10431443392 10856034304 1195154208 12.731 100 92 180 1 1540756928 325177952 20424768
02:54:15 844 8192000000 10440831856 10879594496 1193313120 12.714 100 99 191 1 1552243504 336254992 22675392
02:55:21 1219 8192000000 10423522032 10870956032 1186948160 12.655 100 92 174 0 1541147888 333318272 20881456
02:56:30 1265 8192000000 10424728304 10866274304 1177117632 12.563 99 95 186 0 1529006624 330090672 21798320
02:57:36 871 8192000000 10461628208 10906898432 1187185168 12.657 100 92 179 3 1567288784 358477984 21625632
02:58:41 900 8192000000 10418216864 10897428480 1184007648 12.628 100 83 161 0 1524210320 321182480 19020192
02:59:46 2646 8192000000 10428992816 10874544128 1181795760 12.607 100 73 144 0 1541663616 342742928 17124928
03:00:53 806 8192000000 10457278944 10882224128 1183729920 12.625 100 93 182 2 1564862096 358965360 22166816
03:01:58 2006 8192000000 10429414608 10883063808 1186123984 12.647 100 71 137 0 1533757856 330488768 17145104
03:03:04 1623 8192000000 10407147248 10870337536 1179637456 12.587 100 76 146 0 1513631344 315542320 18451568
03:04:10 1899 8192000000 10410105616 10834014208 1173543152 12.530 100 93 175 4 1510511888 316440208 20528528
03:05:14 3485 8192000000 10432716112 10854739968 1169290448 12.490 100 94 190 0 1531809328 340142752 22376128
03:06:19 9400 8192000000 10426887920 10890792960 1175359536 12.547 100 49 98 0 1528901600 341223968 12318096
03:07:37 13281 8192000000 10390495840 10894958592 1164229248 12.443 99 45 89 0 1488415536 313101104 11085184
03:08:53 12445 8192000000 10396470736 10828505088 1170784736 12.504 100 50 94 1 1494694672 312747728 11162208
03:10:16 15137 8192000000 10328716208 10823929856 1147557984 12.287 99 24 48 1 1447199232 293309728 6331520
03:11:32 11487 8192000000 10294939536 10774958080 1153082272 12.338 100 27 54 0 1412398560 252646464 6669824
03:12:47 65516 8192000000 10182373120 10649886720 1096412688 11.804 95 24 44 0 1247235200 145725456 5097056
03:14:54 22484 8192000000 10158277792 10580844544 1136966192 12.187 99 59 108 2 1246468432 96642544 12859696
03:16:19 50081 8192000000 10160721952 10593337344 1147839872 12.289 100 62 111 2 1264718416 103866896 13011648
03:18:13 11580 8192000000 10158642736 10590752768 1151137744 12.320 100 69 132 4 1250833984 86785216 12911024
03:19:26 23169 8192000000 10200921472 10642317312 1156217088 12.368 100 44 86 0 1309253392 143484528 9551776
03:20:51 63563 8192000000 10143280384 10622046208 1102180704 11.858 96 14 24 0 1208795936 103765408 2849824
03:22:55 32751 8192000000 10053392864 10526879744 1132096096 12.141 100 20 36 1 1173963920 38097856 3769968
03:24:30 55119 8192000000 10063087744 10514812928 1092931712 11.771 96 20 40 0 1133337312 36335968 4069632
03:26:29 31292 8192000000 10080468320 10541596672 1124370688 12.068 99 18 36 0 1157619184 29373136 3875360
03:29:16 98998 8192000000 10111572784 10599534592 1125730432 12.081 99 12 24 0 1204356144 75356288 3269424
03:32:55 93988 8192000000 10101471616 10609315840 1118490704 12.013 98 25 48 0 1182587200 59299968 4796528
03:35:31 29758 8192000000 10119673728 10570723328 1127792816 12.101 98 36 71 1 1197683280 61439888 8450576
03:37:02 59412 8192000000 10105702400 10578055168 1138997088 12.206 99 16 32 0 1244720032 102268976 3453968
03:39:03 95965 8192000000 10138917120 10640683008 1143311280 12.247 99 15 29 0 1234449024 87914368 3223376
03:41:46 55918 8192000000 10072040496 10532937728 1132636800 12.146 99 12 23 0 1207076064 72175712 2263552
03:43:44 21794 8192000000 10093562128 10529071104 1143616272 12.250 100 63 121 1 1187057344 30283248 13157824
03:45:07 19874 8192000000 10152123856 10599047168 1148217616 12.293 99 42 80 0 1246187120 88822544 9146960
03:46:31 74780 8192000000 10072838400 10530803712 1052365712 11.383 92 16 32 0 1144976784 89316224 3294848
03:48:48 69982 8192000000 10084432576 10528468992 1077918256 11.628 95 28 56 0 1133641360 50015664 5707440
03:51:05 99068 8192000000 10077400912 10557485056 1080096128 11.648 96 29 55 0 1121492624 36054096 5342400
03:53:50 49119 8192000000 10073639712 10509676544 1117106048 12.000 100 19 37 2 1169490896 49224768 3160080
03:55:41 96301 8192000000 10098313808 10551955456 1129349680 12.115 100 39 78 0 1217989376 80424752 8214944
03:58:24 44449 8192000000 10072871264 10500837376 1112475904 11.956 98 42 78 1 1156762784 36582160 7704720
04:00:15 19170 8192000000 10134135040 10558353408 1125258944 12.077 99 49 95 0 1219626624 84477328 9890352
04:01:38 56595 8192000000 10092840464 10548137984 1106391744 11.898 98 23 44 0 1172602736 61775584 4435408
04:03:36 47428 8192000000 10086486512 10534055936 1078713472 11.635 96 16 32 0 1161893584 79812464 3367648
04:06:25 44992 8192000000 10030367728 10476175360 1087612640 11.720 98 16 32 0 1138744816 47432928 3699248
04:08:11 42531 8192000000 10082811216 10517704704 1099404880 11.832 97 25 50 0 1151447248 47025872 5016496
04:09:54 65533 8192000000 10096281840 10533740544 1127767760 12.100 99 32 64 0 1179394544 43976496 7650288
04:12:00 37431 8192000000 10136931856 10582134784 1139550144 12.211 100 26 51 1 1212202704 67444256 5208304
04:13:47 44072 8192000000 10088517360 10539728896 1129061616 12.113 99 42 80 1 1180680064 43569296 8049152
04:15:31 58135 8192000000 10110760304 10564157440 1101684240 11.854 97 36 69 0 1221490560 112873296 6933024
04:17:31 57228 8192000000 10092622640 10540814336 1093166048 11.773 97 44 86 1 1148744384 46942544 8635792
04:19:33 68070 8192000000 10119103456 10557112320 1143759600 12.251 100 30 57 2 1199882240 50161280 5961360
04:21:48 133773 8192000000 10146723504 10615939072 1143197584 12.246 100 45 88 0 1244713872 92349712 9166576
04:25:12 67520 8192000000 10070242208 10571104256 1126001312 12.084 98 10 20 0 1195504176 67330960 2171904
04:27:23 88828 8192000000 10076458336 10545848320 1127600320 12.099 98 14 28 0 1161112272 30237248 3274704
04:29:53 74640 8192000000 10070276288 10510295040 1065415408 11.508 93 17 34 0 1126067760 57460480 3191872
04:32:10 80542 8192000000 10084151216 10519437312 1085598688 11.701 95 30 60 0 1174379584 81988928 6791968
04:34:31 38634 8192000000 10135262576 10571120640 1114196176 11.972 98 27 54 0 1207195504 86953264 6046064
04:36:13 5374 8192000000 10136202336 10559131648 1145244208 12.265 100 67 126 0 1232859680 74546128 13069344
04:37:25 16599 8192000000 9166303056 9500872704 299297664 3.524 26 5 10 0 343754736 43304640 1152432

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 25-01-2023 04:39:03 to 25-01-2023 04:39:20.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 112 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 25-01-2023 04:39:21 to 25-01-2023 04:39:23.

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          212378
MON$OLDEST_ACTIVE               212379
MON$OLDEST_SNAPSHOT             212379
MON$NEXT_TRANSACTION            212379
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                       2061536
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             761
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2891 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2891 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2891 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2891 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 ---
:57: 
:58: mscs-test-161.budzdorov.ru    Wed Jan 25 04:37:44 2023
:59:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:60: 
:61: 
:62: mscs-test-161.budzdorov.ru    Wed Jan 25 04:38:12 2023
:63:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:64: 
--- end of diff output ---

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

25.01.23 04:39:24. 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-25 04:39:26 No severe PSQL-related problems occured
25.01.23 04:39:28. End of report.