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

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-07-31 10:37:15
DTS_END                         2022-07-31 10:37:15.8630
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/46874
TRN_ID                          226764
ATT_ID                          674
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: 8968.84 25778 1076261
customer order: creation 171.82 423 20618
customer order: refuse 15.15 392 1818
order to supplier: creation 45.74 1239 5489
order to supplier: removal 3.38 156555 405
invoice (draft): creation 46.17 2600 5540
invoice (draft): removal 49.52 1096 5942
invoice accept: apply 42.32 25413 5078
invoice accept: cancel 44.84 1033 5381
customer reserve: creation 3904.68 245 468562
customer reserve: removal 4347.30 94 521676
realization accept: apply 84.39 260 10127
realization accept: cancel 80.99 158 9719
payment from customer: creation 61.38 166 7366
payment from customer: removal 5.63 138 675
payment to supplier: creation 50.33 182 6039
payment to supplier: removal 3.40 119 408
service: total inventory turnovers 3.98 3332 477
service: total monetary turnovers 6.97 363 836
service: aggregate perf. data 0.84 15359 101
service: refresh index statistics 0.03 306398 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 31-07-2022 10:40:14 to 31-07-2022 10:40:16.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8270
TEST_TIME 2 6913
TEST_TIME 3 7255
TEST_TIME 4 7695
TEST_TIME 5 6865
TEST_TIME 6 6513
TEST_TIME 7 6640
TEST_TIME 8 6630
TEST_TIME 9 6666
TEST_TIME 10 6860
TEST_TIME 11 6706
TEST_TIME 12 6666
TEST_TIME 13 6656
TEST_TIME 14 6642
TEST_TIME 15 6691
TEST_TIME 16 6769
TEST_TIME 17 6803
TEST_TIME 18 6815
TEST_TIME 19 7283
TEST_TIME 20 7524
TEST_TIME 21 7544
TEST_TIME 22 7623
TEST_TIME 23 8076
TEST_TIME 24 8728
TEST_TIME 25 9085
TEST_TIME 26 9221
TEST_TIME 27 9721
TEST_TIME 28 9808
TEST_TIME 29 9843
TEST_TIME 30 10480
TEST_TIME 31 10375
TEST_TIME 32 10756
TEST_TIME 33 10743
TEST_TIME 34 10885
TEST_TIME 35 10809
TEST_TIME 36 10766
TEST_TIME 37 10583
TEST_TIME 38 10635
TEST_TIME 39 10516
TEST_TIME 40 10487
TEST_TIME 41 10380
TEST_TIME 42 10588
TEST_TIME 43 10421
TEST_TIME 44 10272
TEST_TIME 45 10130
TEST_TIME 46 10090
TEST_TIME 47 9939
TEST_TIME 48 9964
TEST_TIME 49 9879
TEST_TIME 50 9878
TEST_TIME 51 9841
TEST_TIME 52 9791
TEST_TIME 53 9746
TEST_TIME 54 9707
TEST_TIME 55 9794
TEST_TIME 56 9730
TEST_TIME 57 9782
TEST_TIME 58 9815
TEST_TIME 59 9754
TEST_TIME 60 9703
TEST_TIME 61 9696
TEST_TIME 62 9628
TEST_TIME 63 9666
TEST_TIME 64 9626
TEST_TIME 65 9618
TEST_TIME 66 9736
TEST_TIME 67 9721
TEST_TIME 68 9690
TEST_TIME 69 9681
TEST_TIME 70 9692
TEST_TIME 71 9630
TEST_TIME 72 9553
TEST_TIME 73 9478
TEST_TIME 74 9448
TEST_TIME 75 9453
TEST_TIME 76 9371
TEST_TIME 77 9317
TEST_TIME 78 9328
TEST_TIME 79 9283
TEST_TIME 80 9245
TEST_TIME 81 9215
TEST_TIME 82 9228
TEST_TIME 83 9179
TEST_TIME 84 9158
TEST_TIME 85 9233
TEST_TIME 86 9243
TEST_TIME 87 9237
TEST_TIME 88 9227
TEST_TIME 89 9263
TEST_TIME 90 9265
TEST_TIME 91 9349
TEST_TIME 92 9338
TEST_TIME 93 9327
TEST_TIME 94 9261
TEST_TIME 95 9252
TEST_TIME 96 9274
TEST_TIME 97 9211
TEST_TIME 98 9131
TEST_TIME 99 9182
TEST_TIME 100 9146
TEST_TIME 101 9173
TEST_TIME 102 9147
TEST_TIME 103 9140
TEST_TIME 104 9148
TEST_TIME 105 9182
TEST_TIME 106 9158
TEST_TIME 107 9187
TEST_TIME 108 9198
TEST_TIME 109 9173
TEST_TIME 110 9172
TEST_TIME 111 9185
TEST_TIME 112 9202
TEST_TIME 113 9279
TEST_TIME 114 9286
TEST_TIME 115 9222
TEST_TIME 116 9175
TEST_TIME 117 9132
TEST_TIME 118 9092
TEST_TIME 119 9054
TEST_TIME 120 8998

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 31-07-2022 10:40:18 to 31-07-2022 10:40:21.

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 1508559 1508559 0 0.00 0 21650 13 0 0
doc_list_biud 1508548 1508548 0 0.00 0 42236 39 0 0
sp_add_invoice_to_stock 5196 5078 118 2.27 65 262320 25414 0 118
sp_cancel_adding_invoice 5381 5381 0 0.00 7 145546 1034 0 0
sp_cancel_client_order 1818 1818 0 0.00 5 27148 393 0 0
sp_cancel_customer_reserve 521676 521676 0 0.00 1 37151 94 0 0
sp_cancel_pay_from_customer 675 675 0 0.00 3 6184 138 0 0
sp_cancel_pay_to_supplier 408 408 0 0.00 3 3764 120 0 0
sp_cancel_supplier_invoice 5948 5942 6 0.10 2 35603 1096 0 6
sp_cancel_supplier_order 405 405 0 0.00 14 1371105 156555 0 0
sp_cancel_write_off 9719 9719 0 0.00 2 28263 158 0 0
sp_client_order 20618 20618 0 0.00 5 29940 424 0 0
sp_customer_reserve 468562 468562 0 0.00 0 124684 246 0 0
sp_fill_shopping_cart 31638 31638 0 0.00 1 9018 67 0 0
sp_fill_shopping_cart_clo_res 468526 468526 0 0.00 0 14062 18 0 0
sp_get_clo_for_invoice 5027 5027 0 0.00 0 31277 283 0 0
sp_kill_cost_storno 16183 16183 0 0.00 0 7898 67 0 0
sp_kill_qstorno_ret_qs2qd 1059418 1059418 0 0.00 0 35593 43 0 0
sp_kill_qty_storno 560093 560093 0 0.00 0 42235 97 0 0
sp_lock_selected_doc 20968 20968 0 0.00 0 8492 8 0 0
sp_make_cost_storno 28557 28557 0 0.00 0 21645 73 0 0
sp_multiply_rows_for_pdistr 28557 28557 0 0.00 0 9754 40 0 0
sp_multiply_rows_for_qdistr 20618 20618 0 0.00 0 20241 171 0 0
sp_pay_from_customer 7366 7366 0 0.00 5 17999 167 0 0
sp_pay_to_supplier 6039 6039 0 0.00 6 21675 183 0 0
sp_payment_common 13405 13405 0 0.00 2 17380 46 0 0
sp_qd_handle_on_cancel_clo 1818 1818 0 0.00 0 27138 326 0 0
sp_qd_handle_on_invoice_upd_sts 10406 10406 0 0.00 0 42235 456 0 0
sp_qd_handle_on_reserve_upd_sts 10127 10127 0 0.00 0 22593 57 0 0
sp_reserve_write_off 10127 10127 0 0.00 8 22626 260 0 0
sp_supplier_invoice 5540 5540 0 0.00 10 77031 2601 0 0
sp_supplier_order 5489 5489 0 0.00 7 45818 1240 0 0
srv_aggregate_perf_data 101 101 0 0.00 2740 121038 15359 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 689 149278 37387 0 0
srv_find_qd_qs_mism 1027092 1027092 0 0.00 0 13586 11 0 0
srv_make_invnt_saldo 477 477 0 0.00 73 47667 3333 0 0
srv_make_money_saldo 836 836 0 0.00 1 8429 364 0 0
srv_recalc_idx_stat 4 4 0 0.00 170703 469127 306399 0 0
t$perf-abend:sp_add_invoice_to_stock 118 118 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5082 5082 0 0.00 1 14910 742 0 0
t$perf-norm:sp_cancel_adding_invoice 379 379 0 0.00 1 4275 341 0 0
t$perf-norm:sp_cancel_client_order 1818 1818 0 0.00 0 3706 28 0 0
t$perf-norm:sp_cancel_customer_reserve 689 689 0 0.00 0 1688 27 0 0
t$perf-norm:sp_cancel_pay_from_customer 675 675 0 0.00 0 855 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 408 408 0 0.00 0 1387 16 0 0
t$perf-norm:sp_cancel_supplier_invoice 428 428 0 0.00 0 1035 36 0 0
t$perf-norm:sp_cancel_supplier_order 405 405 0 0.00 0 95427 5461 0 0
t$perf-norm:sp_cancel_write_off 1015 1015 0 0.00 0 2637 18 0 0
t$perf-norm:sp_client_order 20618 20618 0 0.00 0 7228 31 0 0
t$perf-norm:sp_customer_reserve 1007 1007 0 0.00 0 1447 13 0 0
t$perf-norm:sp_pay_from_customer 7366 7366 0 0.00 0 5793 18 0 0
t$perf-norm:sp_pay_to_supplier 6039 6039 0 0.00 0 3611 16 0 0
t$perf-norm:sp_reserve_write_off 10127 10127 0 0.00 0 6664 22 0 0
t$perf-norm:sp_supplier_invoice 5540 5540 0 0.00 0 3822 26 0 0
t$perf-norm:sp_supplier_order 5489 5489 0 0.00 0 4404 28 0 0
t$perf-norm:srv_aggregate_perf_data 101 101 0 0.00 0 268 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 60 3 0 0
t$perf-norm:srv_make_invnt_saldo 477 477 0 0.00 0 606 15 0 0
t$perf-norm:srv_make_money_saldo 836 836 0 0.00 0 675 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 11 181 56 0 0
v_add_invoice_to_stock 10284 10048 236 2.29 0 5243 22 0 236
v_all_customers 37984 37984 0 0.00 0 104 3 0 0
v_all_suppliers 30942 30942 0 0.00 0 101 4 0 0
v_all_wares 41236 41236 0 0.00 0 144 3 0 0
v_cancel_adding_invoice 758 758 0 0.00 0 1984 14 0 0
v_cancel_client_order 3636 3636 0 0.00 0 2450 10 0 0
v_cancel_customer_prepayment 1350 1350 0 0.00 0 3111 20 0 0
v_cancel_customer_reserve 1378 1378 0 0.00 0 1830 11 0 0
v_cancel_payment_to_supplier 816 816 0 0.00 0 2826 14 0 0
v_cancel_supplier_invoice 868 856 12 1.38 0 3614 36 0 12
v_cancel_supplier_order 810 810 0 0.00 0 2490 17 0 0
v_cancel_write_off 2030 2030 0 0.00 0 3825 26 0 0
v_max_id_clo_ord 5486 5486 0 0.00 0 249 4 0 0
v_max_id_clo_res 1007 1007 0 0.00 0 1697 13 0 0
v_max_id_ord_sup 5533 5533 0 0.00 0 3362 5 0 0
v_max_non_paid_invoice 1587 1587 0 0.00 0 73 7 0 0
v_max_non_paid_realizn 3832 3832 0 0.00 0 5757 10 0 0
v_min_id_clo_ord 5486 5486 0 0.00 0 3387 7 0 0
v_min_id_clo_res 1007 1007 0 0.00 0 1352 10 0 0
v_min_id_ord_sup 5533 5533 0 0.00 0 5254 6 0 0
v_min_non_paid_invoice 6039 6039 0 0.00 0 5598 17 0 0
v_min_non_paid_realizn 7366 7366 0 0.00 0 17368 23 0 0
v_our_firm 10320 10320 0 0.00 0 146 4 0 0
v_reserve_write_off 20254 20254 0 0.00 0 3221 9 0 0
x_lock_depdocs_on_canc_invoice 10895 10895 0 0.00 0 14853 117 0 0
x_lock_depdocs_on_canc_sup_ord 405 405 0 0.00 0 15344 336 0 0
x_make_qstorno_1000_1200 5489 5489 0 0.00 2 45694 1096 0 0
x_make_qstorno_1200_2000 5540 5540 0 0.00 4 76865 2419 0 0
x_make_qstorno_2100_3300 468560 468560 0 0.00 0 124655 217 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 31-07-2022 10:40:22 to 31-07-2022 10:40:26.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
08:07:19 3 8192000000 8798973472 8810385408 4473440 0.054 2 1 2 0 4572832 63616 35776
08:08:20 41573 8192000000 9731716848 10034741248 804665824 8.944 100 89 178 0 834026064 7925088 21435152
08:10:05 18718 8192000000 9782685152 10093694976 852085776 9.421 100 91 182 0 886103200 12766096 21251328
08:11:38 12556 8192000000 9828314560 10149384192 887466048 9.774 100 83 161 0 930418208 25155408 17796752
08:12:55 4298 8192000000 9868179952 10203836416 932768080 10.222 100 85 170 0 988763520 38295088 17700352
08:14:06 3084 8192000000 9932724320 10277621760 954342688 10.434 100 87 172 0 1050074512 77813680 17918144
08:15:12 2998 8192000000 9959278592 10318405632 994509200 10.825 100 88 171 1 1084113248 72284416 17319632
08:16:16 1242 8192000000 9996916576 10361008128 1023629584 11.107 100 94 180 0 1117976752 76384128 17963040
08:17:20 997 8192000000 10005878848 10372964352 1047308352 11.335 100 98 189 1 1126636352 61010656 18317344
08:18:23 1495 8192000000 10029391568 10407497728 1066992960 11.523 100 86 171 0 1149525888 65173376 17359552
08:19:26 1064 8192000000 10049382864 10429341696 1074316752 11.593 100 98 188 0 1170574192 77196336 19061104
08:20:28 1056 8192000000 10071279184 10454392832 1077603936 11.625 100 95 185 3 1195026848 97957552 19465360
08:21:30 2238 8192000000 10049999392 10437308416 1079057472 11.638 100 82 161 1 1174752176 79777072 15917632
08:22:33 995 8192000000 10045296976 10432475136 1090342208 11.746 100 90 172 1 1169548640 60644400 18562032
08:23:35 1042 8192000000 10062502160 10449707008 1092545968 11.767 100 97 184 1 1183128768 70055856 20526944
08:24:37 1316 8192000000 10042541488 10430918656 1096234208 11.802 100 87 163 3 1158110304 46009600 15866496
08:25:40 1114 8192000000 10076104032 10464546816 1101483152 11.852 100 94 187 1 1199292528 79185904 18623472
08:26:42 2094 8192000000 10062262960 10450915328 1090863824 11.751 99 77 144 0 1180443136 75114112 14465200
08:27:44 1245 8192000000 10058017120 10446163968 1095652736 11.796 100 97 180 0 1179116432 66038512 17425184
08:28:46 1338 8192000000 10030376080 10420035584 1094433120 11.785 100 88 156 3 1153091264 43366528 15291616
08:29:48 2353 8192000000 10041435568 10429595648 1088054576 11.724 100 75 137 0 1161337472 60144720 13138176
08:30:52 964 8192000000 10006617520 10383028224 1048655392 11.348 99 95 187 3 1128057744 59545424 19856928
08:31:53 1011 8192000000 10019982064 10400186368 1049581952 11.357 100 92 173 3 1142568912 75041504 17945456
08:32:55 2180 8192000000 9976484704 10354716672 1037452576 11.240 100 70 132 0 1101135840 50705984 12977280
08:33:58 1298 8192000000 9947391760 10319937536 1010707984 10.982 99 81 153 1 1071547248 46234288 14604976
08:35:01 1054 8192000000 9990565184 10362212352 1029263088 11.161 100 92 180 0 1112834352 65530672 18040592
08:36:03 1133 8192000000 9983937776 10353496064 1019792944 11.070 99 90 164 2 1100339664 64853584 15693136
08:37:05 927 8192000000 9991956096 10371506176 1048844048 11.350 100 76 145 3 1112344048 47849120 15650880
08:38:12 23361 8192000000 10006036976 10399023104 1055605136 11.414 100 60 111 3 1130963808 64167552 11191120
08:39:43 7906 8192000000 10049373744 10443112448 1073567744 11.586 100 65 123 1 1169875584 83727728 12580112
08:40:56 8958 8192000000 10057237040 10453508096 1085012272 11.695 100 66 130 0 1166181600 68049904 13119424
08:42:12 2393 8192000000 10092074256 10475945984 1105112256 11.886 100 80 149 0 1194590432 72851696 16626480
08:43:23 9210 8192000000 10115750736 10516422656 1124511952 12.070 100 64 126 1 1246582384 108995024 13075408
08:44:44 2383 8192000000 10165759152 10562252800 1150278256 12.312 100 79 148 0 1276160704 110267760 15614688
08:45:51 2479 8192000000 10226762528 10628542464 1167892944 12.477 100 90 173 0 1341649600 154662320 19094336
08:46:57 1875 8192000000 10264696464 10675802112 1175142800 12.545 100 88 172 1 1375988128 181678096 19167232
08:48:05 730 8192000000 10308326208 10708127744 1195641184 12.736 100 93 180 1 1418069152 202129968 20298000
08:49:10 2160 8192000000 10340837360 10739511296 1197423392 12.752 100 93 182 2 1445975952 228073488 20479072
08:50:15 675 8192000000 10395435312 10798866432 1210788128 12.876 100 98 189 1 1504880160 272875856 21216176
08:51:19 1529 8192000000 10411156528 10812588032 1186969312 12.655 99 88 171 0 1523634368 316755008 19910048
08:52:23 1381 8192000000 10476161952 10888937472 1203238240 12.806 100 85 155 0 1585058880 363434496 18386144
08:53:29 1540 8192000000 10520106176 10934599680 1204707024 12.820 100 88 167 1 1633224560 408495792 20021744
08:54:32 882 8192000000 10558146688 10963587072 1191456464 12.697 100 97 188 1 1661440816 447129024 22855328
08:55:36 862 8192000000 10566344752 10986221568 1200529952 12.781 100 98 193 1 1673589328 448582304 24477072
08:56:40 2801 8192000000 10593637744 11021721600 1197812720 12.756 100 78 154 1 1697148208 480179664 19155824
08:57:50 9456 8192000000 10540099696 10993926144 1193560864 12.716 100 54 105 0 1649250272 442271920 13417488
08:59:24 8061 8192000000 10554487568 11006119936 1168210240 12.480 100 55 106 0 1663964768 480900416 14854112
09:00:36 10375 8192000000 10479507696 10958868480 1152859744 12.336 98 47 92 1 1612101904 446828448 12413712
09:01:55 20203 8192000000 10403200400 10857697280 1145321920 12.266 99 37 74 2 1506286192 351545664 9418608
09:03:19 11493 8192000000 10365240800 10828165120 1147797600 12.289 99 39 78 0 1468765744 311403072 9565072
09:04:34 20236 8192000000 10262102000 10722869248 1147329104 12.284 100 46 91 1 1370489072 212015760 11144208
09:05:56 27660 8192000000 10300445568 10742509568 1140729664 12.222 99 34 68 0 1394899920 245930576 8239680
09:07:31 25624 8192000000 10199141168 10696232960 1149108000 12.301 100 36 70 0 1318037408 160460736 8468672
09:09:00 25074 8192000000 10154867856 10650615808 1127666480 12.099 99 29 53 2 1284911456 149891872 7353104
09:10:36 72219 8192000000 10094044256 10598334464 1119969360 12.027 98 8 16 0 1192557648 70689488 1898800
09:12:48 25528 8192000000 10078170384 10505093120 1115873648 11.988 98 27 53 0 1138475680 16917120 5684912
09:14:25 26689 8192000000 10076828080 10503098368 1123769312 12.063 100 37 55 3 1175619712 46555200 5295200
09:15:53 32406 8192000000 10073406976 10510647296 1116736400 11.996 99 19 38 1 1155140240 33953840 4450000
09:17:26 94791 8192000000 10134024592 10576711680 1087418032 11.718 96 22 44 0 1151522160 59813504 4290624
09:20:25 134692 8192000000 10107105152 10563706880 1137610128 12.193 100 28 52 0 1203260288 60237840 5412320
09:23:42 71083 8192000000 10110658592 10548043776 1136120144 12.179 99 29 48 1 1206060048 65199136 4740768
09:25:55 45688 8192000000 10106580784 10546073600 1108007424 11.914 97 21 42 0 1186109168 73533152 4568592
09:27:41 10373 8192000000 10149598720 10574000128 1145101712 12.263 99 72 131 4 1235142992 74442048 15599232
09:28:53 33651 8192000000 10175532736 10639163392 1133353360 12.153 98 19 38 0 1250987440 113016656 4617424
09:30:41 106861 8192000000 10148485440 10616008704 1134386080 12.163 98 12 22 0 1265667440 128754896 2526464
09:33:34 87365 8192000000 10085469328 10573656064 1111790368 11.949 98 14 28 0 1179068864 64364144 2914352
09:36:07 46974 8192000000 10066840432 10519539712 1120037504 12.027 99 9 18 0 1154459600 32883376 1538720
09:37:59 21161 8192000000 10058476032 10492067840 1109765280 11.930 99 51 100 0 1156982576 36416736 10800560
09:39:22 19402 8192000000 10096558064 10523742208 1115634688 11.986 99 54 97 0 1176339136 51345024 9359424
09:40:41 52906 8192000000 10123575232 10585628672 1092434272 11.766 97 28 56 0 1193047520 94803776 5809472
09:42:35 51647 8192000000 10078648800 10579640320 1121182528 12.038 100 15 30 0 1235326560 110566496 3577536
09:44:27 27195 8192000000 10058858256 10483474432 1098164576 11.820 98 40 78 1 1127012896 21043824 7804496
09:45:55 32518 8192000000 10131700880 10567053312 1116503136 11.994 100 48 96 0 1193089856 67154128 9432592
09:47:28 24902 8192000000 10098657648 10521501696 1119802832 12.025 100 46 92 1 1191227744 62587616 8837296
09:48:53 106797 8192000000 10062791600 10510004224 1104573728 11.881 99 43 84 0 1174049712 60835632 8640352
09:51:40 14781 8192000000 10100413056 10549858304 1125528176 12.079 100 42 84 0 1207039824 72756848 8754800
09:52:59 60435 8192000000 10077270176 10520592384 1114144256 11.972 98 15 30 0 1170470672 53201712 3124704
09:55:07 63655 8192000000 10061123632 10536222720 1099041712 11.829 97 16 32 0 1159035360 56340400 3653248
09:57:15 34879 8192000000 10073295632 10529513472 1100083856 11.838 98 37 69 3 1177765984 69939520 7742608
09:59:08 78432 8192000000 10086590800 10531110912 1106727040 11.901 99 23 46 0 1171144144 59508000 4909104
10:01:33 22008 8192000000 10141952048 10586742784 1108089936 11.914 99 31 62 0 1220552688 105604272 6858480
10:03:15 45673 8192000000 10097765952 10532446208 1096595152 11.805 97 26 50 0 1167554400 65551184 5408064
10:05:05 48746 8192000000 10161611680 10615848960 1113406736 11.965 98 37 74 0 1244169168 123157856 7604576
10:07:20 66994 8192000000 10157077152 10619125760 1108249296 11.916 98 16 32 0 1231973104 119669952 4053856
10:09:37 1750 8192000000 10069711504 10492940288 1120815440 12.035 100 64 122 3 1161420720 27624640 12980640
10:10:42 125338 8192000000 10160896416 10635620352 1133819904 12.157 100 30 60 0 1245869152 105214192 6835056
10:14:54 97768 8192000000 10070910080 10631090176 1117800768 12.006 99 13 25 0 1212671680 91827648 3043264
10:17:32 62031 8192000000 10074235008 10534772736 1125384176 12.078 99 19 35 0 1171906080 43282864 3239040
10:20:02 44394 8192000000 10103994928 10550439936 1141991280 12.234 100 30 60 0 1203214112 54844576 6378256
10:21:50 65346 8192000000 10067214464 10544168960 1104164768 11.877 97 19 38 0 1155672880 46618160 4889952
10:24:02 33623 8192000000 10083341968 10519367680 1081936608 11.666 96 27 55 0 1130123856 42984144 5203104
10:25:41 34168 8192000000 10074445440 10503204864 1091729552 11.759 97 39 76 0 1142260288 43099040 7431696
10:27:18 16465 8192000000 10113843552 10549665792 1126726640 12.090 100 51 99 0 1201731040 65394768 9609632
10:28:35 21424 8192000000 10119016848 10548637696 1102297824 11.859 97 50 99 0 1188027776 76197424 9532528
10:29:59 149277 8192000000 10043343088 10558144512 1042355152 11.287 93 15 30 0 1106817088 61689648 2772288
10:34:21 85474 8192000000 10059911008 10513408000 1095979792 11.799 98 11 22 0 1132436224 33624416 2832016
10:36:47 52634 8192000000 9414414672 9791909888 549161344 6.282 49 5 10 0 591560784 41123312 1276128

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 31-07-2022 10:40:28 to 31-07-2022 10:40:44.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 118 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 31-07-2022 10:40:45 to 31-07-2022 10:40:47.

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          226991
MON$OLDEST_ACTIVE               226992
MON$OLDEST_SNAPSHOT             226992
MON$NEXT_TRANSACTION            226992
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                       2061728
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             789
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2811 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2811 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2811 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2811 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 ---
:355: 
:356: mscs-test-161.budzdorov.ru    Sun Jul 31 10:37:44 2022
:357:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:358: 
--- end of diff output ---

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

31.07.22 10:40:48. 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-07-31 10:40:50 No severe PSQL-related problems occured
31.07.22 10:40:52. End of report.