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

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-12-25 13:37:22
DTS_END                         2022-12-25 13:37:22.8830
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/32902
TRN_ID                          221215
ATT_ID                          656
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: 9128.51 24532 1095421
customer order: creation 170.26 472 20431
customer order: refuse 14.93 389 1792
order to supplier: creation 45.98 1259 5517
order to supplier: removal 3.57 155397 428
invoice (draft): creation 46.19 2774 5543
invoice (draft): removal 50.48 908 6058
invoice accept: apply 42.12 25368 5054
invoice accept: cancel 45.60 1188 5472
customer reserve: creation 3931.19 239 471743
customer reserve: removal 4478.67 97 537440
realization accept: apply 83.68 279 10042
realization accept: cancel 85.38 164 10245
payment from customer: creation 59.60 173 7152
payment from customer: removal 5.93 122 712
payment to supplier: creation 49.26 184 5911
payment to supplier: removal 3.26 129 391
service: total inventory turnovers 3.80 4027 456
service: total monetary turnovers 7.65 352 918
service: aggregate perf. data 0.93 15220 112
service: refresh index statistics 0.03 281906 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 25-12-2022 13:38:09 to 25-12-2022 13:38:12.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9043
TEST_TIME 2 8105
TEST_TIME 3 6916
TEST_TIME 4 6100
TEST_TIME 5 6969
TEST_TIME 6 6796
TEST_TIME 7 7185
TEST_TIME 8 7505
TEST_TIME 9 7578
TEST_TIME 10 7463
TEST_TIME 11 7369
TEST_TIME 12 7343
TEST_TIME 13 7285
TEST_TIME 14 7209
TEST_TIME 15 7090
TEST_TIME 16 7128
TEST_TIME 17 7268
TEST_TIME 18 7782
TEST_TIME 19 7774
TEST_TIME 20 7873
TEST_TIME 21 7760
TEST_TIME 22 7661
TEST_TIME 23 8244
TEST_TIME 24 8313
TEST_TIME 25 8699
TEST_TIME 26 8783
TEST_TIME 27 8841
TEST_TIME 28 9311
TEST_TIME 29 9406
TEST_TIME 30 9865
TEST_TIME 31 10664
TEST_TIME 32 11134
TEST_TIME 33 11269
TEST_TIME 34 11066
TEST_TIME 35 11095
TEST_TIME 36 11107
TEST_TIME 37 11016
TEST_TIME 38 10838
TEST_TIME 39 10716
TEST_TIME 40 10661
TEST_TIME 41 10521
TEST_TIME 42 10542
TEST_TIME 43 10525
TEST_TIME 44 10527
TEST_TIME 45 10394
TEST_TIME 46 10354
TEST_TIME 47 10289
TEST_TIME 48 10240
TEST_TIME 49 10179
TEST_TIME 50 10147
TEST_TIME 51 10053
TEST_TIME 52 9906
TEST_TIME 53 9857
TEST_TIME 54 9709
TEST_TIME 55 9708
TEST_TIME 56 9706
TEST_TIME 57 9647
TEST_TIME 58 9735
TEST_TIME 59 9679
TEST_TIME 60 9590
TEST_TIME 61 9555
TEST_TIME 62 9464
TEST_TIME 63 9440
TEST_TIME 64 9601
TEST_TIME 65 9509
TEST_TIME 66 9536
TEST_TIME 67 9479
TEST_TIME 68 9514
TEST_TIME 69 9548
TEST_TIME 70 9587
TEST_TIME 71 9630
TEST_TIME 72 9661
TEST_TIME 73 9673
TEST_TIME 74 9732
TEST_TIME 75 9715
TEST_TIME 76 9707
TEST_TIME 77 9699
TEST_TIME 78 9585
TEST_TIME 79 9576
TEST_TIME 80 9494
TEST_TIME 81 9450
TEST_TIME 82 9426
TEST_TIME 83 9365
TEST_TIME 84 9309
TEST_TIME 85 9285
TEST_TIME 86 9260
TEST_TIME 87 9364
TEST_TIME 88 9342
TEST_TIME 89 9325
TEST_TIME 90 9330
TEST_TIME 91 9299
TEST_TIME 92 9382
TEST_TIME 93 9386
TEST_TIME 94 9347
TEST_TIME 95 9474
TEST_TIME 96 9466
TEST_TIME 97 9498
TEST_TIME 98 9479
TEST_TIME 99 9508
TEST_TIME 100 9456
TEST_TIME 101 9376
TEST_TIME 102 9326
TEST_TIME 103 9340
TEST_TIME 104 9321
TEST_TIME 105 9285
TEST_TIME 106 9265
TEST_TIME 107 9233
TEST_TIME 108 9186
TEST_TIME 109 9134
TEST_TIME 110 9155
TEST_TIME 111 9121
TEST_TIME 112 9124
TEST_TIME 113 9138
TEST_TIME 114 9162
TEST_TIME 115 9147
TEST_TIME 116 9157
TEST_TIME 117 9205
TEST_TIME 118 9181
TEST_TIME 119 9164
TEST_TIME 120 9146

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 25-12-2022 13:38:13 to 25-12-2022 13:38:16.

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 1529121 1529121 0 0.00 0 36671 13 0 0
doc_list_biud 1529119 1529119 0 0.00 0 58482 39 0 0
sp_add_invoice_to_stock 5167 5054 113 2.18 38 185450 25369 0 113
sp_cancel_adding_invoice 5472 5472 0 0.00 6 97218 1189 0 0
sp_cancel_client_order 1792 1792 0 0.00 6 7421 389 0 0
sp_cancel_customer_reserve 537440 537440 0 0.00 1 58485 97 0 0
sp_cancel_pay_from_customer 712 712 0 0.00 3 3771 122 0 0
sp_cancel_pay_to_supplier 391 391 0 0.00 3 6439 130 0 0
sp_cancel_supplier_invoice 6069 6058 11 0.18 3 14632 909 0 11
sp_cancel_supplier_order 428 428 0 0.00 13 1460878 155398 0 0
sp_cancel_write_off 10245 10245 0 0.00 2 12833 164 0 0
sp_client_order 20431 20431 0 0.00 5 36157 472 0 0
sp_customer_reserve 471743 471743 0 0.00 0 77046 239 0 0
sp_fill_shopping_cart 31489 31489 0 0.00 1 11106 80 0 0
sp_fill_shopping_cart_clo_res 471726 471726 0 0.00 0 11980 18 0 0
sp_get_clo_for_invoice 5028 5028 0 0.00 0 29037 314 0 0
sp_kill_cost_storno 16820 16820 0 0.00 0 36654 71 0 0
sp_kill_qstorno_ret_qs2qd 1091611 1091611 0 0.00 0 58477 41 0 0
sp_kill_qty_storno 576502 576502 0 0.00 0 58482 95 0 0
sp_lock_selected_doc 20863 20863 0 0.00 0 10557 8 0 0
sp_make_cost_storno 28131 28131 0 0.00 0 13471 85 0 0
sp_multiply_rows_for_pdistr 28131 28131 0 0.00 0 9153 35 0 0
sp_multiply_rows_for_qdistr 20431 20431 0 0.00 0 11916 171 0 0
sp_pay_from_customer 7152 7152 0 0.00 6 10704 173 0 0
sp_pay_to_supplier 5911 5911 0 0.00 7 11675 185 0 0
sp_payment_common 13063 13063 0 0.00 2 10592 50 0 0
sp_qd_handle_on_cancel_clo 1792 1792 0 0.00 0 4589 314 0 0
sp_qd_handle_on_invoice_upd_sts 10497 10497 0 0.00 0 38156 485 0 0
sp_qd_handle_on_reserve_upd_sts 10042 10042 0 0.00 0 14282 63 0 0
sp_reserve_write_off 10042 10042 0 0.00 8 14296 279 0 0
sp_supplier_invoice 5543 5543 0 0.00 8 87889 2775 0 0
sp_supplier_order 5517 5517 0 0.00 8 31326 1260 0 0
srv_aggregate_perf_data 112 112 0 0.00 2023 237273 15220 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 786 140329 37574 0 0
srv_find_qd_qs_mism 1045735 1045735 0 0.00 0 13147 10 0 0
srv_make_invnt_saldo 456 456 0 0.00 43 105176 4028 0 0
srv_make_money_saldo 918 918 0 0.00 1 16625 352 0 0
srv_recalc_idx_stat 4 4 0 0.00 75806 468880 281907 0 0
t$perf-abend:sp_add_invoice_to_stock 113 113 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5054 5054 0 0.00 0 11783 902 0 0
t$perf-norm:sp_cancel_adding_invoice 411 411 0 0.00 1 10969 513 0 0
t$perf-norm:sp_cancel_client_order 1792 1792 0 0.00 0 4336 28 0 0
t$perf-norm:sp_cancel_customer_reserve 702 702 0 0.00 0 811 20 0 0
t$perf-norm:sp_cancel_pay_from_customer 712 712 0 0.00 0 1467 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 391 391 0 0.00 0 2411 19 0 0
t$perf-norm:sp_cancel_supplier_invoice 408 408 0 0.00 0 1507 37 0 0
t$perf-norm:sp_cancel_supplier_order 428 428 0 0.00 0 117444 6220 0 0
t$perf-norm:sp_cancel_write_off 953 953 0 0.00 0 2232 20 0 0
t$perf-norm:sp_client_order 20431 20431 0 0.00 0 9668 37 0 0
t$perf-norm:sp_customer_reserve 1060 1060 0 0.00 0 4704 18 0 0
t$perf-norm:sp_pay_from_customer 7152 7152 0 0.00 0 13172 25 0 0
t$perf-norm:sp_pay_to_supplier 5911 5911 0 0.00 0 4787 21 0 0
t$perf-norm:sp_reserve_write_off 10042 10042 0 0.00 0 6385 24 0 0
t$perf-norm:sp_supplier_invoice 5543 5543 0 0.00 0 5414 34 0 0
t$perf-norm:sp_supplier_order 5517 5517 0 0.00 0 3204 30 0 0
t$perf-norm:srv_aggregate_perf_data 112 112 0 0.00 0 177 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 17 3 0 0
t$perf-norm:srv_make_invnt_saldo 456 456 0 0.00 0 839 13 0 0
t$perf-norm:srv_make_money_saldo 918 918 0 0.00 0 418 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 158 55 0 0
v_add_invoice_to_stock 10274 10048 226 2.19 0 3577 24 0 226
v_all_customers 37624 37624 0 0.00 0 109 4 0 0
v_all_suppliers 30900 30900 0 0.00 0 104 4 0 0
v_all_wares 40862 40862 0 0.00 0 94 4 0 0
v_cancel_adding_invoice 822 822 0 0.00 0 848 14 0 0
v_cancel_client_order 3584 3584 0 0.00 0 3385 12 0 0
v_cancel_customer_prepayment 1424 1424 0 0.00 0 3742 13 0 0
v_cancel_customer_reserve 1404 1404 0 0.00 0 2386 12 0 0
v_cancel_payment_to_supplier 782 782 0 0.00 0 2293 13 0 0
v_cancel_supplier_invoice 838 816 22 2.62 0 2279 27 0 22
v_cancel_supplier_order 856 856 0 0.00 0 1144 12 0 0
v_cancel_write_off 1906 1906 0 0.00 0 2290 26 0 0
v_max_id_clo_ord 5517 5517 0 0.00 0 577 5 0 0
v_max_id_clo_res 1060 1060 0 0.00 0 1502 9 0 0
v_max_id_ord_sup 5541 5541 0 0.00 0 4593 8 0 0
v_max_non_paid_invoice 1519 1519 0 0.00 0 237 8 0 0
v_max_non_paid_realizn 3854 3854 0 0.00 0 7132 12 0 0
v_min_id_clo_ord 5517 5517 0 0.00 0 2447 8 0 0
v_min_id_clo_res 1060 1060 0 0.00 0 1178 8 0 0
v_min_id_ord_sup 5541 5541 0 0.00 0 4505 8 0 0
v_min_non_paid_invoice 5911 5911 0 0.00 0 10538 18 0 0
v_min_non_paid_realizn 7152 7152 0 0.00 0 8124 23 0 0
v_our_firm 9834 9834 0 0.00 0 91 5 0 0
v_reserve_write_off 20084 20084 0 0.00 0 3700 11 0 0
x_lock_depdocs_on_canc_invoice 11122 11122 0 0.00 0 24477 146 0 0
x_lock_depdocs_on_canc_sup_ord 428 428 0 0.00 0 10941 332 0 0
x_make_qstorno_1000_1200 5517 5517 0 0.00 2 31316 1101 0 0
x_make_qstorno_1200_2000 5543 5543 0 0.00 3 84405 2562 0 0
x_make_qstorno_2100_3300 471743 471743 0 0.00 0 77046 209 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 25-12-2022 13:38:17 to 25-12-2022 13:38:22.

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:26 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
11:08:27 30822 8192000000 9732468768 10034049024 806662736 8.964 100 85 168 0 834455232 7633952 20158544
11:10:05 27248 8192000000 9774409664 10088124416 844700672 9.347 100 86 172 0 877118784 12757424 19660688
11:11:40 18305 8192000000 9819677488 10142146560 874765488 9.648 100 86 172 0 925125040 30160032 20199520
11:13:03 9214 8192000000 9861398368 10195783680 921390352 10.110 100 76 150 1 977943248 40907088 15645808
11:14:16 4404 8192000000 9935496480 10278031360 963542080 10.524 100 87 166 0 1038883376 57652768 17688528
11:15:24 2070 8192000000 9965115136 10317676544 992954448 10.810 100 83 163 0 1071303184 62196304 16152432
11:16:28 1774 8192000000 9993542096 10354839552 1014710912 11.021 100 87 167 0 1113197136 81957136 16529088
11:17:31 820 8192000000 10007300400 10374213632 1038244864 11.248 100 95 187 1 1127687616 71549680 17893072
11:18:33 898 8192000000 10017309888 10397450240 1049189792 11.353 100 99 189 0 1139266352 70909056 19167504
11:19:37 834 8192000000 10055388944 10426826752 1061376928 11.470 100 95 187 0 1174770768 93552896 19840944
11:20:40 1323 8192000000 10039859648 10421755904 1067974416 11.533 100 89 173 0 1158195040 72860160 17360464
11:21:42 1137 8192000000 10058143072 10439909376 1073195216 11.583 100 99 191 0 1175891856 83317312 19379328
11:22:44 1089 8192000000 10035513920 10421714944 1083903664 11.685 100 95 183 1 1158995520 56475376 18616480
11:23:46 1160 8192000000 10077637808 10467741696 1091474176 11.757 100 94 181 1 1197629760 87902352 18253232
11:24:48 1283 8192000000 10075828048 10462269440 1094475824 11.785 100 99 197 3 1197643616 84246496 18921296
11:25:51 1104 8192000000 10073094800 10465406976 1098504416 11.823 100 89 167 1 1194341504 79885840 15951248
11:26:52 1619 8192000000 10032498912 10429841408 1087431472 11.718 100 61 114 1 1154878384 55614032 11832880
11:27:54 5514 8192000000 10055314144 10450956288 1096414800 11.804 100 65 124 0 1182064144 73261552 12387792
11:29:01 973 8192000000 10044061440 10434207744 1104298064 11.878 100 92 182 0 1171359584 49065680 17995840
11:30:03 1319 8192000000 10061113088 10459004928 1099455840 11.832 100 71 136 1 1185173952 72527072 13191040
11:31:05 997 8192000000 10024121888 10406477824 1074778640 11.598 100 94 176 5 1143851280 49863696 19208944
11:32:07 790 8192000000 10006845200 10390376448 1067763120 11.531 100 82 158 0 1135095552 49182464 18149968
11:33:09 1294 8192000000 9995199680 10363076608 1029966560 11.168 99 93 178 0 1119813712 72058240 17788912
11:34:11 1299 8192000000 10001019152 10377023488 1051136352 11.372 100 99 184 2 1123601856 52180288 20285216
11:35:14 1081 8192000000 9991820832 10368401408 1033671584 11.204 99 88 159 3 1112283840 62872992 15739264
11:36:16 1525 8192000000 9990149200 10363764736 1034647536 11.213 99 72 134 1 1111709424 64011664 13050224
11:37:18 36358 8192000000 9982277808 10368532480 1041636656 11.280 98 48 89 0 1091326608 41167280 8522672
11:39:00 1359 8192000000 10036048816 10421112832 1078831056 11.636 100 96 191 1 1157063392 57599616 20632720
11:40:08 12274 8192000000 10060145440 10459000832 1093013680 11.771 100 58 117 0 1178438864 73976032 11449152
11:41:27 4136 8192000000 10107668256 10493759488 1108911328 11.922 100 84 162 3 1224248800 98017936 17319536
11:42:34 1140 8192000000 10158619744 10542841856 1115992832 11.989 100 96 179 4 1266486688 131236992 19256864
11:43:37 19434 8192000000 10179673856 10585706496 1129303568 12.115 100 67 133 2 1302276032 158674320 14298144
11:45:02 1089 8192000000 10187658800 10591940608 1146891888 12.280 100 97 186 2 1301388512 133084048 21412576
11:46:08 1106 8192000000 10237838672 10635812864 1152181056 12.330 100 93 178 0 1354095632 181627776 20286800
11:47:13 1822 8192000000 10228576896 10637803520 1162330608 12.425 100 65 125 0 1338902304 162015856 14555840
11:48:17 4216 8192000000 10241701952 10650578944 1171088240 12.507 100 66 119 2 1355465744 170425232 13952272
11:49:26 1069 8192000000 10313601712 10720673792 1182287888 12.612 100 94 175 1 1419404240 219314240 17802112
11:50:31 909 8192000000 10348722848 10749120512 1183218912 12.620 99 96 188 2 1459202864 255540816 20443136
11:51:36 2690 8192000000 10401147056 10811895808 1189417584 12.678 100 83 159 0 1505229968 297019376 18793008
11:52:44 1388 8192000000 10435487376 10839560192 1199492928 12.772 100 90 169 1 1539792224 321138480 19160816
11:53:48 948 8192000000 10500050176 10921144320 1214688032 12.913 100 98 192 0 1605704416 367376880 23639504
11:54:50 1067 8192000000 10483621760 10922655744 1193182384 12.713 100 89 171 1 1584425840 371149552 20093904
11:55:54 1932 8192000000 10501966768 10915659776 1212531408 12.893 100 90 176 1 1609776016 375330560 21914048
11:56:58 782 8192000000 10531777552 10950815744 1210603136 12.875 100 94 185 0 1636995680 405143056 21249488
11:58:00 2591 8192000000 10554208160 10967568384 1196452960 12.743 100 90 180 0 1657872896 439944288 21475648
11:59:08 1143 8192000000 10588276144 11043307520 1210875168 12.877 100 95 182 1 1691064624 456048000 24141456
12:00:14 880 8192000000 10620926960 11086839808 1216016096 12.925 100 95 187 0 1724235280 484078192 24140992
12:01:17 5710 8192000000 10594954432 11058958336 1203545968 12.809 100 71 142 1 1687062304 465813888 17702448
12:02:35 2382 8192000000 10624014912 11096133632 1206545792 12.837 100 77 147 0 1724880160 500162336 18172032
12:03:44 10556 8192000000 10595974512 11047673856 1176276384 12.555 99 51 102 0 1683117600 493459456 13381760
12:05:15 15617 8192000000 10574431328 11057356800 1175898448 12.552 100 33 66 0 1679469504 494244944 9326112
12:06:35 17437 8192000000 10502030464 11026710528 1160408848 12.407 100 29 58 0 1642352288 473212496 8730944
12:08:10 36783 8192000000 10243455744 10726924288 1123018560 12.055 98 19 36 0 1354466432 226963888 4483984
12:09:51 22598 8192000000 10061252496 10509922304 1060996736 11.466 93 36 57 1 1113203056 46399184 5807136
12:11:17 20308 8192000000 10104567504 10531205120 1127166160 12.095 99 44 84 1 1202054416 66120832 8767424
12:12:51 76231 8192000000 10078779184 10609913856 1131696128 12.137 99 9 18 0 1239661216 106034928 1930160
12:15:54 71215 8192000000 10091888816 10543661056 1121147520 12.038 98 30 58 0 1153121920 26076432 5897968
12:18:07 49149 8192000000 10129341296 10583158784 1155926464 12.365 100 31 60 1 1204970208 42873712 6170032
12:20:34 130860 8192000000 10081901280 10571337728 1115335312 11.983 96 13 26 0 1146108592 28077936 2695344
12:23:55 75024 8192000000 10078566784 10549456896 1105857328 11.893 96 7 12 1 1125476928 18641136 978464
12:26:13 33958 8192000000 10102460512 10545885184 1131509712 12.136 98 35 70 0 1177886656 39000928 7376016
12:27:57 129871 8192000000 10116538416 10562703360 1141280464 12.228 99 38 70 0 1191983856 43323200 7380192
12:31:08 21326 8192000000 10104710736 10546352128 1130452944 12.126 98 37 70 0 1198246592 60847632 6946016
12:32:32 1020 8192000000 10100749216 10529792000 1146134320 12.273 100 73 137 4 1198448176 36646752 15667104
12:33:36 9265 8192000000 10097354048 10531803136 1142017392 12.235 99 60 104 2 1191102880 39092384 9993104
12:34:48 46046 8192000000 10138554752 10603720704 1147626688 12.287 99 43 85 1 1247463392 90078240 9758464
12:36:37 27924 8192000000 10074039056 10552463360 1134059696 12.160 99 9 18 0 1176321088 40312800 1948592
12:38:16 57027 8192000000 10105390288 10548678656 1098571440 11.824 96 14 26 1 1158652128 57121856 2958832
12:40:14 72216 8192000000 10093962336 10641715200 1131160224 12.132 100 11 22 0 1244912352 111430768 2321360
12:42:32 13000 8192000000 10091790688 10525413376 1130826032 12.129 100 49 95 0 1183222048 42308000 10088016
12:43:48 47038 8192000000 10122557360 10595336192 1104847040 11.884 97 29 56 0 1198461648 87851888 5762720
12:45:40 54452 8192000000 10139844832 10590588928 1131585440 12.136 99 20 40 0 1222180416 85885504 4709472
12:47:35 48677 8192000000 10094851936 10533695488 1121342656 12.040 98 26 49 0 1179033248 52484432 5206160
12:49:25 56707 8192000000 10114066304 10572324864 1104210576 11.878 97 28 56 0 1168152272 58315840 5625856
12:51:22 83446 8192000000 10100227712 10540560384 1123471840 12.060 99 32 64 1 1187592640 57772768 6348032
12:54:43 140329 8192000000 10061282832 10517463040 1091441536 11.756 96 35 68 0 1152079024 53477872 7159616
12:58:04 50684 8192000000 10085076960 10533224448 1100868464 11.846 97 26 49 0 1179091728 73314864 4908400
13:00:03 73423 8192000000 10138571424 10582544384 1132597616 12.146 100 17 34 0 1226676272 90192704 3885952
13:02:27 85208 8192000000 10126721392 10603696128 1105041440 11.885 98 15 30 0 1237793536 128380752 4371344
13:04:54 33295 8192000000 10054952144 10499575808 1127611424 12.099 100 28 53 1 1169939984 37137424 5191136
13:06:28 104347 8192000000 10098419568 10553159680 1129274208 12.115 100 25 50 0 1201935584 67330224 5331152
13:09:32 73783 8192000000 10075208464 10534793216 1093397600 11.775 97 25 50 0 1170065488 71925712 4742176
13:12:13 88625 8192000000 10087060960 10559483904 1134511152 12.164 100 23 46 0 1230349472 91229024 4609296
13:14:42 75239 8192000000 10106266160 10559533056 1135913696 12.177 100 24 48 0 1181556064 40541520 5100848
13:17:02 28426 8192000000 10115059712 10562162688 1124894160 12.073 99 32 64 0 1182951312 51735440 6321712
13:18:31 89543 8192000000 10078963216 10552131584 1125869232 12.082 99 32 60 1 1180291552 47960608 6461712
13:21:05 67218 8192000000 10060205856 10535243776 1125911968 12.083 99 22 45 0 1162896800 32470560 4514272
13:23:13 47732 8192000000 10069443472 10529878016 1118770352 12.015 98 14 28 0 1141602016 19706784 3124880
13:25:01 83124 8192000000 10071170400 10533654528 1117328368 12.002 98 13 26 0 1166354160 45964464 3061328
13:27:39 65553 8192000000 10096276736 10551099392 1097434512 11.813 97 11 22 0 1151252688 51476272 2341904
13:30:47 49405 8192000000 10059932112 10529005568 1103581472 11.872 97 35 70 0 1185274784 74300400 7392912
13:32:39 22877 8192000000 10095187696 10554507264 1096170656 11.801 97 38 76 0 1181680752 77797760 7712336
13:34:10 44666 8192000000 10077168736 10528530432 1126947632 12.093 100 31 58 1 1195218096 61490304 6780160
13:35:58 8890 8192000000 10100084592 10530414592 1132570128 12.146 100 51 102 0 1181227136 38520704 10136304
13:37:10 25693 8192000000 9113684816 9390678016 253629696 3.003 23 6 12 0 275588848 20930704 1028448

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 25-12-2022 13:38:23 to 25-12-2022 13:38:39.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 113 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 25-12-2022 13:38:40 to 25-12-2022 13:38:42.

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          221214
MON$OLDEST_ACTIVE               222256
MON$OLDEST_SNAPSHOT             222256
MON$NEXT_TRANSACTION            222256
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                       2063776
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             775
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2885 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2885 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2885 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2885 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 ---
:484: 
:485: mscs-test-161.budzdorov.ru    Sun Dec 25 13:37:37 2022
:486:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:487: 
--- end of diff output ---

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

25.12.22 13:38:43. There are 3 logs in /var/tmp/logs-oltp40 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2022-12-25 13:38:46 No severe PSQL-related problems occured
25.12.22 13:38:47. End of report.