Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 05.06.2022 07: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 15670624 kB
MemAvailable 31359848 kB
Buffers 0 kB
Cached 16055620 kB
SwapCached 2448 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-05 07:37:16
DTS_END                         2022-06-05 07:37:16.4960
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/35912
TRN_ID                          214621
ATT_ID                          660
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: 9103.28 30510 1092394
customer order: creation 169.59 516 20351
customer order: refuse 14.16 507 1699
order to supplier: creation 44.33 1475 5320
order to supplier: removal 3.47 187318 416
invoice (draft): creation 44.68 3370 5361
invoice (draft): removal 51.89 1149 6227
invoice accept: apply 41.37 26565 4964
invoice accept: cancel 46.32 1381 5558
customer reserve: creation 3826.17 249 459140
customer reserve: removal 4574.43 109 548931
realization accept: apply 80.84 310 9701
realization accept: cancel 79.17 175 9500
payment from customer: creation 57.45 202 6894
payment from customer: removal 5.05 172 606
payment to supplier: creation 48.07 216 5768
payment to supplier: removal 3.58 151 429
service: total inventory turnovers 4.20 4614 504
service: total monetary turnovers 7.61 458 913
service: aggregate perf. data 0.89 22212 107
service: refresh index statistics 0.04 359057 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 05-06-2022 07:39:35 to 05-06-2022 07:39:37.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7698
TEST_TIME 2 5834
TEST_TIME 3 6190
TEST_TIME 4 5780
TEST_TIME 5 6371
TEST_TIME 6 6602
TEST_TIME 7 6537
TEST_TIME 8 6641
TEST_TIME 9 6812
TEST_TIME 10 7030
TEST_TIME 11 7290
TEST_TIME 12 7428
TEST_TIME 13 7432
TEST_TIME 14 7732
TEST_TIME 15 7803
TEST_TIME 16 7803
TEST_TIME 17 7823
TEST_TIME 18 7782
TEST_TIME 19 7849
TEST_TIME 20 7864
TEST_TIME 21 7775
TEST_TIME 22 7986
TEST_TIME 23 7891
TEST_TIME 24 7819
TEST_TIME 25 7875
TEST_TIME 26 7804
TEST_TIME 27 7924
TEST_TIME 28 8252
TEST_TIME 29 8327
TEST_TIME 30 8178
TEST_TIME 31 8236
TEST_TIME 32 8416
TEST_TIME 33 8892
TEST_TIME 34 9065
TEST_TIME 35 9284
TEST_TIME 36 9333
TEST_TIME 37 9373
TEST_TIME 38 9732
TEST_TIME 39 10210
TEST_TIME 40 10247
TEST_TIME 41 10362
TEST_TIME 42 10531
TEST_TIME 43 10746
TEST_TIME 44 10575
TEST_TIME 45 10504
TEST_TIME 46 10425
TEST_TIME 47 10298
TEST_TIME 48 10217
TEST_TIME 49 10354
TEST_TIME 50 10259
TEST_TIME 51 10159
TEST_TIME 52 10135
TEST_TIME 53 10207
TEST_TIME 54 10214
TEST_TIME 55 10082
TEST_TIME 56 9947
TEST_TIME 57 9863
TEST_TIME 58 9820
TEST_TIME 59 9798
TEST_TIME 60 9677
TEST_TIME 61 9633
TEST_TIME 62 9602
TEST_TIME 63 9573
TEST_TIME 64 9467
TEST_TIME 65 9510
TEST_TIME 66 9481
TEST_TIME 67 9555
TEST_TIME 68 9546
TEST_TIME 69 9572
TEST_TIME 70 9529
TEST_TIME 71 9433
TEST_TIME 72 9438
TEST_TIME 73 9469
TEST_TIME 74 9426
TEST_TIME 75 9450
TEST_TIME 76 9483
TEST_TIME 77 9435
TEST_TIME 78 9381
TEST_TIME 79 9422
TEST_TIME 80 9397
TEST_TIME 81 9526
TEST_TIME 82 9611
TEST_TIME 83 9578
TEST_TIME 84 9646
TEST_TIME 85 9592
TEST_TIME 86 9528
TEST_TIME 87 9465
TEST_TIME 88 9496
TEST_TIME 89 9421
TEST_TIME 90 9392
TEST_TIME 91 9395
TEST_TIME 92 9334
TEST_TIME 93 9314
TEST_TIME 94 9262
TEST_TIME 95 9196
TEST_TIME 96 9211
TEST_TIME 97 9262
TEST_TIME 98 9302
TEST_TIME 99 9264
TEST_TIME 100 9251
TEST_TIME 101 9267
TEST_TIME 102 9303
TEST_TIME 103 9325
TEST_TIME 104 9324
TEST_TIME 105 9327
TEST_TIME 106 9380
TEST_TIME 107 9366
TEST_TIME 108 9414
TEST_TIME 109 9400
TEST_TIME 110 9445
TEST_TIME 111 9443
TEST_TIME 112 9369
TEST_TIME 113 9361
TEST_TIME 114 9329
TEST_TIME 115 9312
TEST_TIME 116 9277
TEST_TIME 117 9248
TEST_TIME 118 9218
TEST_TIME 119 9177
TEST_TIME 120 9126

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 05-06-2022 07:39:39 to 05-06-2022 07: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 1516054 1516054 0 0.00 0 22127 14 0 0
doc_list_biud 1516045 1516045 0 0.00 0 33666 46 0 0
sp_add_invoice_to_stock 5093 4964 129 2.53 65 170278 26566 0 129
sp_cancel_adding_invoice 5558 5558 0 0.00 5 92603 1382 0 0
sp_cancel_client_order 1699 1699 0 0.00 6 24208 508 0 0
sp_cancel_customer_reserve 548931 548931 0 0.00 1 33705 109 0 0
sp_cancel_pay_from_customer 606 606 0 0.00 4 11468 173 0 0
sp_cancel_pay_to_supplier 429 429 0 0.00 4 7656 151 0 0
sp_cancel_supplier_invoice 6241 6227 14 0.22 3 25325 1149 0 14
sp_cancel_supplier_order 416 416 0 0.00 12 1509175 187318 0 0
sp_cancel_write_off 9500 9500 0 0.00 2 23073 175 0 0
sp_client_order 20351 20351 0 0.00 6 44167 516 0 0
sp_customer_reserve 459140 459140 0 0.00 1 92059 249 0 0
sp_fill_shopping_cart 31011 31011 0 0.00 1 12420 89 0 0
sp_fill_shopping_cart_clo_res 459104 459104 0 0.00 0 14955 20 0 0
sp_get_clo_for_invoice 4923 4923 0 0.00 0 27005 270 0 0
sp_kill_cost_storno 16093 16093 0 0.00 0 7691 90 0 0
sp_kill_qstorno_ret_qs2qd 1114005 1114005 0 0.00 0 33660 47 0 0
sp_kill_qty_storno 586953 586953 0 0.00 0 33663 110 0 0
sp_lock_selected_doc 20243 20243 0 0.00 0 7846 10 0 0
sp_make_cost_storno 27286 27286 0 0.00 0 11821 91 0 0
sp_multiply_rows_for_pdistr 27284 27284 0 0.00 0 7507 43 0 0
sp_multiply_rows_for_qdistr 20350 20350 0 0.00 0 13026 174 0 0
sp_pay_from_customer 6894 6894 0 0.00 5 12427 202 0 0
sp_pay_to_supplier 5768 5768 0 0.00 6 10806 216 0 0
sp_payment_common 12662 12662 0 0.00 2 11718 49 0 0
sp_qd_handle_on_cancel_clo 1699 1699 0 0.00 1 23085 423 0 0
sp_qd_handle_on_invoice_upd_sts 10481 10481 0 0.00 1 23852 629 0 0
sp_qd_handle_on_reserve_upd_sts 9699 9699 0 0.00 0 24516 81 0 0
sp_reserve_write_off 9701 9701 0 0.00 9 28305 311 0 0
sp_supplier_invoice 5361 5361 0 0.00 10 75581 3370 0 0
sp_supplier_order 5320 5320 0 0.00 7 29833 1476 0 0
srv_aggregate_perf_data 107 107 0 0.00 2739 264291 22213 0 0
srv_fill_mon_cache_memory 74 74 0 0.00 765 141844 31522 0 0
srv_find_qd_qs_mism 1044668 1044668 0 0.00 0 22126 9 0 0
srv_make_invnt_saldo 504 504 0 0.00 92 102651 4614 0 0
srv_make_money_saldo 913 913 0 0.00 1 29258 459 0 0
srv_recalc_idx_stat 5 5 0 0.00 94969 467712 359058 0 0
t$perf-abend:sp_add_invoice_to_stock 129 129 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4971 4971 0 0.00 0 35297 1146 0 0
t$perf-norm:sp_cancel_adding_invoice 389 389 0 0.00 1 6996 566 0 0
t$perf-norm:sp_cancel_client_order 1699 1699 0 0.00 0 9375 37 0 0
t$perf-norm:sp_cancel_customer_reserve 696 696 0 0.00 0 2643 36 0 0
t$perf-norm:sp_cancel_pay_from_customer 606 606 0 0.00 0 2245 24 0 0
t$perf-norm:sp_cancel_pay_to_supplier 429 429 0 0.00 0 982 17 0 0
t$perf-norm:sp_cancel_supplier_invoice 414 414 0 0.00 0 1706 47 0 0
t$perf-norm:sp_cancel_supplier_order 416 416 0 0.00 0 105215 8806 0 0
t$perf-norm:sp_cancel_write_off 978 978 0 0.00 0 3655 31 0 0
t$perf-norm:sp_client_order 20351 20351 0 0.00 0 13647 48 0 0
t$perf-norm:sp_customer_reserve 994 994 0 0.00 0 2663 20 0 0
t$perf-norm:sp_pay_from_customer 6894 6894 0 0.00 0 15767 23 0 0
t$perf-norm:sp_pay_to_supplier 5768 5768 0 0.00 0 13428 29 0 0
t$perf-norm:sp_reserve_write_off 9701 9701 0 0.00 0 21561 30 0 0
t$perf-norm:sp_supplier_invoice 5361 5361 0 0.00 0 13990 42 0 0
t$perf-norm:sp_supplier_order 5320 5320 0 0.00 0 7599 43 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 2104 33 0 0
t$perf-norm:srv_fill_mon_cache_memory 74 74 0 0.00 0 750 17 0 0
t$perf-norm:srv_make_invnt_saldo 504 504 0 0.00 0 2963 26 0 0
t$perf-norm:srv_make_money_saldo 913 913 0 0.00 0 3290 11 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 4 215 70 0 0
v_add_invoice_to_stock 10092 9834 258 2.55 0 10379 34 0 258
v_all_customers 36850 36850 0 0.00 0 82 4 0 0
v_all_suppliers 29866 29866 0 0.00 0 64 5 0 0
v_all_wares 40690 40690 0 0.00 0 233 4 0 0
v_cancel_adding_invoice 778 778 0 0.00 0 2521 18 0 0
v_cancel_client_order 3398 3398 0 0.00 0 5388 12 0 0
v_cancel_customer_prepayment 1212 1212 0 0.00 0 5399 17 0 0
v_cancel_customer_reserve 1392 1392 0 0.00 0 1694 13 0 0
v_cancel_payment_to_supplier 858 858 0 0.00 0 947 13 0 0
v_cancel_supplier_invoice 856 828 28 3.27 0 3980 35 0 28
v_cancel_supplier_order 832 832 0 0.00 0 2489 14 0 0
v_cancel_write_off 1956 1956 0 0.00 0 2203 25 0 0
v_max_id_clo_ord 5311 5311 0 0.00 0 333 6 0 0
v_max_id_clo_res 994 994 0 0.00 0 1289 13 0 0
v_max_id_ord_sup 5355 5355 0 0.00 0 5631 7 0 0
v_max_non_paid_invoice 1501 1501 0 0.00 0 424 9 0 0
v_max_non_paid_realizn 3611 3611 0 0.00 0 1273 8 0 0
v_min_id_clo_ord 5311 5311 0 0.00 0 2027 9 0 0
v_min_id_clo_res 994 994 0 0.00 0 394 8 0 0
v_min_id_ord_sup 5355 5355 0 0.00 0 3986 8 0 0
v_min_non_paid_invoice 5768 5768 0 0.00 0 9831 17 0 0
v_min_non_paid_realizn 6894 6894 0 0.00 0 6983 16 0 0
v_our_firm 10406 10406 0 0.00 0 54 5 0 0
v_reserve_write_off 19398 19398 0 0.00 0 9356 11 0 0
x_lock_depdocs_on_canc_invoice 11371 11371 0 0.00 0 15999 140 0 0
x_lock_depdocs_on_canc_sup_ord 416 416 0 0.00 0 15960 479 0 0
x_make_qstorno_1000_1200 5320 5320 0 0.00 2 29820 1278 0 0
x_make_qstorno_1200_2000 5361 5361 0 0.00 5 75467 3137 0 0
x_make_qstorno_2100_3300 459138 459138 0 0.00 0 92058 215 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 05-06-2022 07:39:43 to 05-06-2022 07: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
05:07:20 26 8192000000 8812398960 8828612608 14289936 0.174 5 4 6 0 14557552 145424 122192
05:08:21 19088 8192000000 9713138144 10008940544 793868848 8.834 100 85 170 1 822950832 8201200 20880784
05:09:42 18998 8192000000 9757565552 10064359424 831130768 9.211 100 88 176 0 863678576 11425504 21122304
05:11:10 14185 8192000000 9796412640 10114715648 863495392 9.535 100 80 160 0 900605504 18879568 18230544
05:12:28 9803 8192000000 9839122912 10168156160 899678432 9.895 100 82 164 0 954690992 37063584 17948976
05:13:41 9938 8192000000 9902631568 10239844352 934778048 10.242 100 88 174 1 1014485504 60861056 18846400
05:14:57 3471 8192000000 9932391632 10280951808 971007440 10.597 100 83 163 0 1048237952 59916608 17313904
05:16:05 2973 8192000000 9963880224 10324979712 1002320448 10.901 100 80 158 1 1090483328 71984704 16178176
05:17:11 794 8192000000 10001840320 10371174400 1025450864 11.125 100 93 187 0 1123799472 79461552 18887056
05:18:13 1092 8192000000 10016912288 10385223680 1046766720 11.330 100 96 188 1 1138975696 73546112 18662864
05:19:17 751 8192000000 10041986544 10413621248 1054082176 11.400 100 98 183 2 1159938384 87657008 18199200
05:20:19 1530 8192000000 10028381616 10406768640 1060384512 11.460 100 88 168 1 1148234096 70872976 16976608
05:21:21 1108 8192000000 10057583984 10440482816 1063296368 11.488 100 96 185 0 1182895840 100960848 18638624
05:22:25 1181 8192000000 10054138976 10436182016 1075974208 11.609 100 97 189 2 1177976256 82635232 19366816
05:23:27 1176 8192000000 10042876048 10430730240 1081033424 11.657 100 95 182 0 1159795440 60299040 18462976
05:24:30 1311 8192000000 10074648000 10460602368 1089383936 11.737 100 95 187 0 1195038640 86868976 18785728
05:25:33 2507 8192000000 10064249184 10451271680 1092232752 11.764 100 78 155 0 1181912448 74630608 15049088
05:26:36 1427 8192000000 10072648992 10460413952 1092191040 11.763 100 98 196 3 1195728400 83707472 19829888
05:27:38 1114 8192000000 10065308464 10456674304 1096582448 11.805 100 93 176 0 1186641504 71186960 18872096
05:28:42 1081 8192000000 10066713200 10455568384 1100601168 11.843 100 94 182 1 1186708784 66877424 19230192
05:29:46 939 8192000000 10061618192 10454200320 1088569936 11.729 100 83 157 3 1187095504 81886832 16638736
05:30:48 1017 8192000000 10060963840 10450354176 1092109728 11.763 100 91 178 3 1179044592 69240160 17694704
05:31:51 1081 8192000000 10041859824 10423390208 1077684176 11.625 100 96 178 0 1162382688 66539840 18158672
05:32:53 1233 8192000000 10030404544 10410254336 1066784736 11.521 100 96 176 0 1150880192 66206576 17888880
05:33:55 1261 8192000000 10025301984 10409439232 1062565424 11.481 100 93 184 2 1151356192 68396688 20394080
05:34:57 1064 8192000000 10017676032 10392555520 1057005984 11.428 100 95 189 0 1139612432 62422672 20183776
05:35:59 1397 8192000000 9994574064 10368028672 1034230704 11.209 100 91 173 1 1118690592 67300592 17159296
05:37:02 2816 8192000000 9972893296 10349547520 1012764320 11.002 97 69 124 0 1071444416 46158048 12522048
05:38:14 14970 8192000000 10003999888 10382561280 1063342400 11.488 100 79 143 2 1126547376 49536224 13668752
05:39:33 5178 8192000000 10040873328 10423103488 1076105024 11.610 100 68 135 1 1159587936 69843168 13639744
05:40:45 902 8192000000 10067900384 10448293888 1088875568 11.732 100 91 174 0 1182612992 76082336 17655088
05:41:50 1415 8192000000 10130057904 10511642624 1093494416 11.776 100 89 176 1 1244272800 133114160 17664224
05:42:55 5669 8192000000 10118728656 10521808896 1105170416 11.887 100 71 138 1 1239473520 120437648 13865456
05:44:04 1733 8192000000 10126237472 10529869824 1118766096 12.015 100 90 170 1 1246751104 109371696 18613312
05:45:09 2414 8192000000 10148715664 10547269632 1127331152 12.096 100 78 146 1 1263752960 121095904 15325904
05:46:17 1079 8192000000 10167643984 10563051520 1135960464 12.178 100 88 169 0 1279736112 126427760 17347888
05:47:22 984 8192000000 10202471408 10599464960 1138412016 12.201 100 94 187 0 1312606352 154026512 20167824
05:48:26 1590 8192000000 10209129744 10620710912 1145510912 12.267 100 88 169 1 1325809680 159476288 20822480
05:49:31 1666 8192000000 10222858880 10626809856 1157308912 12.378 100 92 178 1 1337160080 158801536 21049632
05:50:36 5650 8192000000 10228469776 10651254784 1164799728 12.448 100 73 142 2 1339339456 158416416 16123312
05:51:44 968 8192000000 10260084064 10668060672 1181573648 12.605 100 96 185 1 1368627424 165832960 21220816
05:52:48 997 8192000000 10300395264 10711871488 1184015552 12.628 100 95 184 0 1412121152 207317216 20788384
05:53:53 948 8192000000 10299739488 10718937088 1187125200 12.657 100 92 177 0 1412246512 204618784 20502528
05:54:59 1602 8192000000 10336910832 10741002240 1177566432 12.567 100 94 180 0 1447074016 247859728 21647856
05:56:05 1198 8192000000 10375142656 10784776192 1186134944 12.647 100 95 186 0 1480302576 272736672 21430960
05:57:09 1152 8192000000 10397318832 10808594432 1186758912 12.653 100 91 178 0 1506586656 299858096 19969648
05:58:15 2119 8192000000 10437696320 10867515392 1201380704 12.789 100 95 188 0 1539523888 315882624 22260560
05:59:23 919 8192000000 10473178272 10906689536 1205636736 12.829 100 97 188 1 1577910272 350347552 21925984
06:00:27 1002 8192000000 10493572736 10902573056 1207371008 12.845 100 99 196 1 1594852288 363943296 23537984
06:01:33 1065 8192000000 10547928384 10971283456 1211563568 12.884 100 92 184 0 1652417968 418215824 22638576
06:02:43 3622 8192000000 10541473984 10963378176 1190727536 12.690 99 82 156 3 1638404016 428315648 19360832
06:03:51 871 8192000000 10528168464 10965438464 1170723328 12.504 99 90 181 0 1631270400 438081024 22466048
06:04:56 854 8192000000 10535640256 11005816832 1184777696 12.635 100 97 194 2 1636594960 427678272 24138992
06:05:59 1335 8192000000 10552951808 10989367296 1191113296 12.694 100 78 156 0 1658213712 447313440 19786976
06:07:03 7964 8192000000 10577747600 10998988800 1191341600 12.696 100 62 122 0 1682864416 476512272 15010544
06:08:17 760 8192000000 10620922672 11087257600 1198376464 12.761 100 96 182 1 1716236096 495147616 22712016
06:09:20 7233 8192000000 10620803552 11061551104 1196440208 12.743 100 61 122 0 1719345312 506972928 15932176
06:10:35 8834 8192000000 10531630048 11048275968 1190552368 12.689 100 52 100 0 1632165696 427852480 13760848
06:11:47 15274 8192000000 10514830832 10965213184 1183789168 12.626 100 44 86 0 1608953648 413399168 11765312
06:13:05 10554 8192000000 10480575952 10965512192 1174185296 12.536 100 36 72 0 1579830480 396166512 9478672
06:14:18 8287 8192000000 10467932656 10927734784 1170623792 12.503 100 39 78 0 1584207120 404095216 9488112
06:15:28 14977 8192000000 10368754512 10912915456 1172275360 12.518 100 39 76 2 1468103536 286161856 9666320
06:16:44 14455 8192000000 10249169088 10711592960 1160933072 12.412 100 36 70 0 1364515088 195685280 7896736
06:18:24 18339 8192000000 10218437984 10715619328 1150219760 12.312 99 21 42 0 1310104368 155414640 4469968
06:19:46 44763 8192000000 10099378368 10673414144 1137948992 12.196 99 15 29 0 1276785312 135774960 3061360
06:21:31 33414 8192000000 10068238224 10497368064 1130928816 12.130 99 65 128 3 1175370384 29594400 14847168
06:23:13 84404 8192000000 10084275088 10540351488 1097366400 11.813 96 19 38 0 1136027808 34833984 3827424
06:25:39 73790 8192000000 10069364464 10585055232 1105575872 11.891 98 21 42 1 1197295872 86668240 5051760
06:27:56 44385 8192000000 10074381904 10509529088 1112423184 11.955 98 24 43 1 1149082800 31945952 4713664
06:29:41 107741 8192000000 10094512256 10530988032 1103941952 11.875 97 14 28 2 1184500336 77417632 3140752
06:33:02 74030 8192000000 10085090928 10525077504 1141845504 12.233 100 34 59 1 1184912256 37737296 5329456
06:35:17 59866 8192000000 10101968208 10541146112 1133160592 12.151 99 49 95 3 1183311584 40045040 10105952
06:37:20 51585 8192000000 10106788560 10551373824 1146861392 12.280 100 43 84 1 1199899424 43702608 9335424
06:39:23 36712 8192000000 10117215808 10567319552 1126437456 12.088 98 22 44 1 1194356960 63144048 4775456
06:41:02 13797 8192000000 10159826064 10588950528 1156340912 12.369 100 58 113 2 1247599184 78067632 13190640
06:42:17 32626 8192000000 10183463472 10647867392 1137313104 12.190 100 42 82 1 1272473632 126789312 8371216
06:43:51 47393 8192000000 10164370800 10652684288 1140977328 12.225 100 10 20 0 1249921504 106410192 2533984
06:45:44 141844 8192000000 10053495232 10536398848 1113497216 11.966 97 10 20 0 1158976896 43539696 1939984
06:49:44 70951 8192000000 10068319776 10555797504 1090911232 11.751 95 18 30 3 1152928704 59890160 2127312
06:51:57 40824 8192000000 10111478272 10567053312 1118712048 12.015 98 25 50 2 1189940288 65820704 5407536
06:53:43 61970 8192000000 10133659808 10584440832 1114761904 11.977 97 20 40 0 1185134016 65904624 4467488
06:55:46 25140 8192000000 10151548640 10617020416 1113609408 11.967 97 56 100 2 1220385424 96439712 10336304
06:57:13 40815 8192000000 10156315488 10618277888 1108232384 11.916 98 34 68 2 1249369120 133395760 7740976
06:58:58 56037 8192000000 10087697312 10564288512 1111394432 11.946 98 14 28 0 1187181776 72850064 2937280
07:00:56 35184 8192000000 10090140720 10553888768 1117287456 12.001 99 41 82 1 1154377360 28364624 8725280
07:02:37 11830 8192000000 10095640432 10517094400 1117734096 12.006 99 54 103 0 1174664496 46287872 10642528
07:03:49 102099 8192000000 10105723328 10573602816 1123985632 12.065 100 21 42 1 1221479392 92824256 4669504
07:06:39 10598 8192000000 10043145024 10473197568 1099765904 11.835 98 47 89 0 1125649712 17446016 8437792
07:07:55 44230 8192000000 10085187968 10538967040 1112989296 11.961 99 32 62 1 1194465840 75201440 6275104
07:09:44 57879 8192000000 10074898688 10537889792 1076393040 11.613 96 33 65 4 1175530208 92433024 6704144
07:12:14 79951 8192000000 10137853408 10614296576 1071276112 11.564 96 15 30 2 1197243840 122202240 3765488
07:15:20 119796 8192000000 10119781184 10560565248 1105199088 11.887 98 13 26 0 1151474864 43834672 2441104
07:18:39 50496 8192000000 10106074704 10571718656 1103079408 11.867 97 35 68 1 1184085664 74247008 6759248
07:20:33 15233 8192000000 10083873696 10507288576 1115407248 11.984 99 59 111 2 1163251168 36035440 11808480
07:21:55 22951 8192000000 10097693136 10556694528 1110324560 11.935 99 38 76 0 1196447856 78481984 7641312
07:23:21 70439 8192000000 10098810192 10572890112 1122806624 12.053 99 17 34 0 1209321840 82677472 3837744
07:25:32 62611 8192000000 10108003472 10554392576 1146835968 12.280 100 23 46 1 1200436944 48656608 4944368
07:27:35 138932 8192000000 10077865840 10524618752 1134478256 12.164 99 22 43 2 1170674768 31364688 4831824
07:30:55 24374 8192000000 10071633280 10556596224 1126050080 12.084 100 12 24 1 1173929088 45609088 2269920
07:32:21 24940 8192000000 10065959696 10516426752 1120130704 12.028 99 45 87 2 1159481952 30301888 9049360
07:33:56 75793 8192000000 10084635472 10537050112 1090881360 11.751 96 23 46 2 1150807216 55292448 4633408
07:36:21 74302 8192000000 9796316864 10249056256 870469008 9.605 76 12 24 1 943124512 69947808 2707696

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 05-06-2022 07:39:49 to 05-06-2022 07:40:06.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 129 v_add_invoice_to_stock 335544517
except 14 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 05-06-2022 07:40:07 to 05-06-2022 07:40:08.

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          214620
MON$OLDEST_ACTIVE               216494
MON$OLDEST_SNAPSHOT             216494
MON$NEXT_TRANSACTION            216494
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                       2118656
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.2772 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2772 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2772 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2772 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 ---
:138: 
:139: mscs-test-161.budzdorov.ru    Sun Jun  5 07:37:58 2022
:140:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:141: 
--- end of diff output ---

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

05.06.22 07:40:10. 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-05 07:40:12 No severe PSQL-related problems occured
05.06.22 07:40:13. End of report.