Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 16.04.2022 13: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 16419532 kB
MemAvailable 31540032 kB
Buffers 0 kB
Cached 15320128 kB
SwapCached 2688 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-04-16 13:37:16
DTS_END                         2022-04-16 13:37:16.3640
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/39832
TRN_ID                          218155
ATT_ID                          611
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: 9203.62 25255 1104434
customer order: creation 167.04 456 20045
customer order: refuse 14.22 411 1706
order to supplier: creation 44.43 1266 5332
order to supplier: removal 3.57 171542 428
invoice (draft): creation 44.30 2908 5316
invoice (draft): removal 52.73 962 6328
invoice accept: apply 41.32 26495 4958
invoice accept: cancel 47.99 1258 5759
customer reserve: creation 3853.03 251 462363
customer reserve: removal 4643.37 103 557204
realization accept: apply 80.33 290 9639
realization accept: cancel 86.01 172 10321
payment from customer: creation 56.88 168 6826
payment from customer: removal 5.78 112 694
payment to supplier: creation 47.35 182 5682
payment to supplier: removal 3.26 129 391
service: total inventory turnovers 3.89 4050 467
service: total monetary turnovers 7.18 390 861
service: aggregate perf. data 0.91 17914 109
service: refresh index statistics 0.04 276044 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 16-04-2022 13:39:35 to 16-04-2022 13:39:37.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9246
TEST_TIME 2 6796
TEST_TIME 3 5938
TEST_TIME 4 6495
TEST_TIME 5 6003
TEST_TIME 6 6115
TEST_TIME 7 6988
TEST_TIME 8 6943
TEST_TIME 9 7133
TEST_TIME 10 7040
TEST_TIME 11 7107
TEST_TIME 12 7244
TEST_TIME 13 7309
TEST_TIME 14 7236
TEST_TIME 15 7317
TEST_TIME 16 7187
TEST_TIME 17 7190
TEST_TIME 18 7329
TEST_TIME 19 7339
TEST_TIME 20 7501
TEST_TIME 21 7484
TEST_TIME 22 7536
TEST_TIME 23 7741
TEST_TIME 24 7762
TEST_TIME 25 7729
TEST_TIME 26 8358
TEST_TIME 27 8426
TEST_TIME 28 8720
TEST_TIME 29 8976
TEST_TIME 30 9685
TEST_TIME 31 10445
TEST_TIME 32 10896
TEST_TIME 33 11020
TEST_TIME 34 11019
TEST_TIME 35 11035
TEST_TIME 36 10949
TEST_TIME 37 10991
TEST_TIME 38 10811
TEST_TIME 39 10599
TEST_TIME 40 10461
TEST_TIME 41 10685
TEST_TIME 42 10668
TEST_TIME 43 10577
TEST_TIME 44 10436
TEST_TIME 45 10281
TEST_TIME 46 10238
TEST_TIME 47 10253
TEST_TIME 48 10145
TEST_TIME 49 10154
TEST_TIME 50 10238
TEST_TIME 51 10137
TEST_TIME 52 10056
TEST_TIME 53 10072
TEST_TIME 54 10094
TEST_TIME 55 10114
TEST_TIME 56 10068
TEST_TIME 57 10053
TEST_TIME 58 9968
TEST_TIME 59 10040
TEST_TIME 60 10040
TEST_TIME 61 10036
TEST_TIME 62 10095
TEST_TIME 63 10160
TEST_TIME 64 10133
TEST_TIME 65 10211
TEST_TIME 66 10189
TEST_TIME 67 10122
TEST_TIME 68 10174
TEST_TIME 69 10065
TEST_TIME 70 9960
TEST_TIME 71 9934
TEST_TIME 72 9893
TEST_TIME 73 9862
TEST_TIME 74 9906
TEST_TIME 75 9829
TEST_TIME 76 9790
TEST_TIME 77 9763
TEST_TIME 78 9685
TEST_TIME 79 9589
TEST_TIME 80 9536
TEST_TIME 81 9577
TEST_TIME 82 9619
TEST_TIME 83 9660
TEST_TIME 84 9625
TEST_TIME 85 9646
TEST_TIME 86 9674
TEST_TIME 87 9631
TEST_TIME 88 9639
TEST_TIME 89 9607
TEST_TIME 90 9591
TEST_TIME 91 9646
TEST_TIME 92 9675
TEST_TIME 93 9695
TEST_TIME 94 9613
TEST_TIME 95 9577
TEST_TIME 96 9535
TEST_TIME 97 9506
TEST_TIME 98 9490
TEST_TIME 99 9455
TEST_TIME 100 9424
TEST_TIME 101 9388
TEST_TIME 102 9402
TEST_TIME 103 9407
TEST_TIME 104 9469
TEST_TIME 105 9537
TEST_TIME 106 9528
TEST_TIME 107 9504
TEST_TIME 108 9477
TEST_TIME 109 9525
TEST_TIME 110 9517
TEST_TIME 111 9497
TEST_TIME 112 9491
TEST_TIME 113 9460
TEST_TIME 114 9443
TEST_TIME 115 9426
TEST_TIME 116 9396
TEST_TIME 117 9354
TEST_TIME 118 9325
TEST_TIME 119 9286
TEST_TIME 120 9235

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 16-04-2022 13:39:38 to 16-04-2022 13:39:42.

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 1531005 1531005 0 0.00 0 23519 14 0 0
doc_list_biud 1530999 1530999 0 0.00 0 79528 43 0 0
sp_add_invoice_to_stock 5074 4958 116 2.28 54 237813 26496 0 116
sp_cancel_adding_invoice 5759 5759 0 0.00 8 102355 1259 0 0
sp_cancel_client_order 1706 1706 0 0.00 8 8445 411 0 0
sp_cancel_customer_reserve 557204 557204 0 0.00 1 79531 104 0 0
sp_cancel_pay_from_customer 694 694 0 0.00 3 5281 113 0 0
sp_cancel_pay_to_supplier 391 391 0 0.00 3 5734 129 0 0
sp_cancel_supplier_invoice 6338 6328 10 0.15 4 49437 962 0 10
sp_cancel_supplier_order 428 428 0 0.00 11 1508419 171543 0 0
sp_cancel_write_off 10321 10321 0 0.00 2 14662 173 0 0
sp_client_order 20045 20045 0 0.00 6 47254 456 0 0
sp_customer_reserve 462363 462363 0 0.00 1 126407 251 0 0
sp_fill_shopping_cart 30689 30689 0 0.00 1 16758 73 0 0
sp_fill_shopping_cart_clo_res 462334 462334 0 0.00 0 22067 19 0 0
sp_get_clo_for_invoice 4908 4908 0 0.00 0 25916 333 0 0
sp_kill_cost_storno 17165 17165 0 0.00 0 12763 79 0 0
sp_kill_qstorno_ret_qs2qd 1131485 1131485 0 0.00 0 79524 44 0 0
sp_kill_qty_storno 596291 596291 0 0.00 0 79528 102 0 0
sp_lock_selected_doc 20114 20114 0 0.00 0 11139 9 0 0
sp_make_cost_storno 27054 27054 0 0.00 0 12782 82 0 0
sp_multiply_rows_for_pdistr 27052 27052 0 0.00 0 9580 39 0 0
sp_multiply_rows_for_qdistr 20045 20045 0 0.00 0 11188 172 0 0
sp_pay_from_customer 6826 6826 0 0.00 6 13021 169 0 0
sp_pay_to_supplier 5682 5682 0 0.00 6 12050 183 0 0
sp_payment_common 12507 12507 0 0.00 2 13010 45 0 0
sp_qd_handle_on_cancel_clo 1706 1706 0 0.00 1 8432 322 0 0
sp_qd_handle_on_invoice_upd_sts 10666 10666 0 0.00 0 22005 495 0 0
sp_qd_handle_on_reserve_upd_sts 9638 9638 0 0.00 0 13654 71 0 0
sp_reserve_write_off 9639 9639 0 0.00 9 17006 291 0 0
sp_supplier_invoice 5316 5316 0 0.00 11 92194 2909 0 0
sp_supplier_order 5332 5332 0 0.00 9 25881 1267 0 0
srv_aggregate_perf_data 109 109 0 0.00 3075 136763 17914 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 806 223066 39290 0 0
srv_find_qd_qs_mism 1056856 1056856 0 0.00 0 23482 10 0 0
srv_make_invnt_saldo 467 467 0 0.00 61 69984 4051 0 0
srv_make_money_saldo 861 861 0 0.00 1 13222 391 0 0
srv_recalc_idx_stat 5 5 0 0.00 49152 384253 276044 0 0
t$perf-abend:sp_add_invoice_to_stock 116 116 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 4964 4964 0 0.00 1 9839 874 0 0
t$perf-norm:sp_cancel_adding_invoice 384 384 0 0.00 2 7176 518 0 0
t$perf-norm:sp_cancel_client_order 1706 1706 0 0.00 0 3793 31 0 0
t$perf-norm:sp_cancel_customer_reserve 636 636 0 0.00 0 2330 28 0 0
t$perf-norm:sp_cancel_pay_from_customer 694 694 0 0.00 0 4877 27 0 0
t$perf-norm:sp_cancel_pay_to_supplier 391 391 0 0.00 0 1680 22 0 0
t$perf-norm:sp_cancel_supplier_invoice 361 361 0 0.00 0 2626 57 0 0
t$perf-norm:sp_cancel_supplier_order 428 428 0 0.00 0 81256 6393 0 0
t$perf-norm:sp_cancel_write_off 969 969 0 0.00 0 2543 18 0 0
t$perf-norm:sp_client_order 20045 20045 0 0.00 0 21923 37 0 0
t$perf-norm:sp_customer_reserve 974 974 0 0.00 0 1107 16 0 0
t$perf-norm:sp_pay_from_customer 6826 6826 0 0.00 0 24650 24 0 0
t$perf-norm:sp_pay_to_supplier 5683 5683 0 0.00 0 8281 21 0 0
t$perf-norm:sp_reserve_write_off 9639 9639 0 0.00 0 7833 25 0 0
t$perf-norm:sp_supplier_invoice 5316 5316 0 0.00 0 20417 37 0 0
t$perf-norm:sp_supplier_order 5332 5332 0 0.00 0 18203 36 0 0
t$perf-norm:srv_aggregate_perf_data 109 109 0 0.00 0 2148 38 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 5865 96 0 0
t$perf-norm:srv_make_invnt_saldo 467 467 0 0.00 0 1617 20 0 0
t$perf-norm:srv_make_money_saldo 861 861 0 0.00 0 510 5 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 3 148 34 0 0
v_add_invoice_to_stock 10046 9814 232 2.30 0 5306 27 0 232
v_all_customers 36880 36880 0 0.00 0 197 3 0 0
v_all_suppliers 29798 29798 0 0.00 0 92 4 0 0
v_all_wares 40090 40090 0 0.00 0 78 4 0 0
v_cancel_adding_invoice 768 768 0 0.00 0 1999 16 0 0
v_cancel_client_order 3412 3412 0 0.00 0 3354 13 0 0
v_cancel_customer_prepayment 1388 1388 0 0.00 0 712 11 0 0
v_cancel_customer_reserve 1272 1272 0 0.00 0 2715 13 0 0
v_cancel_payment_to_supplier 782 782 0 0.00 0 5715 23 0 0
v_cancel_supplier_invoice 742 722 20 2.69 0 3195 31 0 20
v_cancel_supplier_order 856 856 0 0.00 0 5160 18 0 0
v_cancel_write_off 1938 1938 0 0.00 0 1047 22 0 0
v_max_id_clo_ord 5331 5331 0 0.00 0 199 4 0 0
v_max_id_clo_res 974 974 0 0.00 0 3311 15 0 0
v_max_id_ord_sup 5312 5312 0 0.00 0 4239 5 0 0
v_max_non_paid_invoice 1425 1425 0 0.00 0 398 7 0 0
v_max_non_paid_realizn 3422 3422 0 0.00 0 5607 10 0 0
v_min_id_clo_ord 5331 5331 0 0.00 0 4063 8 0 0
v_min_id_clo_res 974 974 0 0.00 0 3972 15 0 0
v_min_id_ord_sup 5312 5312 0 0.00 0 6860 9 0 0
v_min_non_paid_invoice 5681 5681 0 0.00 0 6503 16 0 0
v_min_non_paid_realizn 6826 6826 0 0.00 0 13002 21 0 0
v_our_firm 10018 10018 0 0.00 0 70 4 0 0
v_reserve_write_off 19276 19276 0 0.00 0 6890 11 0 0
x_lock_depdocs_on_canc_invoice 11726 11726 0 0.00 0 36319 144 0 0
x_lock_depdocs_on_canc_sup_ord 428 428 0 0.00 0 13945 441 0 0
x_make_qstorno_1000_1200 5332 5332 0 0.00 2 25828 1116 0 0
x_make_qstorno_1200_2000 5316 5316 0 0.00 6 90976 2720 0 0
x_make_qstorno_2100_3300 462360 462360 0 0.00 0 126403 220 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 16-04-2022 13:39:43 to 16-04-2022 13:39:47.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
11:07:20 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:22 30592 8192000000 9741076784 10047696896 815334864 9.051 100 87 175 0 843328832 7631104 20362864
11:09:57 20613 8192000000 9775747904 10087813120 848747392 9.388 100 84 168 0 881989568 13628064 19614112
11:11:23 18790 8192000000 9823397264 10144829440 881342544 9.713 100 90 180 0 929994192 28516064 20135584
11:12:46 8748 8192000000 9883953728 10220974080 922793936 10.124 100 88 174 1 994802272 52739904 19268432
11:13:58 6652 8192000000 9914623728 10260094976 962723872 10.516 100 86 167 0 1035926432 55982736 17219824
11:15:08 2115 8192000000 9976182976 10331348992 999330368 10.872 100 91 167 2 1092032256 75572480 17129408
11:16:11 1009 8192000000 10014714240 10373861376 1027825008 11.147 100 97 189 1 1132121728 85548576 18748144
11:17:15 933 8192000000 10011121296 10383073280 1050679440 11.367 100 92 173 2 1129653600 61870320 17103840
11:18:17 904 8192000000 10044772928 10424029184 1065126208 11.506 100 96 184 3 1161461264 77646336 18688720
11:19:19 939 8192000000 10062621360 10447745024 1071963344 11.571 100 96 181 2 1183424592 93359392 18101856
11:20:22 1027 8192000000 10048283904 10430124032 1075657776 11.606 100 91 176 1 1168135600 74525600 17952224
11:21:24 4496 8192000000 10056429088 10438217728 1078911776 11.637 100 87 169 0 1178043632 81714912 17416944
11:22:32 1075 8192000000 10040018064 10427437056 1081713952 11.664 100 95 182 1 1159028176 59540032 17774192
11:23:34 1218 8192000000 10052558208 10441744384 1086126896 11.706 100 94 184 1 1174296512 69148784 19020832
11:24:36 1219 8192000000 10072616368 10461712384 1098161504 11.820 100 87 158 1 1194668304 79900928 16605872
11:25:38 1078 8192000000 10050230624 10443378688 1098218480 11.821 100 92 173 3 1173251152 56061760 18970912
11:26:40 1201 8192000000 10054378688 10446958592 1100468144 11.842 100 93 180 1 1177504944 57734240 19302560
11:27:41 1127 8192000000 10063091312 10458013696 1094169216 11.782 100 96 190 0 1185911424 72465888 19276320
11:28:43 1313 8192000000 10053871040 10446782464 1095398944 11.794 100 97 169 2 1181919792 68150592 18370256
11:29:46 1116 8192000000 10051561264 10437865472 1089484928 11.738 100 94 189 1 1167540224 58734800 19320496
11:30:48 831 8192000000 10019121936 10409840640 1082260624 11.669 100 76 146 1 1143405760 46027008 15118128
11:31:50 1947 8192000000 9986830848 10369241088 1047865840 11.340 99 73 144 4 1116142960 53484192 14792928
11:32:53 6182 8192000000 9975534224 10359812096 1050045344 11.361 100 54 103 1 1107751328 47614928 10091056
11:34:01 1709 8192000000 9996729120 10377576448 1043927152 11.302 100 81 153 4 1118052224 57756992 16368080
11:35:04 1076 8192000000 10006439472 10382356480 1033702688 11.204 100 96 185 0 1125858560 74928528 17227344
11:36:05 1261 8192000000 10015552272 10396434432 1047023408 11.332 100 93 179 2 1137049488 72214816 17811264
11:37:07 1403 8192000000 9983466096 10353295360 1032908432 11.196 99 90 168 2 1103046096 53279488 16858176
11:38:18 5690 8192000000 10018105680 10402148352 1069832800 11.550 100 79 133 0 1144891152 61965344 13093008
11:39:36 2201 8192000000 10041348032 10430631936 1084434688 11.690 100 67 126 2 1162333696 65320688 12578320
11:40:43 7122 8192000000 10065197232 10457100288 1093060752 11.772 100 62 122 0 1185376576 80303840 12011984
11:41:56 2686 8192000000 10078384800 10475913216 1105186272 11.887 100 66 124 1 1197608960 81051584 11371104
11:43:05 1867 8192000000 10116808832 10512224256 1118004032 12.008 100 75 139 0 1240692976 108005040 14683904
11:44:11 1068 8192000000 10148605488 10549383168 1118850768 12.016 100 91 172 2 1265518912 126925408 19742736
11:45:14 2251 8192000000 10175814512 10566983680 1133629984 12.156 100 89 169 0 1286944000 136444352 16869664
11:46:20 1177 8192000000 10191012592 10586705920 1136746000 12.185 100 92 185 0 1308567696 152282432 19539264
11:47:25 1396 8192000000 10199951184 10612543488 1148937856 12.300 100 93 185 0 1318586400 148596624 21051920
11:48:36 996 8192000000 10244788976 10646765568 1159014048 12.394 100 95 185 3 1358791328 179689424 20087856
11:49:40 2443 8192000000 10281905552 10698330112 1170614176 12.503 100 65 129 0 1394595152 208741664 15239312
11:50:47 1818 8192000000 10297280368 10712588288 1177319632 12.565 100 87 162 0 1409417808 214568400 17529776
11:51:53 2810 8192000000 10335552144 10736766976 1182014880 12.609 100 80 158 0 1440670912 239973472 18682560
11:52:58 2005 8192000000 10353032576 10756206592 1189265344 12.677 100 82 162 1 1470537840 261365152 19907344
11:54:05 1477 8192000000 10395982064 10813165568 1197293664 12.751 100 94 187 0 1514184576 294564672 22326240
11:55:09 1157 8192000000 10447009984 10872758272 1203606336 12.810 100 91 178 1 1552987984 326075232 23306416
11:56:14 2096 8192000000 10502691136 10932482048 1217058592 12.934 100 91 175 1 1611991616 373781424 21151600
11:57:18 1278 8192000000 10553767488 10960134144 1214060208 12.907 100 97 193 0 1658091280 420900976 23130096
11:58:25 1158 8192000000 10569042368 10976976896 1218174176 12.945 100 95 186 4 1673648656 432775216 22699264
11:59:30 955 8192000000 10624012496 11048157184 1224711840 13.005 100 96 193 1 1730667792 482078368 23877584
12:00:34 1314 8192000000 10644170576 11083821056 1218461216 12.947 100 80 160 0 1750798416 511766256 20570944
12:01:43 797 8192000000 10664274880 11083296768 1209181904 12.862 100 86 170 1 1766507920 535214240 22111776
12:02:48 1803 8192000000 10649658544 11118178304 1196080672 12.740 100 81 162 0 1752013728 534993936 20939120
12:03:53 3808 8192000000 10627705072 11089768448 1200192480 12.778 100 57 114 1 1733438032 518463424 14782128
12:04:57 22206 8192000000 10623397616 11160338432 1194586400 12.726 100 49 98 0 1715050160 507192304 13271456
12:06:20 16135 8192000000 10555792528 11071889408 1174636288 12.540 100 35 70 0 1671911472 487599488 9675696
12:08:26 31822 8192000000 10233976928 10790395904 1133665568 12.156 98 16 32 0 1361220032 223565472 3988992
12:09:59 103969 8192000000 10153608784 10689228800 1129579424 12.117 98 8 16 0 1189839920 58723840 1536656
12:12:54 53838 8192000000 10121433696 10552950784 1144027216 12.253 99 46 84 4 1229541392 77224608 8289568
12:14:51 103731 8192000000 10097341152 10539991040 1137850672 12.195 99 21 42 1 1186207536 43902880 4453984
12:17:54 223066 8192000000 10078692592 10518564864 1005065312 10.928 88 24 48 1 1057390576 48026944 4298320
12:22:39 961 8192000000 10109090848 10536108032 1133294832 12.152 100 68 129 1 1205004960 58275968 13434160
12:23:42 55466 8192000000 10141516416 10596499456 1118161728 12.010 98 33 66 0 1246284912 119972208 8150976
12:25:51 29818 8192000000 10200608848 10647982080 1136348032 12.181 99 27 54 2 1292949616 150289792 6311792
12:28:18 20805 8192000000 10130074544 10626248704 1135992496 12.178 100 11 22 1 1235840112 97415984 2431632
12:29:40 139426 8192000000 10077220912 10591846400 1113685280 11.967 98 17 35 0 1184594768 67004640 3904848
12:33:16 72252 8192000000 10077449200 10535497728 1127613344 12.099 99 21 42 0 1171579488 40260416 3705728
12:35:34 37614 8192000000 10113201968 10571018240 1130460048 12.126 99 44 86 1 1218216288 78079072 9677168
12:37:29 49573 8192000000 10115238224 10554318848 1133790400 12.157 99 29 56 2 1199597648 59953952 5853296
12:39:20 51223 8192000000 10122916608 10586464256 1150113632 12.311 100 13 26 1 1222498864 69434320 2950912
12:41:38 91567 8192000000 10092046848 10580094976 1126887040 12.092 98 8 16 2 1198560512 70074864 1598608
12:44:19 12731 8192000000 10101611392 10525745152 1126420704 12.088 98 61 115 2 1180401312 42270832 11709776
12:45:34 126821 8192000000 10110215888 10556874752 1122627936 12.052 98 37 74 0 1208700848 78247360 7825552
12:48:50 74418 8192000000 10106849664 10562912256 1149480480 12.305 100 38 76 3 1206420912 49730224 7210208
12:51:07 64732 8192000000 10066928480 10553016320 1120380224 12.031 98 20 40 0 1181430240 56982752 4067264
12:53:12 63909 8192000000 10060771312 10551083008 1122128848 12.047 98 22 45 1 1171846816 44666544 5051424
12:55:25 71730 8192000000 10124800544 10571296768 1134122416 12.160 99 18 35 0 1173446720 35051104 4273200
12:59:14 52610 8192000000 10083961888 10598121472 1105985600 11.894 97 14 28 0 1203629872 94746656 2897616
13:01:10 30905 8192000000 10082047920 10531590144 1115275952 11.982 99 43 85 0 1158831136 35164880 8390304
13:02:45 12638 8192000000 10065958944 10496421888 1096873968 11.808 98 38 73 3 1157030160 52223424 7932768
13:04:06 4150 8192000000 10064464176 10483609600 1125102160 12.075 100 61 103 5 1163349632 27879712 10367760
13:05:12 24411 8192000000 10086877744 10514141184 1121263968 12.039 100 41 82 1 1185411680 55955504 8192208
13:06:57 37612 8192000000 10147428800 10578337792 1091970048 11.761 96 39 78 0 1192536464 92763856 7802560
13:08:37 79449 8192000000 10105092928 10545991680 1110621568 11.938 98 20 40 0 1174857632 60304032 3932032
13:10:58 87607 8192000000 10080384336 10534764544 1123995824 12.065 99 46 91 0 1178860512 45762000 9102688
13:13:34 61994 8192000000 10046075296 10506776576 1108009008 11.914 98 8 16 0 1137756288 28429504 1317776
13:15:37 52802 8192000000 10059226560 10495373312 1088368496 11.727 96 18 36 0 1118910832 26804384 3737952
13:17:35 22622 8192000000 10125271696 10554662912 1130336128 12.125 99 33 66 1 1202965040 65092176 7536736
13:18:58 17853 8192000000 10162584720 10597416960 1145055280 12.263 100 47 93 2 1265393520 110044336 10293904
13:20:19 64857 8192000000 10169526720 10634186752 1099582784 11.834 96 27 54 0 1233113488 126985712 6544992
13:22:26 86082 8192000000 10068755120 10523742208 1116066976 11.990 99 22 44 0 1164126944 43712288 4347680
13:24:52 24937 8192000000 10101797104 10538717184 1115912752 11.988 99 36 71 0 1187622992 64509328 7200912
13:26:33 98241 8192000000 10111337584 10562723840 1114299808 11.973 98 15 30 0 1158192320 41183232 2709280
13:29:14 135083 8192000000 10099205008 10604740608 1133595344 12.155 99 27 53 2 1239836016 100575760 5664912
13:32:30 55470 8192000000 10085803408 10559909888 1137996288 12.197 100 8 16 1 1186827840 47155888 1675664
13:34:38 74360 8192000000 10100141200 10572963840 1139196656 12.208 99 11 22 0 1203912112 62445600 2269856

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 16-04-2022 13:39:49 to 16-04-2022 13:40:04.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 116 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 16-04-2022 13:40:05 to 16-04-2022 13:40:07.

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          216637
MON$OLDEST_ACTIVE               219269
MON$OLDEST_SNAPSHOT             219269
MON$NEXT_TRANSACTION            219269
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                       2056544
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             768
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2761 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2761 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2761 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2761 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 ---
:188: 
:189: mscs-test-161.budzdorov.ru    Sat Apr 16 13:37:41 2022
:190:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:191: 
--- end of diff output ---

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

16.04.22 13:40:08. 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-04-16 13:40:11 No severe PSQL-related problems occured
16.04.22 13:40:12. End of report.