Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 19.06.2022 07: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 15873620 kB
MemAvailable 31224612 kB
Buffers 0 kB
Cached 15800656 kB
SwapCached 2464 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-06-19 07:37:15
DTS_END                         2022-06-19 07:37:15.8950
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/53736
TRN_ID                          226893
ATT_ID                          642
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: 9124.12 23306 1094894
customer order: creation 178.48 484 21418
customer order: refuse 15.27 384 1832
order to supplier: creation 46.98 1354 5638
order to supplier: removal 3.53 150083 423
invoice (draft): creation 47.51 3230 5701
invoice (draft): removal 49.62 890 5954
invoice accept: apply 43.13 25388 5176
invoice accept: cancel 44.96 1264 5395
customer reserve: creation 4014.14 240 481697
customer reserve: removal 4374.07 96 524888
realization accept: apply 87.11 288 10453
realization accept: cancel 85.45 171 10254
payment from customer: creation 60.55 181 7266
payment from customer: removal 5.70 122 684
payment to supplier: creation 50.49 193 6059
payment to supplier: removal 3.64 116 437
service: total inventory turnovers 4.18 3648 501
service: total monetary turnovers 8.30 364 996
service: aggregate perf. data 0.98 14398 118
service: refresh index statistics 0.03 263228 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 19-06-2022 07:38:23 to 19-06-2022 07:38:25.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6276
TEST_TIME 2 5273
TEST_TIME 3 5470
TEST_TIME 4 5868
TEST_TIME 5 6513
TEST_TIME 6 6823
TEST_TIME 7 6944
TEST_TIME 8 6886
TEST_TIME 9 6757
TEST_TIME 10 6766
TEST_TIME 11 6767
TEST_TIME 12 7004
TEST_TIME 13 7162
TEST_TIME 14 7066
TEST_TIME 15 7292
TEST_TIME 16 7516
TEST_TIME 17 8037
TEST_TIME 18 8198
TEST_TIME 19 8237
TEST_TIME 20 8549
TEST_TIME 21 8593
TEST_TIME 22 8873
TEST_TIME 23 8963
TEST_TIME 24 9047
TEST_TIME 25 9372
TEST_TIME 26 9399
TEST_TIME 27 9610
TEST_TIME 28 9710
TEST_TIME 29 10091
TEST_TIME 30 10290
TEST_TIME 31 10687
TEST_TIME 32 11425
TEST_TIME 33 11577
TEST_TIME 34 11557
TEST_TIME 35 11383
TEST_TIME 36 11319
TEST_TIME 37 11119
TEST_TIME 38 10951
TEST_TIME 39 10751
TEST_TIME 40 10565
TEST_TIME 41 10651
TEST_TIME 42 10655
TEST_TIME 43 10549
TEST_TIME 44 10463
TEST_TIME 45 10386
TEST_TIME 46 10425
TEST_TIME 47 10326
TEST_TIME 48 10258
TEST_TIME 49 10276
TEST_TIME 50 10150
TEST_TIME 51 10104
TEST_TIME 52 9976
TEST_TIME 53 9971
TEST_TIME 54 9925
TEST_TIME 55 9840
TEST_TIME 56 9857
TEST_TIME 57 9958
TEST_TIME 58 9969
TEST_TIME 59 9909
TEST_TIME 60 9903
TEST_TIME 61 9900
TEST_TIME 62 9957
TEST_TIME 63 9925
TEST_TIME 64 9899
TEST_TIME 65 9997
TEST_TIME 66 9941
TEST_TIME 67 9992
TEST_TIME 68 9885
TEST_TIME 69 9858
TEST_TIME 70 9747
TEST_TIME 71 9713
TEST_TIME 72 9684
TEST_TIME 73 9676
TEST_TIME 74 9597
TEST_TIME 75 9525
TEST_TIME 76 9537
TEST_TIME 77 9487
TEST_TIME 78 9444
TEST_TIME 79 9585
TEST_TIME 80 9522
TEST_TIME 81 9521
TEST_TIME 82 9559
TEST_TIME 83 9585
TEST_TIME 84 9584
TEST_TIME 85 9576
TEST_TIME 86 9547
TEST_TIME 87 9585
TEST_TIME 88 9615
TEST_TIME 89 9522
TEST_TIME 90 9500
TEST_TIME 91 9452
TEST_TIME 92 9436
TEST_TIME 93 9395
TEST_TIME 94 9348
TEST_TIME 95 9308
TEST_TIME 96 9303
TEST_TIME 97 9308
TEST_TIME 98 9284
TEST_TIME 99 9276
TEST_TIME 100 9297
TEST_TIME 101 9264
TEST_TIME 102 9310
TEST_TIME 103 9307
TEST_TIME 104 9444
TEST_TIME 105 9452
TEST_TIME 106 9420
TEST_TIME 107 9507
TEST_TIME 108 9449
TEST_TIME 109 9401
TEST_TIME 110 9326
TEST_TIME 111 9344
TEST_TIME 112 9342
TEST_TIME 113 9299
TEST_TIME 114 9255
TEST_TIME 115 9264
TEST_TIME 116 9229
TEST_TIME 117 9187
TEST_TIME 118 9210
TEST_TIME 119 9212
TEST_TIME 120 9149

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 19-06-2022 07:38:26 to 19-06-2022 07:38:29.

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 1537144 1537144 0 0.00 0 29288 14 0 0
doc_list_biud 1537132 1537132 0 0.00 0 31700 38 0 0
sp_add_invoice_to_stock 5334 5176 158 2.96 59 190318 25388 0 158
sp_cancel_adding_invoice 5395 5395 0 0.00 6 96709 1264 0 0
sp_cancel_client_order 1832 1832 0 0.00 6 11671 384 0 0
sp_cancel_customer_reserve 524888 524888 0 0.00 1 31718 96 0 0
sp_cancel_pay_from_customer 684 684 0 0.00 3 5494 123 0 0
sp_cancel_pay_to_supplier 437 437 0 0.00 3 4106 117 0 0
sp_cancel_supplier_invoice 5964 5954 10 0.16 3 25716 891 0 10
sp_cancel_supplier_order 423 423 0 0.00 11 1734393 150083 0 0
sp_cancel_write_off 10254 10254 0 0.00 1 12214 171 0 0
sp_client_order 21418 21418 0 0.00 6 39006 485 0 0
sp_customer_reserve 481697 481697 0 0.00 1 103527 241 0 0
sp_fill_shopping_cart 32742 32742 0 0.00 1 40659 84 0 0
sp_fill_shopping_cart_clo_res 481664 481664 0 0.00 0 11682 18 0 0
sp_get_clo_for_invoice 5135 5135 0 0.00 0 21467 257 0 0
sp_kill_cost_storno 16770 16770 0 0.00 0 14246 68 0 0
sp_kill_qstorno_ret_qs2qd 1066407 1066407 0 0.00 0 31669 41 0 0
sp_kill_qty_storno 564333 564333 0 0.00 0 31697 96 0 0
sp_lock_selected_doc 21536 21536 0 0.00 0 10855 8 0 0
sp_make_cost_storno 28913 28913 0 0.00 0 29283 76 0 0
sp_multiply_rows_for_pdistr 28913 28913 0 0.00 0 9939 37 0 0
sp_multiply_rows_for_qdistr 21418 21418 0 0.00 0 14825 181 0 0
sp_pay_from_customer 7266 7266 0 0.00 5 10295 182 0 0
sp_pay_to_supplier 6059 6059 0 0.00 6 24718 193 0 0
sp_payment_common 13322 13322 0 0.00 2 8412 48 0 0
sp_qd_handle_on_cancel_clo 1832 1832 0 0.00 0 7984 302 0 0
sp_qd_handle_on_invoice_upd_sts 10530 10530 0 0.00 0 29960 519 0 0
sp_qd_handle_on_reserve_upd_sts 10452 10452 0 0.00 0 15860 68 0 0
sp_reserve_write_off 10453 10453 0 0.00 9 29301 289 0 0
sp_supplier_invoice 5701 5701 0 0.00 9 106546 3231 0 0
sp_supplier_order 5638 5638 0 0.00 6 28808 1355 0 0
srv_aggregate_perf_data 118 118 0 0.00 2842 139081 14398 0 0
srv_fill_mon_cache_memory 71 71 0 0.00 799 139913 35686 0 0
srv_find_qd_qs_mism 1043529 1043529 0 0.00 0 16881 11 0 0
srv_make_invnt_saldo 501 501 0 0.00 69 54607 3648 0 0
srv_make_money_saldo 996 996 0 0.00 1 15412 365 0 0
srv_recalc_idx_stat 4 4 0 0.00 111402 408170 263228 0 0
t$perf-abend:sp_add_invoice_to_stock 158 158 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5177 5177 0 0.00 1 15094 954 0 0
t$perf-norm:sp_cancel_adding_invoice 436 436 0 0.00 1 25892 535 0 0
t$perf-norm:sp_cancel_client_order 1832 1832 0 0.00 0 3387 32 0 0
t$perf-norm:sp_cancel_customer_reserve 714 714 0 0.00 0 3311 38 0 0
t$perf-norm:sp_cancel_pay_from_customer 684 684 0 0.00 0 667 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 437 437 0 0.00 0 2856 25 0 0
t$perf-norm:sp_cancel_supplier_invoice 405 405 0 0.00 0 604 34 0 0
t$perf-norm:sp_cancel_supplier_order 423 423 0 0.00 0 95187 6723 0 0
t$perf-norm:sp_cancel_write_off 1022 1022 0 0.00 0 2688 19 0 0
t$perf-norm:sp_client_order 21418 21418 0 0.00 0 5956 35 0 0
t$perf-norm:sp_customer_reserve 993 993 0 0.00 0 7179 26 0 0
t$perf-norm:sp_pay_from_customer 7266 7266 0 0.00 0 6683 21 0 0
t$perf-norm:sp_pay_to_supplier 6059 6059 0 0.00 0 3674 19 0 0
t$perf-norm:sp_reserve_write_off 10453 10453 0 0.00 0 5432 25 0 0
t$perf-norm:sp_supplier_invoice 5701 5701 0 0.00 0 8128 34 0 0
t$perf-norm:sp_supplier_order 5638 5638 0 0.00 0 8315 36 0 0
t$perf-norm:srv_aggregate_perf_data 118 118 0 0.00 0 615 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 71 71 0 0.00 0 60 5 0 0
t$perf-norm:srv_make_invnt_saldo 501 501 0 0.00 0 585 12 0 0
t$perf-norm:srv_make_money_saldo 996 996 0 0.00 0 1863 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 5 103 54 0 0
v_add_invoice_to_stock 10580 10264 316 2.98 0 5319 26 0 316
v_all_customers 39006 39006 0 0.00 0 63 4 0 0
v_all_suppliers 31458 31458 0 0.00 0 234 4 0 0
v_all_wares 42816 42816 0 0.00 0 82 4 0 0
v_cancel_adding_invoice 872 872 0 0.00 0 4043 21 0 0
v_cancel_client_order 3664 3664 0 0.00 0 2923 11 0 0
v_cancel_customer_prepayment 1368 1368 0 0.00 0 2053 13 0 0
v_cancel_customer_reserve 1428 1428 0 0.00 1 3739 14 0 0
v_cancel_payment_to_supplier 874 874 0 0.00 0 4032 15 0 0
v_cancel_supplier_invoice 830 810 20 2.40 0 1116 23 0 20
v_cancel_supplier_order 846 846 0 0.00 0 3170 19 0 0
v_cancel_write_off 2044 2044 0 0.00 0 1678 22 0 0
v_max_id_clo_ord 5635 5635 0 0.00 0 311 5 0 0
v_max_id_clo_res 993 993 0 0.00 0 3869 18 0 0
v_max_id_ord_sup 5693 5693 0 0.00 0 5349 8 0 0
v_max_non_paid_invoice 1656 1656 0 0.00 0 5509 10 0 0
v_max_non_paid_realizn 3794 3794 0 0.00 0 2972 8 0 0
v_min_id_clo_ord 5635 5635 0 0.00 0 530 7 0 0
v_min_id_clo_res 993 993 0 0.00 0 1772 11 0 0
v_min_id_ord_sup 5693 5693 0 0.00 0 7802 17 0 0
v_min_non_paid_invoice 6057 6057 0 0.00 0 4726 16 0 0
v_min_non_paid_realizn 7265 7265 0 0.00 0 8407 21 0 0
v_our_firm 10752 10752 0 0.00 0 79 5 0 0
v_reserve_write_off 20900 20900 0 0.00 0 5196 13 0 0
x_lock_depdocs_on_canc_invoice 10944 10944 0 0.00 0 24687 120 0 0
x_lock_depdocs_on_canc_sup_ord 423 423 0 0.00 0 7417 300 0 0
x_make_qstorno_1000_1200 5638 5638 0 0.00 2 28729 1193 0 0
x_make_qstorno_1200_2000 5701 5701 0 0.00 3 106383 2997 0 0
x_make_qstorno_2100_3300 481696 481696 0 0.00 0 103525 210 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 19-06-2022 07:38:30 to 19-06-2022 07:38:34.

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
05:07:19 2 8192000000 8796669024 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
05:08:20 20344 8192000000 9721588592 10020536320 796494960 8.861 100 85 170 0 824704512 7424768 20784784
05:09:45 21161 8192000000 9746356672 10055057408 825982880 9.159 100 85 170 0 859567936 12672480 20912576
05:11:12 13564 8192000000 9796481280 10113818624 864309408 9.543 100 86 172 1 904247328 19791744 20146176
05:12:31 16206 8192000000 9854307728 10192281600 917824480 10.075 100 77 153 0 963064784 28420896 16819408
05:13:53 9128 8192000000 9921958064 10267000832 968017600 10.567 100 86 172 1 1035513104 49776128 17719376
05:15:06 2345 8192000000 9966803824 10321182720 994531120 10.825 100 89 171 3 1089487568 77832864 17123584
05:16:11 941 8192000000 10003669392 10366111744 1018656672 11.059 100 95 188 1 1127427952 90555776 18215504
05:17:14 1027 8192000000 10018382352 10390597632 1040738064 11.272 100 96 181 0 1138793312 79579328 18475920
05:18:16 1022 8192000000 10015941888 10393935872 1058532640 11.442 100 93 178 2 1138000000 60765072 18702288
05:19:18 1189 8192000000 10049118976 10430271488 1067785120 11.531 100 97 191 1 1168042752 81007504 19250128
05:20:21 1275 8192000000 10040803216 10423001088 1076669776 11.616 100 92 180 3 1165936432 70824608 18442048
05:21:23 1101 8192000000 10043726976 10429628416 1078944048 11.637 100 86 169 0 1169734960 72672896 18118016
05:22:25 2528 8192000000 10040100560 10430566400 1083181584 11.678 100 71 132 2 1161349024 63425632 14741808
05:23:29 1168 8192000000 10037966352 10422464512 1086001072 11.705 100 86 157 2 1151960704 50103680 15855952
05:24:32 955 8192000000 10048821584 10437660672 1088456448 11.728 100 93 172 1 1169532432 63405728 17670256
05:25:34 1229 8192000000 10056543536 10444562432 1095510032 11.795 100 93 182 0 1181295248 66210032 19575184
05:26:36 1462 8192000000 10077484560 10465255424 1097763264 11.816 100 98 189 3 1194419632 77488672 19167696
05:27:39 1011 8192000000 10081761184 10470195200 1098769232 11.826 100 96 183 0 1200970448 82641200 19560016
05:28:40 1277 8192000000 10035743360 10424049664 1094848592 11.789 100 92 180 3 1154492048 41536080 18107376
05:29:42 3519 8192000000 10039708064 10426204160 1092680608 11.768 100 90 177 1 1168200304 56517392 19002304
05:30:50 1088 8192000000 10026001248 10410733568 1071934176 11.571 99 91 175 3 1146449312 57052272 17462864
05:31:51 1015 8192000000 10046139216 10425233408 1060681456 11.463 99 94 182 0 1168483328 88628992 19172880
05:32:53 1224 8192000000 10019544240 10396372992 1044768400 11.310 100 88 167 3 1140819968 78008864 18042704
05:33:56 1172 8192000000 10003223264 10374307840 1035819360 11.224 99 92 170 1 1124709936 72498128 16392448
05:34:58 1401 8192000000 9989809616 10360471552 1038369088 11.249 100 95 174 0 1108910528 53795872 16745568
05:36:00 1128 8192000000 9970410320 10342662144 1024586416 11.116 99 91 165 2 1095922080 51903152 19432512
05:37:02 1025 8192000000 10005255840 10380955648 1054613184 11.405 100 85 164 0 1127300048 57313616 15373248
05:38:09 5527 8192000000 10021785888 10403151872 1054854400 11.407 100 69 131 3 1149128592 80808960 13465232
05:39:20 3923 8192000000 10028300736 10413064192 1077237136 11.621 100 67 130 0 1149749824 59466560 13046128
05:40:28 2738 8192000000 10068259440 10462162944 1087991184 11.724 100 65 126 1 1188368176 86772928 13604064
05:41:33 2882 8192000000 10105409184 10492571648 1101376992 11.851 100 82 153 4 1223156976 105726832 16053152
05:42:40 1223 8192000000 10126438960 10518306816 1113813376 11.969 100 87 173 0 1243722976 110049968 19859632
05:43:47 1492 8192000000 10167581920 10568171520 1126771696 12.091 100 93 180 0 1284193296 137915088 19506512
05:44:52 9562 8192000000 10187609344 10590662656 1139980912 12.215 100 72 140 1 1303982080 149076672 14924496
05:46:08 1371 8192000000 10234982624 10625441792 1162304336 12.425 100 93 175 1 1341178080 160041168 18832576
05:47:14 814 8192000000 10258446608 10662273024 1175272336 12.546 100 92 176 0 1372456880 174126496 23058048
05:48:22 779 8192000000 10304020000 10705899520 1182575328 12.614 100 96 186 0 1415960880 210536080 22849472
05:49:26 1140 8192000000 10323462176 10732732416 1179835824 12.589 99 91 175 2 1443499456 243404224 20259408
05:50:31 3343 8192000000 10368409312 10783162368 1185694240 12.643 100 80 156 1 1481555120 277063856 18797024
05:51:38 1187 8192000000 10431923152 10838278144 1193498880 12.716 100 95 189 1 1540183568 324742896 21941792
05:52:41 993 8192000000 10417594752 10849062912 1191398880 12.696 100 98 191 0 1525851120 312847696 21604544
05:53:45 943 8192000000 10411778848 10851958784 1197276800 12.751 100 96 186 0 1520168400 301597520 21294080
05:54:47 953 8192000000 10425553584 10842968064 1183359024 12.622 100 88 173 1 1530790832 326993760 20438048
05:55:50 1254 8192000000 10424477840 10850717696 1164729792 12.448 99 91 179 1 1526697552 342573488 19394272
05:56:54 950 8192000000 10414511392 10857103360 1167913872 12.477 100 88 172 0 1521844832 333246400 20684560
05:57:57 907 8192000000 10441461808 10856849408 1181916864 12.608 100 99 196 0 1546026656 340215680 23894112
05:59:01 1017 8192000000 10419370640 10868568064 1182896912 12.617 100 94 183 0 1525363296 320093568 22372816
06:00:03 4505 8192000000 10445129904 10892582912 1171074064 12.507 100 63 126 1 1553503600 366522048 15907488
06:01:41 3635 8192000000 10482881680 10943164416 1175732080 12.550 100 70 140 0 1589675312 396320688 17622544
06:02:47 8365 8192000000 10443768800 10888163328 1170938400 12.506 100 57 107 0 1549331504 364088032 14305072
06:03:58 11953 8192000000 10462252144 10891235328 1141318272 12.228 100 45 89 0 1561900864 409533808 11048784
06:05:19 16783 8192000000 10418211520 10852360192 1133519552 12.155 99 37 74 0 1504303296 361548848 9234896
06:06:36 28435 8192000000 10370659888 10857107456 1135627184 12.174 99 30 59 2 1491307136 348197488 7482464
06:08:11 29052 8192000000 10255645184 10732548096 1092804512 11.769 97 18 36 2 1338828432 241470864 4553056
06:09:41 45669 8192000000 10079676048 10547240960 1097311952 11.812 98 16 30 0 1203280576 102899696 3068928
06:11:52 18928 8192000000 10056992480 10475708416 1118883104 12.016 99 34 65 1 1150791776 25044320 6864352
06:13:12 30330 8192000000 10075386640 10498371584 1124102320 12.066 99 40 77 0 1161302336 27901056 9298960
06:14:44 136993 8192000000 10121663936 10552365056 1103189712 11.868 98 32 62 0 1163404288 53152800 7061776
06:18:06 82402 8192000000 10072792928 10548695040 1125292560 12.077 100 34 68 1 1188984736 56138080 7554096
06:20:29 29708 8192000000 10105201392 10551119872 1143285616 12.246 100 29 58 0 1201741072 51174752 7280704
06:22:08 26665 8192000000 10137188864 10575794176 1135690640 12.175 99 33 66 0 1231686928 88431776 7564512
06:23:38 47874 8192000000 10082621584 10579218432 1147051040 12.282 100 11 22 1 1205776576 56292528 2433008
06:25:49 87060 8192000000 10085721888 10587529216 1147578496 12.287 100 11 22 1 1208113584 57905520 2629568
06:28:16 3824 8192000000 10072531520 10549178368 1144144432 12.254 100 28 50 3 1180332288 29027696 7160160
06:29:39 61532 8192000000 10092394704 10598199296 1121772416 12.044 98 14 28 0 1183362176 58595584 2994176
06:31:41 35860 8192000000 10089573280 10539524096 1141110896 12.226 100 40 80 1 1178325824 27293280 9921648
06:33:18 88694 8192000000 10124628112 10662604800 1143353392 12.247 99 17 34 0 1253043840 105752512 3937936
06:35:49 26671 8192000000 10081410512 10524696576 1111371824 11.945 98 34 68 0 1151272816 32874592 7026400
06:37:20 23213 8192000000 10142552560 10588323840 1145095584 12.263 100 44 88 0 1230944528 76943008 8905936
06:38:45 28773 8192000000 10108227552 10583707648 1121372960 12.040 98 39 76 1 1220043072 91032576 7637536
06:40:14 27220 8192000000 10134743072 10585407488 1133633808 12.156 99 30 61 1 1212883520 73284208 5965504
06:41:42 71075 8192000000 10086114752 10521124864 1118616208 12.014 98 38 71 2 1200334112 74107936 7609968
06:43:53 4173 8192000000 10123602192 10543747072 1139633600 12.212 100 68 132 3 1212602304 58962928 14005776
06:44:59 99623 8192000000 10066658672 10534457344 1128988144 12.112 99 36 72 2 1188186464 51774928 7423392
06:47:39 15985 8192000000 10094476800 10530582528 1146755856 12.279 100 52 99 1 1201321376 43189536 11375984
06:48:56 52386 8192000000 10062499360 10536038400 1090652816 11.749 96 8 16 1 1145921712 53477360 1791536
06:50:48 48947 8192000000 10052367408 10517835776 1118926000 12.017 99 8 16 0 1152333984 31475424 1932560
06:53:02 83597 8192000000 10095458768 10560757760 1108796976 11.921 99 11 22 0 1170878208 59647296 2433936
06:55:26 71793 8192000000 10116987056 10600103936 1113344928 11.964 99 23 46 1 1225554816 106833056 5376832
06:58:04 28494 8192000000 10094873184 10530852864 1124979296 12.074 100 39 78 0 1187420144 54703248 7737600
06:59:34 35994 8192000000 10087877728 10524340224 1090874048 11.751 97 23 46 0 1185548864 89781264 4893552
07:01:14 62495 8192000000 10078037776 10532990976 1107385216 11.908 99 27 52 1 1163160256 50807408 4967632
07:03:22 29982 8192000000 10126799968 10557812736 1122571264 12.051 100 41 78 1 1209069984 78532416 7966304
07:04:52 45627 8192000000 10083905296 10543001600 1117275824 12.001 99 35 69 1 1175644880 51605952 6763104
07:06:39 100012 8192000000 10068729872 10518138880 1105903392 11.894 98 35 69 1 1155225440 42517232 6804816
07:09:19 42610 8192000000 10053269760 10523586560 1132352960 12.144 100 14 28 1 1172743376 37757888 2632528
07:12:01 83470 8192000000 10122994848 10597462016 1137078096 12.188 99 16 32 0 1171328432 30986848 3263488
07:14:29 25555 8192000000 10103746352 10557251584 1123869360 12.064 98 25 50 0 1214057888 84836992 5351536
07:15:55 24899 8192000000 10145946352 10584203264 1143259136 12.246 100 35 70 1 1244462560 93495232 7708192
07:17:23 17161 8192000000 10181416240 10640416768 1145080032 12.263 99 44 88 1 1271155808 116120784 9954992
07:18:47 66974 8192000000 10225458272 10715918336 1164903824 12.449 100 40 80 4 1329399056 155266720 9228512
07:20:56 119467 8192000000 10102098848 10573115392 1090232368 11.745 95 20 38 1 1229406240 135182064 3991808
07:24:04 70029 8192000000 10070029024 10527735808 1130156640 12.123 99 18 36 2 1186295984 52544752 3594592
07:26:15 139913 8192000000 10146076416 10588573696 1134774944 12.166 99 26 51 1 1231926960 91719200 5432816
07:29:35 60104 8192000000 10086601664 10570448896 1135479584 12.173 99 16 33 1 1202971744 63830144 3662016
07:31:37 73579 8192000000 10048607840 10526453760 1117806976 12.006 98 9 17 1 1150519872 30859360 1853536
07:34:16 54488 8192000000 10101734480 10547376128 1119103936 12.019 98 18 36 0 1161898944 38837392 3957616
07:36:22 57806 8192000000 9052143408 9383567360 174593296 2.086 16 1 2 0 184467488 9838784 35408

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 19-06-2022 07:38:36 to 19-06-2022 07:38:52.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 158 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 19-06-2022 07:38:53 to 19-06-2022 07:38:55.

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          227565
MON$OLDEST_ACTIVE               227566
MON$OLDEST_SNAPSHOT             227566
MON$NEXT_TRANSACTION            227566
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                       2075424
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             784
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2780 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2780 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2780 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2780 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 ---
:194: 
:195: mscs-test-161.budzdorov.ru    Sun Jun 19 07:37:21 2022
:196:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:197: 
--- end of diff output ---

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

19.06.22 07:38:56. 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-06-19 07:38:59 No severe PSQL-related problems occured
19.06.22 07:39:00. End of report.