Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 10.12.2022 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 13978980 kB
MemAvailable 30075084 kB
Buffers 0 kB
Cached 17680248 kB
SwapCached 3400 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 2022-12-10 13:37:22
DTS_END                         2022-12-10 13:37:22.0500
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/59492
TRN_ID                          218772
ATT_ID                          665
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: 9075.70 27612 1089084
customer order: creation 168.19 478 20183
customer order: refuse 14.53 531 1744
order to supplier: creation 45.07 1364 5408
order to supplier: removal 3.22 209005 386
invoice (draft): creation 45.00 3391 5400
invoice (draft): removal 52.06 1096 6247
invoice accept: apply 41.60 26406 4992
invoice accept: cancel 47.29 1452 5675
customer reserve: creation 3785.75 249 454290
customer reserve: removal 4570.18 115 548422
realization accept: apply 83.30 333 9996
realization accept: cancel 91.11 190 10933
payment from customer: creation 58.23 214 6987
payment from customer: removal 5.77 175 692
payment to supplier: creation 48.85 228 5862
payment to supplier: removal 3.27 213 392
service: total inventory turnovers 3.44 5597 413
service: total monetary turnovers 7.98 374 957
service: aggregate perf. data 0.84 18260 101
service: refresh index statistics 0.03 282568 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 10-12-2022 13:38:23 to 10-12-2022 13:38:25.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8103
TEST_TIME 2 5930
TEST_TIME 3 5529
TEST_TIME 4 6042
TEST_TIME 5 6275
TEST_TIME 6 6548
TEST_TIME 7 6784
TEST_TIME 8 7058
TEST_TIME 9 6938
TEST_TIME 10 7113
TEST_TIME 11 7417
TEST_TIME 12 7418
TEST_TIME 13 7322
TEST_TIME 14 7404
TEST_TIME 15 7504
TEST_TIME 16 7462
TEST_TIME 17 7431
TEST_TIME 18 7363
TEST_TIME 19 7471
TEST_TIME 20 7483
TEST_TIME 21 7558
TEST_TIME 22 7759
TEST_TIME 23 7861
TEST_TIME 24 7828
TEST_TIME 25 7932
TEST_TIME 26 8099
TEST_TIME 27 8162
TEST_TIME 28 8202
TEST_TIME 29 8097
TEST_TIME 30 8305
TEST_TIME 31 8229
TEST_TIME 32 8287
TEST_TIME 33 8662
TEST_TIME 34 9179
TEST_TIME 35 9161
TEST_TIME 36 9731
TEST_TIME 37 9964
TEST_TIME 38 9949
TEST_TIME 39 9842
TEST_TIME 40 10068
TEST_TIME 41 10702
TEST_TIME 42 10522
TEST_TIME 43 10616
TEST_TIME 44 10608
TEST_TIME 45 10499
TEST_TIME 46 10678
TEST_TIME 47 10785
TEST_TIME 48 10887
TEST_TIME 49 10745
TEST_TIME 50 10722
TEST_TIME 51 10636
TEST_TIME 52 10560
TEST_TIME 53 10539
TEST_TIME 54 10487
TEST_TIME 55 10368
TEST_TIME 56 10255
TEST_TIME 57 10141
TEST_TIME 58 10025
TEST_TIME 59 10012
TEST_TIME 60 10148
TEST_TIME 61 10087
TEST_TIME 62 9972
TEST_TIME 63 9874
TEST_TIME 64 9807
TEST_TIME 65 9778
TEST_TIME 66 9742
TEST_TIME 67 9679
TEST_TIME 68 9821
TEST_TIME 69 9817
TEST_TIME 70 9855
TEST_TIME 71 9800
TEST_TIME 72 9738
TEST_TIME 73 9692
TEST_TIME 74 9647
TEST_TIME 75 9593
TEST_TIME 76 9521
TEST_TIME 77 9542
TEST_TIME 78 9459
TEST_TIME 79 9457
TEST_TIME 80 9423
TEST_TIME 81 9367
TEST_TIME 82 9350
TEST_TIME 83 9320
TEST_TIME 84 9292
TEST_TIME 85 9214
TEST_TIME 86 9214
TEST_TIME 87 9286
TEST_TIME 88 9254
TEST_TIME 89 9290
TEST_TIME 90 9283
TEST_TIME 91 9325
TEST_TIME 92 9332
TEST_TIME 93 9345
TEST_TIME 94 9303
TEST_TIME 95 9343
TEST_TIME 96 9364
TEST_TIME 97 9413
TEST_TIME 98 9547
TEST_TIME 99 9541
TEST_TIME 100 9487
TEST_TIME 101 9425
TEST_TIME 102 9368
TEST_TIME 103 9398
TEST_TIME 104 9366
TEST_TIME 105 9337
TEST_TIME 106 9295
TEST_TIME 107 9261
TEST_TIME 108 9221
TEST_TIME 109 9200
TEST_TIME 110 9148
TEST_TIME 111 9105
TEST_TIME 112 9144
TEST_TIME 113 9125
TEST_TIME 114 9181
TEST_TIME 115 9147
TEST_TIME 116 9172
TEST_TIME 117 9165
TEST_TIME 118 9146
TEST_TIME 119 9154
TEST_TIME 120 9104

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 10-12-2022 13:38:27 to 10-12-2022 13:38:30.

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 1508950 1508950 0 0.00 0 17359 15 0 0
doc_list_biud 1508939 1508939 0 0.00 0 63245 48 0 0
sp_add_invoice_to_stock 5134 4992 142 2.76 39 191179 26407 0 142
sp_cancel_adding_invoice 5675 5675 0 0.00 6 145639 1453 0 0
sp_cancel_client_order 1744 1744 0 0.00 5 11181 532 0 0
sp_cancel_customer_reserve 548422 548422 0 0.00 1 63254 115 0 0
sp_cancel_pay_from_customer 692 692 0 0.00 4 6862 176 0 0
sp_cancel_pay_to_supplier 392 392 0 0.00 4 7013 214 0 0
sp_cancel_supplier_invoice 6254 6247 7 0.11 3 33700 1097 0 7
sp_cancel_supplier_order 386 386 0 0.00 24 1585206 209006 0 0
sp_cancel_write_off 10933 10933 0 0.00 2 10495 191 0 0
sp_client_order 20183 20183 0 0.00 5 37683 479 0 0
sp_customer_reserve 454290 454290 0 0.00 0 89379 249 0 0
sp_fill_shopping_cart 30983 30983 0 0.00 1 11548 100 0 0
sp_fill_shopping_cart_clo_res 454254 454254 0 0.00 0 21113 20 0 0
sp_get_clo_for_invoice 4943 4943 0 0.00 0 17477 284 0 0
sp_kill_cost_storno 17692 17692 0 0.00 0 10160 89 0 0
sp_kill_qstorno_ret_qs2qd 1114410 1114410 0 0.00 0 63223 48 0 0
sp_kill_qty_storno 588345 588345 0 0.00 0 63243 113 0 0
sp_lock_selected_doc 20608 20608 0 0.00 0 10080 9 0 0
sp_make_cost_storno 27788 27788 0 0.00 0 10535 95 0 0
sp_multiply_rows_for_pdistr 27787 27787 0 0.00 0 8725 45 0 0
sp_multiply_rows_for_qdistr 20181 20181 0 0.00 0 10212 162 0 0
sp_pay_from_customer 6987 6987 0 0.00 5 10981 214 0 0
sp_pay_to_supplier 5862 5862 0 0.00 6 10599 229 0 0
sp_payment_common 12849 12849 0 0.00 2 7040 54 0 0
sp_qd_handle_on_cancel_clo 1744 1744 0 0.00 0 9950 433 0 0
sp_qd_handle_on_invoice_upd_sts 10618 10618 0 0.00 0 21144 640 0 0
sp_qd_handle_on_reserve_upd_sts 9995 9995 0 0.00 0 22277 73 0 0
sp_reserve_write_off 9996 9996 0 0.00 8 22355 334 0 0
sp_supplier_invoice 5400 5400 0 0.00 9 114936 3392 0 0
sp_supplier_order 5408 5408 0 0.00 9 41182 1365 0 0
srv_aggregate_perf_data 101 101 0 0.00 2724 210415 18261 0 0
srv_fill_mon_cache_memory 74 74 0 0.00 631 148025 29952 0 0
srv_find_qd_qs_mism 1041692 1041692 0 0.00 0 17359 10 0 0
srv_make_invnt_saldo 413 413 0 0.00 49 67663 5598 0 0
srv_make_money_saldo 957 957 0 0.00 1 12127 374 0 0
srv_recalc_idx_stat 4 4 0 0.00 29057 451573 282569 0 0
t$perf-abend:sp_add_invoice_to_stock 142 142 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5003 5003 0 0.00 1 24313 1175 0 0
t$perf-norm:sp_cancel_adding_invoice 408 408 0 0.00 1 9301 648 0 0
t$perf-norm:sp_cancel_client_order 1744 1744 0 0.00 0 2145 36 0 0
t$perf-norm:sp_cancel_customer_reserve 665 665 0 0.00 0 2348 37 0 0
t$perf-norm:sp_cancel_pay_from_customer 692 692 0 0.00 0 914 20 0 0
t$perf-norm:sp_cancel_pay_to_supplier 392 392 0 0.00 0 3352 26 0 0
t$perf-norm:sp_cancel_supplier_invoice 405 405 0 0.00 0 1967 50 0 0
t$perf-norm:sp_cancel_supplier_order 386 386 0 0.00 0 134146 10103 0 0
t$perf-norm:sp_cancel_write_off 980 980 0 0.00 0 3740 23 0 0
t$perf-norm:sp_client_order 20183 20183 0 0.00 0 7165 43 0 0
t$perf-norm:sp_customer_reserve 1016 1016 0 0.00 0 854 17 0 0
t$perf-norm:sp_pay_from_customer 6987 6987 0 0.00 0 3188 21 0 0
t$perf-norm:sp_pay_to_supplier 5862 5862 0 0.00 0 2821 22 0 0
t$perf-norm:sp_reserve_write_off 9996 9996 0 0.00 0 3565 28 0 0
t$perf-norm:sp_supplier_invoice 5401 5401 0 0.00 0 2524 34 0 0
t$perf-norm:sp_supplier_order 5408 5408 0 0.00 0 8433 41 0 0
t$perf-norm:srv_aggregate_perf_data 101 101 0 0.00 0 214 17 0 0
t$perf-norm:srv_fill_mon_cache_memory 74 74 0 0.00 0 1236 25 0 0
t$perf-norm:srv_make_invnt_saldo 413 413 0 0.00 0 579 16 0 0
t$perf-norm:srv_make_money_saldo 957 957 0 0.00 0 693 8 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 8 217 71 0 0
v_add_invoice_to_stock 10165 9881 284 2.79 0 6628 33 0 284
v_all_customers 37492 37492 0 0.00 0 146 5 0 0
v_all_suppliers 30491 30491 0 0.00 0 132 5 0 0
v_all_wares 40356 40356 0 0.00 0 73 5 0 0
v_cancel_adding_invoice 816 816 0 0.00 0 4579 33 0 0
v_cancel_client_order 3488 3488 0 0.00 0 5303 15 0 0
v_cancel_customer_prepayment 1384 1384 0 0.00 0 5184 26 0 0
v_cancel_customer_reserve 1330 1330 0 0.00 0 4739 19 0 0
v_cancel_payment_to_supplier 784 784 0 0.00 0 2436 22 0 0
v_cancel_supplier_invoice 824 810 14 1.69 0 2207 31 0 14
v_cancel_supplier_order 772 772 0 0.00 0 2060 19 0 0
v_cancel_write_off 1960 1960 0 0.00 0 1610 28 0 0
v_max_id_clo_ord 5405 5405 0 0.00 0 2445 7 0 0
v_max_id_clo_res 1016 1016 0 0.00 0 2379 17 0 0
v_max_id_ord_sup 5398 5398 0 0.00 0 5844 12 0 0
v_max_non_paid_invoice 1419 1419 0 0.00 0 70 10 0 0
v_max_non_paid_realizn 3437 3437 0 0.00 0 6205 11 0 0
v_min_id_clo_ord 5405 5405 0 0.00 0 2637 12 0 0
v_min_id_clo_res 1016 1016 0 0.00 0 2603 13 0 0
v_min_id_ord_sup 5398 5398 0 0.00 0 4082 9 0 0
v_min_non_paid_invoice 5862 5862 0 0.00 0 3426 14 0 0
v_min_non_paid_realizn 6987 6987 0 0.00 0 7011 20 0 0
v_our_firm 9960 9960 0 0.00 0 119 6 0 0
v_reserve_write_off 19990 19990 0 0.00 0 5834 15 0 0
x_lock_depdocs_on_canc_invoice 11517 11517 0 0.00 0 13502 134 0 0
x_lock_depdocs_on_canc_sup_ord 386 386 0 0.00 0 9552 416 0 0
x_make_qstorno_1000_1200 5408 5408 0 0.00 2 41130 1143 0 0
x_make_qstorno_1200_2000 5400 5400 0 0.00 3 112348 3140 0 0
x_make_qstorno_2100_3300 454290 454290 0 0.00 0 89375 214 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 10-12-2022 13:38:31 to 10-12-2022 13:38:35.

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:25 1 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
11:08:26 27404 8192000000 9725608384 10028019712 812476032 9.023 100 88 176 0 840878672 7036624 21366016
11:09:57 20570 8192000000 9771737056 10080378880 844540880 9.345 100 88 176 0 877216416 11528592 21146944
11:11:26 13726 8192000000 9803909792 10126184448 871236784 9.612 100 81 160 0 914092480 23933264 18922432
11:12:42 7380 8192000000 9854504320 10188845056 918488304 10.081 100 75 150 0 967975424 33233152 16253968
11:14:01 4057 8192000000 9926331680 10271641600 960218960 10.491 100 82 161 0 1039859056 63539184 16100912
11:15:08 2673 8192000000 9969946048 10321948672 985557328 10.738 100 90 171 0 1087530080 84889392 17083360
11:16:14 1298 8192000000 9990520944 10351755264 1011224880 10.987 100 91 175 0 1106861536 77858656 17778000
11:17:20 1468 8192000000 10017816464 10389880832 1042919136 11.293 100 93 180 2 1143523632 82509024 18095472
11:18:24 1153 8192000000 10008467504 10387660800 1051649824 11.376 100 93 182 1 1128587120 58891120 18046176
11:19:28 1110 8192000000 10032199120 10407743488 1060300528 11.459 100 96 188 0 1146107616 67152336 18654752
11:20:30 1649 8192000000 10037879264 10422890496 1068625520 11.539 100 91 170 3 1161695584 76511600 16558464
11:21:37 1041 8192000000 10051808736 10433323008 1075972336 11.609 100 95 177 0 1169552000 75623680 17955984
11:22:38 1091 8192000000 10043972544 10429714432 1082924560 11.675 100 99 182 1 1164837840 64213200 17700080
11:23:42 4310 8192000000 10046583104 10442391552 1085404400 11.699 100 66 128 2 1175868160 77454112 13009648
11:24:49 1054 8192000000 10043296720 10433179648 1097794576 11.817 100 90 169 3 1169572096 55531552 16245968
11:25:50 1088 8192000000 10062036752 10449043456 1094924816 11.789 100 92 176 2 1183233920 69877408 18431696
11:26:53 1114 8192000000 10073746720 10464817152 1091914416 11.761 100 99 190 4 1196981456 86192080 18874960
11:27:56 1160 8192000000 10079102144 10472050688 1102702608 11.863 100 98 186 2 1198824320 77552816 18568896
11:28:58 1351 8192000000 10059600624 10448461824 1093876720 11.780 100 95 184 0 1181662544 69395168 18390656
11:30:01 1016 8192000000 10022120832 10406567936 1063701392 11.492 99 82 153 1 1145390576 66338496 15350688
11:31:03 1021 8192000000 10047664944 10431451136 1077272528 11.621 100 92 180 0 1168149024 73065808 17810688
11:32:05 2381 8192000000 10006393872 10394218496 1057939280 11.437 99 75 149 0 1125488416 52440608 15108528
11:33:09 1070 8192000000 10050193504 10435948544 1070530320 11.557 100 94 177 0 1169009520 80329168 18150032
11:34:11 1083 8192000000 10039507136 10417803264 1068306464 11.536 100 95 181 0 1159697584 72520896 18870224
11:35:13 2684 8192000000 10003254800 10387161088 1054415408 11.403 100 80 151 1 1129787616 58534768 16837440
11:36:18 1107 8192000000 10007760736 10385530880 1043983696 11.303 100 84 163 1 1130921696 70399632 16538368
11:37:21 1144 8192000000 10003177440 10379587584 1058121808 11.439 100 94 173 4 1121881424 47166464 16593152
11:38:37 1106 8192000000 10010405072 10388996096 1051326912 11.373 99 77 143 2 1127538528 61840512 14371104
11:39:45 26591 8192000000 10050147024 10443100160 1086739152 11.712 100 64 128 0 1157860480 57639584 13481744
11:41:17 4609 8192000000 10083336848 10470649856 1092801040 11.769 100 75 143 0 1204193632 96620112 14772480
11:42:27 1375 8192000000 10099414224 10483257344 1106775424 11.902 100 94 180 0 1215299088 90868576 17655088
11:43:33 1801 8192000000 10137056320 10534174720 1125159328 12.076 100 74 139 1 1257835968 119158336 13518304
11:44:39 1108 8192000000 10138521488 10530906112 1130824928 12.129 100 87 168 3 1253710144 105133504 17751712
11:45:43 1117 8192000000 10175552320 10575548416 1140390096 12.219 100 86 171 2 1289837392 130640288 18807008
11:46:49 3391 8192000000 10206398656 10605404160 1152307936 12.331 100 97 188 2 1316537200 144309552 19919712
11:47:55 1599 8192000000 10213845856 10615975936 1150173824 12.311 100 90 166 0 1325881024 158276656 17430544
11:49:00 950 8192000000 10246648080 10649939968 1164855696 12.449 100 90 174 1 1355615760 171574240 19185824
11:50:05 1586 8192000000 10252233984 10655989760 1159977904 12.403 100 89 171 0 1363783504 185063936 18741664
11:51:09 3432 8192000000 10299925376 10702843904 1168190832 12.480 100 82 162 1 1407631824 220107296 19333696
11:52:20 1127 8192000000 10305997200 10721546240 1177529536 12.567 100 86 166 0 1419995840 224078992 18387312
11:53:23 1233 8192000000 10337014656 10746875904 1186414160 12.650 100 98 188 0 1443654656 236375424 20865072
11:54:29 1214 8192000000 10396859872 10807185408 1196737456 12.746 100 94 185 1 1509137488 290444288 21955744
11:55:35 1954 8192000000 10415519296 10843578368 1193578352 12.717 100 94 181 1 1526925440 313368448 19978640
11:56:41 1220 8192000000 10435729376 10846531584 1192541520 12.707 100 97 183 1 1547467888 332204640 22721728
11:57:47 2735 8192000000 10487153328 10903060480 1184585216 12.633 99 94 188 1 1591117504 384341696 22190592
11:58:54 948 8192000000 10479368656 10928058368 1207373456 12.845 100 97 191 2 1586775040 357015440 22386144
11:59:58 913 8192000000 10511983024 10942304256 1201180272 12.787 100 95 185 1 1618263392 395546480 21536640
12:01:02 953 8192000000 10532820752 10953175040 1205197344 12.825 100 94 177 3 1640056400 413997520 20861536
12:02:08 5578 8192000000 10544192976 10986246144 1192174896 12.704 100 74 148 1 1662391168 451962288 18253984
12:03:18 1172 8192000000 10554273184 10998525952 1186921616 12.655 100 79 158 0 1657879616 451177888 19780112
12:04:23 3521 8192000000 10559541136 11007213568 1186340752 12.649 100 54 105 0 1666478848 466954448 13183648
12:05:32 5889 8192000000 10585804304 11013492736 1187540832 12.660 100 63 122 1 1685849360 482991168 15317360
12:06:55 3204 8192000000 10612158368 11078557696 1196418736 12.743 100 75 142 4 1711223296 496929296 17875264
12:08:19 3912 8192000000 10638925616 11095556096 1191625984 12.698 100 71 139 1 1741635312 532113456 17895872
12:09:28 6776 8192000000 10709442736 11144933376 1191643248 12.699 100 62 124 0 1814211856 606383856 16184752
12:10:45 19423 8192000000 10598873696 11153489920 1191981872 12.702 100 49 96 0 1717996384 512820384 13194128
12:12:24 11244 8192000000 10534085264 10998538240 1174821696 12.542 100 39 76 0 1647854480 463202480 9830304
12:13:44 8514 8192000000 10397936432 10929987584 1167109632 12.470 100 31 62 0 1509052288 334292752 7649904
12:15:04 16098 8192000000 10329544864 10789949440 1139409264 12.210 98 35 66 0 1417509408 269870816 8229328
12:16:23 1254 8192000000 10303865264 10810716160 1155572112 12.362 100 84 161 6 1400369936 224678768 20119056
12:17:27 782 8192000000 10259962000 10784800768 1159571424 12.399 100 96 186 1 1356810352 175348224 21890704
12:18:29 3308 8192000000 10252215568 10759692288 1159462944 12.398 100 74 145 0 1352556016 176135296 16957776
12:19:34 3191 8192000000 10235806592 10665353216 1160681504 12.410 100 62 124 1 1333291344 157214240 15395600
12:20:40 31962 8192000000 10276772160 10778775552 1163311984 12.434 100 24 46 0 1369890672 200851488 5727200
12:23:30 48938 8192000000 10210106432 10688323584 1141013200 12.225 99 18 36 0 1341401632 195854112 4534320
12:25:21 148024 8192000000 10081035664 10553327616 1060665664 11.463 93 22 44 0 1096721104 31639264 4416176
12:28:58 106788 8192000000 10072335536 10536914944 1056987776 11.428 94 19 39 0 1105485104 44394064 4103264
12:31:55 29275 8192000000 10067811728 10490871808 1113024096 11.961 99 40 68 0 1140887808 19339536 8524176
12:33:29 90464 8192000000 10163465936 10621825024 1124173232 12.066 99 38 76 0 1198275888 66027248 8075408
12:37:11 66214 8192000000 10118189520 10546491392 1138278784 12.199 100 32 60 0 1187810736 43397520 6134432
12:39:20 60000 8192000000 10140661376 10584965120 1159308944 12.397 100 42 82 0 1223936592 55494192 9133456
12:41:29 626 8192000000 10171658896 10591657984 1156693712 12.372 100 97 188 0 1261175152 82070992 22410448
12:42:32 68349 8192000000 10142424624 10602790912 1123413952 12.059 97 36 70 0 1203917328 73040544 7462832
12:44:43 45867 8192000000 10177051728 10677784576 1117841104 12.007 98 36 72 1 1284395920 157526112 9028704
12:46:32 61829 8192000000 10097912192 10563596288 1118920400 12.017 99 12 24 0 1188362800 66949520 2492880
12:48:35 85969 8192000000 10083575184 10542821376 1121462432 12.041 98 20 37 1 1167245264 41769840 4012992
12:51:24 63113 8192000000 10073972016 10557423616 1128186896 12.104 99 11 22 0 1181481664 50688016 2606752
12:53:34 87880 8192000000 10070559984 10557743104 1137851600 12.195 100 17 34 0 1195151152 53497184 3802368
12:56:06 42150 8192000000 10117517968 10599923712 1146505888 12.277 100 45 87 1 1204683616 49553776 8623952
12:58:06 74643 8192000000 10081552032 10554712064 1136941920 12.187 99 12 22 0 1187066880 47721296 2403664
13:00:27 66332 8192000000 10074348560 10580566016 1132613232 12.146 99 18 36 0 1174408528 37999872 3795424
13:02:37 54532 8192000000 10122793312 10576502784 1104012416 11.876 96 26 50 0 1179464080 69730416 5721248
13:04:42 28304 8192000000 10038824768 10490306560 1078761648 11.636 96 20 37 0 1115019648 33239536 3018464
13:06:14 11001 8192000000 10099624176 10529075200 1116632400 11.995 100 44 79 0 1197722784 73172192 7918192
13:07:27 9358 8192000000 10063895840 10496737280 1122889392 12.054 100 46 91 1 1158095200 26241072 8964736
13:08:37 55482 8192000000 10044662416 10488987648 1095240656 11.792 97 18 36 1 1146805072 48252544 3311872
13:10:34 46335 8192000000 10110076560 10548781056 1086845760 11.713 96 25 50 1 1162421712 70540256 5035696
13:12:24 57577 8192000000 10171248032 10644676608 1139482608 12.211 100 20 40 1 1250423152 106441824 4498720
13:14:27 60461 8192000000 10145287152 10621706240 1122658192 12.052 99 13 26 0 1256682192 131108288 2915712
13:16:48 18000 8192000000 10063658608 10502795264 1102305792 11.860 98 47 89 0 1142718208 30858496 9553920
13:18:19 79968 8192000000 10082226080 10540601344 1101686848 11.854 98 33 62 0 1169132128 61293376 6151904
13:20:40 54041 8192000000 10065075696 10540695552 1112034128 11.952 99 19 38 0 1168029968 51928736 4067104
13:22:43 36815 8192000000 10035788144 10486312960 1127749536 12.100 100 29 55 0 1148932224 14707424 6475264
13:24:24 60189 8192000000 10091273376 10538938368 1117469792 12.003 99 20 40 1 1173247856 50859152 4918912
13:26:29 80952 8192000000 10113434880 10578370560 1127010448 12.093 99 21 40 0 1195047280 63615088 4421744
13:29:12 56099 8192000000 10109562416 10603479040 1129960192 12.121 99 12 22 0 1199620560 66741632 2918736
13:31:18 85448 8192000000 10087839072 10539520000 1098147424 11.820 97 10 20 0 1134241344 34356992 1736928
13:33:45 53781 8192000000 10079206912 10556108800 1136192848 12.180 100 19 36 0 1203419904 63683264 3543792
13:35:40 20626 8192000000 10100721328 10534420480 1119803136 12.025 99 46 88 0 1179198608 50755920 8639552
13:37:02 12738 8192000000 10130292000 10570182656 1132755632 12.147 99 46 93 0 1219493696 77474096 9263968

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 10-12-2022 13:38:37 to 10-12-2022 13:38:54.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 10-12-2022 13:38:55 to 10-12-2022 13:38:57.

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          215019
MON$OLDEST_ACTIVE               219786
MON$OLDEST_SNAPSHOT             219786
MON$NEXT_TRANSACTION            219786
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                       2159520
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             770
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2884 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2884 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2884 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2884 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 ---
:24: 
:25: mscs-test-161.budzdorov.ru    Sat Dec 10 13:37:24 2022
:26:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:27: 
:28: 
:29: mscs-test-161.budzdorov.ru    Sat Dec 10 13:37:24 2022
:30:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:31: 
:32: 
:33: mscs-test-161.budzdorov.ru    Sat Dec 10 13:37:32 2022
:34:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:35: 
--- end of diff output ---

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

10.12.22 13:38:58. 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-12-10 13:39:01 No severe PSQL-related problems occured
10.12.22 13:39:02. End of report.