Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 28.06.2022 10: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 16052580 kB
MemAvailable 31215156 kB
Buffers 0 kB
Cached 15673560 kB
SwapCached 2508 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-28 10:37:16
DTS_END                         2022-06-28 10:37:16.5730
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/52450
TRN_ID                          224649
ATT_ID                          658
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: 9248.19 19872 1109783
customer order: creation 180.62 450 21674
customer order: refuse 14.88 364 1786
order to supplier: creation 47.57 1363 5708
order to supplier: removal 3.44 149471 413
invoice (draft): creation 47.31 2884 5677
invoice (draft): removal 50.91 930 6109
invoice accept: apply 42.91 23120 5149
invoice accept: cancel 45.78 1114 5493
customer reserve: creation 3983.48 220 478017
customer reserve: removal 4537.78 91 544534
realization accept: apply 87.29 246 10475
realization accept: cancel 72.99 151 8759
payment from customer: creation 61.68 150 7401
payment from customer: removal 5.98 124 718
payment to supplier: creation 49.74 164 5969
payment to supplier: removal 3.50 127 420
service: total inventory turnovers 4.10 3554 492
service: total monetary turnovers 7.28 306 873
service: aggregate perf. data 0.93 13289 112
service: refresh index statistics 0.03 199322 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 28-06-2022 10:38:43 to 28-06-2022 10:38:45.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6851
TEST_TIME 2 5186
TEST_TIME 3 4918
TEST_TIME 4 5482
TEST_TIME 5 5622
TEST_TIME 6 5447
TEST_TIME 7 5324
TEST_TIME 8 5996
TEST_TIME 9 6090
TEST_TIME 10 6072
TEST_TIME 11 6206
TEST_TIME 12 6546
TEST_TIME 13 6802
TEST_TIME 14 6854
TEST_TIME 15 7192
TEST_TIME 16 7610
TEST_TIME 17 7831
TEST_TIME 18 7992
TEST_TIME 19 8260
TEST_TIME 20 8245
TEST_TIME 21 8339
TEST_TIME 22 8506
TEST_TIME 23 8947
TEST_TIME 24 9252
TEST_TIME 25 9684
TEST_TIME 26 10177
TEST_TIME 27 10618
TEST_TIME 28 10774
TEST_TIME 29 10958
TEST_TIME 30 11176
TEST_TIME 31 11094
TEST_TIME 32 10974
TEST_TIME 33 10899
TEST_TIME 34 11132
TEST_TIME 35 11072
TEST_TIME 36 11038
TEST_TIME 37 10938
TEST_TIME 38 10763
TEST_TIME 39 10576
TEST_TIME 40 10449
TEST_TIME 41 10386
TEST_TIME 42 10241
TEST_TIME 43 10206
TEST_TIME 44 10257
TEST_TIME 45 10257
TEST_TIME 46 10214
TEST_TIME 47 10163
TEST_TIME 48 10078
TEST_TIME 49 10038
TEST_TIME 50 9933
TEST_TIME 51 9893
TEST_TIME 52 10046
TEST_TIME 53 10129
TEST_TIME 54 10068
TEST_TIME 55 10056
TEST_TIME 56 10091
TEST_TIME 57 10052
TEST_TIME 58 10018
TEST_TIME 59 10038
TEST_TIME 60 10097
TEST_TIME 61 10138
TEST_TIME 62 10124
TEST_TIME 63 10138
TEST_TIME 64 10016
TEST_TIME 65 9974
TEST_TIME 66 9952
TEST_TIME 67 9848
TEST_TIME 68 9940
TEST_TIME 69 9867
TEST_TIME 70 9808
TEST_TIME 71 9773
TEST_TIME 72 9665
TEST_TIME 73 9598
TEST_TIME 74 9530
TEST_TIME 75 9514
TEST_TIME 76 9430
TEST_TIME 77 9427
TEST_TIME 78 9481
TEST_TIME 79 9517
TEST_TIME 80 9483
TEST_TIME 81 9476
TEST_TIME 82 9463
TEST_TIME 83 9641
TEST_TIME 84 9646
TEST_TIME 85 9687
TEST_TIME 86 9705
TEST_TIME 87 9688
TEST_TIME 88 9635
TEST_TIME 89 9608
TEST_TIME 90 9552
TEST_TIME 91 9485
TEST_TIME 92 9462
TEST_TIME 93 9418
TEST_TIME 94 9440
TEST_TIME 95 9413
TEST_TIME 96 9387
TEST_TIME 97 9378
TEST_TIME 98 9354
TEST_TIME 99 9330
TEST_TIME 100 9287
TEST_TIME 101 9327
TEST_TIME 102 9299
TEST_TIME 103 9405
TEST_TIME 104 9501
TEST_TIME 105 9569
TEST_TIME 106 9553
TEST_TIME 107 9492
TEST_TIME 108 9463
TEST_TIME 109 9474
TEST_TIME 110 9417
TEST_TIME 111 9358
TEST_TIME 112 9321
TEST_TIME 113 9313
TEST_TIME 114 9267
TEST_TIME 115 9308
TEST_TIME 116 9272
TEST_TIME 117 9339
TEST_TIME 118 9351
TEST_TIME 119 9311
TEST_TIME 120 9269

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 28-06-2022 10:38:46 to 28-06-2022 10:38:50.

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 1549517 1549517 0 0.00 0 26182 12 0 0
doc_list_biud 1549503 1549503 0 0.00 0 47627 38 0 0
sp_add_invoice_to_stock 5313 5149 164 3.08 35 202287 23120 0 164
sp_cancel_adding_invoice 5493 5493 0 0.00 6 102687 1114 0 0
sp_cancel_client_order 1786 1786 0 0.00 6 10060 365 0 0
sp_cancel_customer_reserve 544534 544534 0 0.00 1 28791 91 0 0
sp_cancel_pay_from_customer 718 718 0 0.00 3 8469 125 0 0
sp_cancel_pay_to_supplier 420 420 0 0.00 4 10570 128 0 0
sp_cancel_supplier_invoice 6119 6109 10 0.16 2 47633 931 0 10
sp_cancel_supplier_order 413 413 0 0.00 10 1358679 149472 0 0
sp_cancel_write_off 8759 8759 0 0.00 2 10875 152 0 0
sp_client_order 21674 21674 0 0.00 5 34955 451 0 0
sp_customer_reserve 478017 478017 0 0.00 0 92589 221 0 0
sp_fill_shopping_cart 33038 33038 0 0.00 1 16451 76 0 0
sp_fill_shopping_cart_clo_res 477990 477990 0 0.00 0 14639 17 0 0
sp_get_clo_for_invoice 5114 5114 0 0.00 0 10669 204 0 0
sp_kill_cost_storno 15390 15390 0 0.00 0 26177 64 0 0
sp_kill_qstorno_ret_qs2qd 1104349 1104349 0 0.00 0 47626 41 0 0
sp_kill_qty_storno 582681 582681 0 0.00 0 47627 93 0 0
sp_lock_selected_doc 21495 21495 0 0.00 0 8467 9 0 0
sp_make_cost_storno 28958 28958 0 0.00 0 13275 70 0 0
sp_multiply_rows_for_pdistr 28957 28957 0 0.00 0 6577 32 0 0
sp_multiply_rows_for_qdistr 21673 21673 0 0.00 0 34908 186 0 0
sp_pay_from_customer 7401 7401 0 0.00 5 13575 150 0 0
sp_pay_to_supplier 5969 5969 0 0.00 5 13298 164 0 0
sp_payment_common 13369 13369 0 0.00 2 12232 39 0 0
sp_qd_handle_on_cancel_clo 1786 1786 0 0.00 0 9999 299 0 0
sp_qd_handle_on_invoice_upd_sts 10605 10605 0 0.00 0 16857 443 0 0
sp_qd_handle_on_reserve_upd_sts 10475 10475 0 0.00 0 20103 55 0 0
sp_reserve_write_off 10475 10475 0 0.00 8 20130 246 0 0
sp_supplier_invoice 5677 5677 0 0.00 7 72137 2884 0 0
sp_supplier_order 5708 5708 0 0.00 7 30886 1364 0 0
srv_aggregate_perf_data 112 112 0 0.00 2409 103729 13289 0 0
srv_fill_mon_cache_memory 71 71 0 0.00 890 144530 36583 0 0
srv_find_qd_qs_mism 1058861 1058861 0 0.00 0 25069 10 0 0
srv_make_invnt_saldo 492 492 0 0.00 65 65067 3555 0 0
srv_make_money_saldo 873 873 0 0.00 1 10323 307 0 0
srv_recalc_idx_stat 4 4 0 0.00 75435 305545 199322 0 0
t$perf-abend:sp_add_invoice_to_stock 164 164 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 5153 5153 0 0.00 1 11914 818 0 0
t$perf-norm:sp_cancel_adding_invoice 418 418 0 0.00 2 6976 401 0 0
t$perf-norm:sp_cancel_client_order 1786 1786 0 0.00 0 14717 35 0 0
t$perf-norm:sp_cancel_customer_reserve 729 729 0 0.00 0 4154 29 0 0
t$perf-norm:sp_cancel_pay_from_customer 718 718 0 0.00 0 3871 24 0 0
t$perf-norm:sp_cancel_pay_to_supplier 420 420 0 0.00 0 137 9 0 0
t$perf-norm:sp_cancel_supplier_invoice 447 447 0 0.00 0 843 30 0 0
t$perf-norm:sp_cancel_supplier_order 413 413 0 0.00 0 92329 5361 0 0
t$perf-norm:sp_cancel_write_off 981 981 0 0.00 0 740 15 0 0
t$perf-norm:sp_client_order 21675 21675 0 0.00 0 18732 34 0 0
t$perf-norm:sp_customer_reserve 1031 1031 0 0.00 0 621 13 0 0
t$perf-norm:sp_pay_from_customer 7401 7401 0 0.00 0 4748 16 0 0
t$perf-norm:sp_pay_to_supplier 5969 5969 0 0.00 0 10054 21 0 0
t$perf-norm:sp_reserve_write_off 10475 10475 0 0.00 0 11642 24 0 0
t$perf-norm:sp_supplier_invoice 5677 5677 0 0.00 0 6248 27 0 0
t$perf-norm:sp_supplier_order 5708 5708 0 0.00 0 9599 29 0 0
t$perf-norm:srv_aggregate_perf_data 112 112 0 0.00 0 475 17 0 0
t$perf-norm:srv_fill_mon_cache_memory 71 71 0 0.00 0 51 4 0 0
t$perf-norm:srv_make_invnt_saldo 492 492 0 0.00 0 1717 10 0 0
t$perf-norm:srv_make_money_saldo 873 873 0 0.00 0 2256 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 4 199 56 0 0
v_add_invoice_to_stock 10544 10216 328 3.11 0 4137 22 0 328
v_all_customers 39642 39642 0 0.00 0 362 3 0 0
v_all_suppliers 31640 31640 0 0.00 0 203 4 0 0
v_all_wares 43344 43344 0 0.00 0 186 4 0 0
v_cancel_adding_invoice 836 836 0 0.00 0 6696 22 0 0
v_cancel_client_order 3572 3572 0 0.00 0 1639 9 0 0
v_cancel_customer_prepayment 1436 1436 0 0.00 0 2473 12 0 0
v_cancel_customer_reserve 1458 1458 0 0.00 0 2375 10 0 0
v_cancel_payment_to_supplier 840 840 0 0.00 0 1994 13 0 0
v_cancel_supplier_invoice 914 894 20 2.18 0 4112 25 0 20
v_cancel_supplier_order 826 826 0 0.00 0 610 8 0 0
v_cancel_write_off 1962 1962 0 0.00 0 5888 24 0 0
v_max_id_clo_ord 5702 5702 0 0.00 0 1699 5 0 0
v_max_id_clo_res 1031 1031 0 0.00 0 2464 17 0 0
v_max_id_ord_sup 5662 5662 0 0.00 0 4723 9 0 0
v_max_non_paid_invoice 1512 1512 0 0.00 0 288 7 0 0
v_max_non_paid_realizn 3833 3833 0 0.00 0 501 6 0 0
v_min_id_clo_ord 5702 5702 0 0.00 0 1669 7 0 0
v_min_id_clo_res 1031 1031 0 0.00 0 7811 16 0 0
v_min_id_ord_sup 5662 5662 0 0.00 0 6454 7 0 0
v_min_non_paid_invoice 5969 5969 0 0.00 0 5715 13 0 0
v_min_non_paid_realizn 7400 7400 0 0.00 0 8085 14 0 0
v_our_firm 10834 10834 0 0.00 0 144 4 0 0
v_reserve_write_off 20950 20950 0 0.00 0 4127 11 0 0
x_lock_depdocs_on_canc_invoice 11155 11155 0 0.00 0 15547 107 0 0
x_lock_depdocs_on_canc_sup_ord 413 413 0 0.00 0 2505 276 0 0
x_make_qstorno_1000_1200 5708 5708 0 0.00 2 30825 1216 0 0
x_make_qstorno_1200_2000 5677 5677 0 0.00 2 67150 2673 0 0
x_make_qstorno_2100_3300 478017 478017 0 0.00 0 92576 193 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 28-06-2022 10:38:51 to 28-06-2022 10:38:55.

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:20 2 8192000000 8796669104 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:21 27191 8192000000 9741694656 10044125184 813525280 9.033 100 91 181 0 842933840 7664320 21744240
08:09:55 22621 8192000000 9774622080 10089422848 850265472 9.403 100 84 167 0 882871008 13101392 19504144
08:11:25 10510 8192000000 9816366832 10140405760 885840160 9.758 100 91 178 0 926670016 20452864 20376992
08:12:40 7875 8192000000 9871126192 10206531584 920495264 10.101 100 88 176 0 984982368 45003856 19483248
08:13:50 2746 8192000000 9908210608 10252275712 956913712 10.459 100 84 162 0 1025551104 51108192 17529200
08:14:54 2797 8192000000 9952653088 10306822144 994436944 10.825 100 81 156 0 1069932256 60400512 15094800
08:16:01 1132 8192000000 10007736480 10367954944 1022433552 11.096 100 95 175 2 1123193312 82162048 18597712
08:17:05 831 8192000000 10012291728 10382503936 1045250368 11.315 100 95 186 0 1136746048 73036032 18459648
08:18:07 1312 8192000000 10011731728 10385571840 1050275344 11.363 100 92 179 2 1132534752 64021872 18237536
08:19:12 1521 8192000000 10045828656 10424778752 1065206768 11.506 100 88 167 1 1169573744 87444224 16922752
08:20:16 1898 8192000000 10048424976 10430189568 1073157888 11.582 100 95 182 0 1169288368 76721904 19408576
08:21:19 904 8192000000 10068381712 10447536128 1080491968 11.652 100 97 185 0 1181113456 82244880 18376608
08:22:22 1065 8192000000 10068997696 10454622208 1087536608 11.719 100 92 176 3 1191621680 86935552 17149520
08:23:24 1039 8192000000 10075166816 10461159424 1088406160 11.728 100 94 185 1 1197516928 91092272 18018496
08:24:26 1106 8192000000 10061417264 10452946944 1092923696 11.770 100 94 179 1 1188312096 76213152 19175248
08:25:28 1482 8192000000 10038577936 10428231680 1087203744 11.716 99 90 172 3 1160514576 55018864 18291968
08:26:31 2095 8192000000 10059315008 10454290432 1102041328 11.857 100 79 156 1 1176058912 57723984 16293600
08:27:34 877 8192000000 10072613232 10466832384 1101619792 11.853 100 90 173 2 1196083040 75292032 19171216
08:28:36 1010 8192000000 10043270944 10436874240 1089161184 11.735 100 82 158 2 1166369168 61138400 16069584
08:29:38 1008 8192000000 10074411376 10460143616 1090683072 11.749 100 94 181 0 1195298128 86094432 18520624
08:30:40 1123 8192000000 10022429104 10406244352 1068400304 11.537 99 88 170 0 1142733840 56067248 18266288
08:31:43 1103 8192000000 9985938832 10363289600 1040764608 11.272 100 84 151 1 1108121072 52302736 15053728
08:32:46 2238 8192000000 9996742256 10375770112 1040655744 11.271 99 75 138 1 1113203648 59419584 13128320
08:33:49 1208 8192000000 10007374976 10388717568 1045852288 11.321 100 75 150 0 1131821968 69433216 16536464
08:34:51 1288 8192000000 9974744864 10351247360 1033490592 11.202 100 83 143 3 1096691328 47725200 15475536
08:35:53 899 8192000000 9994610496 10365149184 1024048128 11.111 99 95 177 1 1114758288 71966464 18743696
08:36:55 1416 8192000000 9993081024 10364157952 1030595184 11.174 100 95 172 3 1114460896 64696656 19169056
08:38:00 15453 8192000000 9991018512 10371211264 1052222896 11.382 100 65 121 4 1111514432 47472320 11819216
08:39:20 11010 8192000000 10043989024 10427199488 1070256384 11.555 100 76 140 2 1162576896 78469056 13851456
08:40:36 5172 8192000000 10081595216 10464313344 1090439024 11.747 100 81 158 0 1207525680 99946800 17139856
08:41:47 1117 8192000000 10128512176 10517827584 1118268672 12.011 100 91 175 1 1253017648 113938304 20810672
08:42:52 1582 8192000000 10172200160 10565959680 1125435536 12.078 100 89 170 0 1295654864 151493568 18725760
08:43:58 2925 8192000000 10203031264 10598465536 1134753296 12.166 100 75 146 0 1317261264 166650016 15857952
08:45:05 1128 8192000000 10246054208 10646593536 1145573360 12.268 100 91 172 3 1357473152 193175936 18723856
08:46:10 1900 8192000000 10267721520 10661564416 1141065456 12.226 100 92 177 2 1378663088 218328448 19269184
08:47:16 1457 8192000000 10306192640 10706796544 1156685456 12.372 100 86 165 1 1415813648 241261760 17866432
08:48:22 1311 8192000000 10335403824 10747572224 1163904992 12.440 100 95 179 0 1451847904 266728096 21214816
08:49:27 1260 8192000000 10374286656 10777505792 1179918048 12.589 100 96 185 0 1489050032 288571216 20560768
08:50:31 2369 8192000000 10419795008 10821505024 1178684928 12.578 100 88 172 2 1527443168 329965248 18792992
08:51:35 1443 8192000000 10419754128 10846498816 1175434256 12.548 100 90 178 1 1537145504 341318288 20392960
08:52:40 1601 8192000000 10423557168 10877550592 1175551728 12.549 100 93 178 3 1530574832 332196624 22826480
08:53:44 881 8192000000 10407261968 10831466496 1159114400 12.395 100 96 188 0 1517222848 335502832 22605616
08:54:48 1235 8192000000 10441055584 10872586240 1177569168 12.568 100 95 189 1 1543871136 344160016 22141952
08:55:52 1069 8192000000 10440155248 10858459136 1170840816 12.505 100 79 150 0 1546408016 358130768 17436432
08:56:54 1180 8192000000 10427057952 10860486656 1175049808 12.544 100 84 151 5 1529989568 338752752 16187008
08:58:03 1237 8192000000 10510378384 10941542400 1178755360 12.579 100 75 148 0 1614413712 418279472 17378880
08:59:06 4237 8192000000 10476760768 10939174912 1168542816 12.483 100 58 113 0 1585874016 403041024 14290176
09:00:16 3271 8192000000 10469140960 10932776960 1159630768 12.400 100 56 111 0 1577505744 403588624 14286352
09:01:22 12225 8192000000 10416565024 10873749504 1161108976 12.414 100 49 98 1 1526415040 352531664 12774400
09:02:39 8957 8192000000 10369030976 10848837632 1149667664 12.306 99 41 80 0 1474098976 314114976 10316336
09:04:02 20340 8192000000 10233887216 10759970816 1139108128 12.207 100 33 65 0 1350722736 203598384 8016224
09:05:39 49737 8192000000 10171520048 10637303808 1088290480 11.726 96 16 32 1 1244049696 151716208 4043008
09:07:30 92906 8192000000 10115706016 10596294656 1103092160 11.867 98 10 20 0 1183328880 78249328 1987392
09:10:09 50173 8192000000 10132067488 10594725888 1097205360 11.811 96 24 42 2 1212281104 110634800 4440944
09:12:01 73181 8192000000 10094087136 10582982656 1101878096 11.855 96 16 32 0 1142767152 37405024 3484032
09:14:34 127970 8192000000 10065740800 10515419136 1082983248 11.676 96 38 77 0 1122432080 31749504 7699328
09:17:52 12486 8192000000 10045903280 10457595904 1095296352 11.793 98 69 117 4 1140093680 34772832 10024496
09:19:06 46452 8192000000 10101943904 10521903104 1127894928 12.102 100 49 87 0 1201675648 65214464 8566256
09:20:54 58643 8192000000 10097875312 10550857728 1114762928 11.977 98 29 57 1 1216085520 95136704 6185888
09:22:54 11788 8192000000 10105937312 10527469568 1137589264 12.193 100 74 126 2 1214371488 64474480 12307744
09:24:07 21524 8192000000 10097439440 10548113408 1134714544 12.166 100 35 70 0 1202607648 60409840 7483264
09:25:49 24930 8192000000 10120642496 10565287936 1140623568 12.221 100 14 28 0 1226132672 82329712 3179392
09:27:14 55986 8192000000 10145938112 10614407168 1134192784 12.161 99 10 20 0 1217249184 80689024 2367376
09:29:10 62364 8192000000 10105866192 10592796672 1130154592 12.123 99 23 44 0 1249105152 113811600 5138960
09:31:17 8871 8192000000 10075065248 10509934592 1144847840 12.261 100 55 94 1 1177449328 22374976 10226512
09:32:27 114932 8192000000 10090328672 10559758336 1099454544 11.832 96 23 43 1 1128282832 24092128 4736160
09:35:24 20442 8192000000 10112119312 10551656448 1135109792 12.170 99 35 69 0 1196419360 54417696 6891872
09:36:45 67430 8192000000 10096418048 10534948864 1113672688 11.967 98 21 42 1 1194560048 76847936 4039424
09:38:59 53927 8192000000 10091070448 10543996928 1106672272 11.901 97 22 44 0 1153077856 42029840 4375744
09:40:53 144530 8192000000 10087019584 10564972544 1120321200 12.030 98 24 48 0 1169846272 43821392 5703680
09:44:26 16869 8192000000 10094370416 10547531776 1118413888 12.012 99 30 58 0 1189805744 65362384 6029472
09:45:53 81589 8192000000 10050246304 10516865024 1108825504 11.921 98 10 20 0 1155191968 44370032 1996432
09:48:18 26274 8192000000 10044983120 10488799232 1125250752 12.077 100 32 62 0 1144107616 11700640 7156224
09:49:52 54860 8192000000 10057931040 10519068672 1131347488 12.134 100 23 46 0 1164827376 28508384 4971504
09:51:47 56055 8192000000 10115639984 10571636736 1119212240 12.020 98 17 31 1 1191237824 68271568 3754016
09:53:43 34714 8192000000 10144387296 10609369088 1117986768 12.008 98 28 56 1 1226140560 101310752 6843040
09:55:21 44969 8192000000 10154998512 10598891520 1132261808 12.143 99 24 48 0 1255988240 117631552 6094880
09:57:11 53107 8192000000 10160425280 10629865472 1134388272 12.163 99 43 86 0 1257673824 114116736 9168816
09:59:04 27841 8192000000 10179321616 10622386176 1125609360 12.080 99 43 86 0 1278253664 143563888 9080416
10:00:47 42870 8192000000 10150782016 10585088000 1137456256 12.192 100 27 54 0 1240098144 97276176 5365712
10:02:31 17756 8192000000 10091016048 10514833408 1128814576 12.110 100 38 72 0 1181177968 45033408 7329984
10:03:49 125565 8192000000 10069471808 10514259968 1089538352 11.738 97 34 64 0 1156577120 60366032 6672736
10:06:57 56046 8192000000 10036000224 10490306560 1108288240 11.916 98 10 20 0 1133484896 23229504 1967152
10:09:40 74883 8192000000 10080562160 10559881216 1106176544 11.896 98 6 12 0 1182769360 75574976 1017840
10:11:58 31814 8192000000 10087267216 10525650944 1121174592 12.038 100 35 70 0 1166998112 38162864 7660656
10:13:33 28060 8192000000 10126461344 10561183744 1136009680 12.178 100 29 56 0 1210130672 68421152 5699840
10:15:05 30458 8192000000 10187208816 10614743040 1140549120 12.221 99 44 88 0 1257391536 107726448 9115968
10:16:41 18243 8192000000 10220704592 10652553216 1143097424 12.245 98 52 102 0 1310237296 155925968 11213904
10:18:01 22389 8192000000 10271206800 10734665728 1156672736 12.372 100 52 104 0 1379238576 210818656 11747184
10:19:26 14159 8192000000 10266148688 10700615680 1154456992 12.351 100 42 84 1 1363869696 199932928 9479776
10:20:41 73613 8192000000 10142137680 10662342656 1097927936 11.818 96 11 20 0 1202546128 102325360 2292832
10:22:54 46954 8192000000 10071205008 10507710464 1067594320 11.529 95 27 54 1 1123185344 49883456 5707568
10:24:41 96398 8192000000 10023807584 10498560000 1072252528 11.574 96 25 50 0 1163152512 85397456 5502528
10:27:19 83460 8192000000 10124891424 10579136512 1116937920 11.998 99 15 30 0 1194372528 74191072 3243536
10:29:43 81389 8192000000 10175872736 10659155968 1140260384 12.218 100 25 50 0 1251158336 104911504 5986448
10:32:08 50281 8192000000 10162590416 10632908800 1122989808 12.055 98 22 44 0 1248225600 120256800 4978992
10:34:06 55187 8192000000 10154060768 10630881280 1142218112 12.236 100 27 54 1 1265179024 116308144 6652768
10:36:08 48685 8192000000 10179573648 10631573504 1141132688 12.226 100 32 64 1 1262149632 113242912 7774032

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 28-06-2022 10:38:56 to 28-06-2022 10:39:12.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 164 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 28-06-2022 10:39:13 to 28-06-2022 10:39:15.

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          219034
MON$OLDEST_ACTIVE               226922
MON$OLDEST_SNAPSHOT             226922
MON$NEXT_TRANSACTION            226922
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                       2051488
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             786
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2792 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2792 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2792 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2792 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 ---
:15: 
:16: mscs-test-161.budzdorov.ru    Tue Jun 28 10:37:19 2022
:17:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:18: 
:19: 
:20: mscs-test-161.budzdorov.ru    Tue Jun 28 10:37:26 2022
:21:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:22: 
:23: 
:24: mscs-test-161.budzdorov.ru    Tue Jun 28 10:37:27 2022
:25:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:26: 
--- end of diff output ---

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

28.06.22 10:39:16. 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-28 10:39:18 No severe PSQL-related problems occured
28.06.22 10:39:20. End of report.