Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 15.12.2022 07:38
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 14732960 kB
MemAvailable 30048120 kB
Buffers 0 kB
Cached 16939268 kB
SwapCached 3412 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-15 07:37:21
DTS_END                         2022-12-15 07:37:21.8220
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/47580
TRN_ID                          230495
ATT_ID                          649
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: 9142.45 30861 1097094
customer order: creation 172.34 468 20681
customer order: refuse 15.36 386 1843
order to supplier: creation 46.11 1320 5533
order to supplier: removal 3.48 164708 418
invoice (draft): creation 46.93 2867 5632
invoice (draft): removal 51.23 877 6147
invoice accept: apply 41.98 24837 5038
invoice accept: cancel 46.78 1204 5614
customer reserve: creation 3866.94 238 464033
customer reserve: removal 4552.36 100 546283
realization accept: apply 85.71 276 10285
realization accept: cancel 77.78 157 9333
payment from customer: creation 61.65 182 7398
payment from customer: removal 6.44 113 773
payment to supplier: creation 49.73 202 5967
payment to supplier: removal 4.08 149 489
service: total inventory turnovers 4.52 3753 542
service: total monetary turnovers 8.04 338 965
service: aggregate perf. data 0.97 17427 116
service: refresh index statistics 0.03 397627 4

Performance in TOTAL, chart


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

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2627
TEST_TIME 2 4281
TEST_TIME 3 4070
TEST_TIME 4 4970
TEST_TIME 5 5610
TEST_TIME 6 5844
TEST_TIME 7 5994
TEST_TIME 8 6318
TEST_TIME 9 6265
TEST_TIME 10 6359
TEST_TIME 11 6738
TEST_TIME 12 6869
TEST_TIME 13 6839
TEST_TIME 14 6844
TEST_TIME 15 7097
TEST_TIME 16 7300
TEST_TIME 17 7363
TEST_TIME 18 7310
TEST_TIME 19 7239
TEST_TIME 20 7373
TEST_TIME 21 7407
TEST_TIME 22 7589
TEST_TIME 23 7654
TEST_TIME 24 7820
TEST_TIME 25 8213
TEST_TIME 26 8603
TEST_TIME 27 8637
TEST_TIME 28 9116
TEST_TIME 29 9241
TEST_TIME 30 9727
TEST_TIME 31 10572
TEST_TIME 32 10902
TEST_TIME 33 10987
TEST_TIME 34 10802
TEST_TIME 35 10817
TEST_TIME 36 10533
TEST_TIME 37 10471
TEST_TIME 38 10452
TEST_TIME 39 10335
TEST_TIME 40 10220
TEST_TIME 41 10391
TEST_TIME 42 10300
TEST_TIME 43 10181
TEST_TIME 44 10111
TEST_TIME 45 10033
TEST_TIME 46 10079
TEST_TIME 47 10043
TEST_TIME 48 10072
TEST_TIME 49 10105
TEST_TIME 50 9947
TEST_TIME 51 9835
TEST_TIME 52 9839
TEST_TIME 53 9831
TEST_TIME 54 9831
TEST_TIME 55 9724
TEST_TIME 56 9733
TEST_TIME 57 9637
TEST_TIME 58 9755
TEST_TIME 59 9678
TEST_TIME 60 9630
TEST_TIME 61 9634
TEST_TIME 62 9757
TEST_TIME 63 9780
TEST_TIME 64 9706
TEST_TIME 65 9759
TEST_TIME 66 9765
TEST_TIME 67 9754
TEST_TIME 68 9625
TEST_TIME 69 9622
TEST_TIME 70 9542
TEST_TIME 71 9550
TEST_TIME 72 9462
TEST_TIME 73 9407
TEST_TIME 74 9348
TEST_TIME 75 9294
TEST_TIME 76 9315
TEST_TIME 77 9269
TEST_TIME 78 9258
TEST_TIME 79 9341
TEST_TIME 80 9305
TEST_TIME 81 9260
TEST_TIME 82 9298
TEST_TIME 83 9328
TEST_TIME 84 9334
TEST_TIME 85 9418
TEST_TIME 86 9361
TEST_TIME 87 9481
TEST_TIME 88 9495
TEST_TIME 89 9542
TEST_TIME 90 9513
TEST_TIME 91 9495
TEST_TIME 92 9498
TEST_TIME 93 9521
TEST_TIME 94 9495
TEST_TIME 95 9497
TEST_TIME 96 9477
TEST_TIME 97 9480
TEST_TIME 98 9494
TEST_TIME 99 9476
TEST_TIME 100 9529
TEST_TIME 101 9511
TEST_TIME 102 9514
TEST_TIME 103 9434
TEST_TIME 104 9427
TEST_TIME 105 9365
TEST_TIME 106 9327
TEST_TIME 107 9303
TEST_TIME 108 9284
TEST_TIME 109 9243
TEST_TIME 110 9231
TEST_TIME 111 9223
TEST_TIME 112 9189
TEST_TIME 113 9204
TEST_TIME 114 9226
TEST_TIME 115 9253
TEST_TIME 116 9238
TEST_TIME 117 9218
TEST_TIME 118 9251
TEST_TIME 119 9216
TEST_TIME 120 9155

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 15-12-2022 07:38:26 to 15-12-2022 07:38:30.

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 1525044 1525044 0 0.00 0 20801 13 0 0
doc_list_biud 1525029 1525029 0 0.00 0 32080 41 0 0
sp_add_invoice_to_stock 5129 5038 91 1.77 44 187322 24837 0 91
sp_cancel_adding_invoice 5614 5614 0 0.00 5 80349 1205 0 0
sp_cancel_client_order 1843 1843 0 0.00 7 7810 386 0 0
sp_cancel_customer_reserve 546283 546283 0 0.00 1 32081 100 0 0
sp_cancel_pay_from_customer 773 773 0 0.00 4 7396 114 0 0
sp_cancel_pay_to_supplier 489 489 0 0.00 4 11741 150 0 0
sp_cancel_supplier_invoice 6160 6147 13 0.21 2 16021 878 0 13
sp_cancel_supplier_order 418 418 0 0.00 6 1269310 164709 0 0
sp_cancel_write_off 9333 9333 0 0.00 2 13855 158 0 0
sp_client_order 20681 20681 0 0.00 5 31710 469 0 0
sp_customer_reserve 464033 464033 0 0.00 1 75331 239 0 0
sp_fill_shopping_cart 31827 31827 0 0.00 1 13417 75 0 0
sp_fill_shopping_cart_clo_res 464014 464014 0 0.00 0 12197 19 0 0
sp_get_clo_for_invoice 5018 5018 0 0.00 0 29167 323 0 0
sp_kill_cost_storno 16209 16209 0 0.00 0 19474 70 0 0
sp_kill_qstorno_ret_qs2qd 1108464 1108464 0 0.00 0 32077 43 0 0
sp_kill_qty_storno 584940 584940 0 0.00 0 32079 98 0 0
sp_lock_selected_doc 21472 21472 0 0.00 0 7521 7 0 0
sp_make_cost_storno 28668 28668 0 0.00 0 20738 79 0 0
sp_multiply_rows_for_pdistr 28666 28666 0 0.00 0 10387 41 0 0
sp_multiply_rows_for_qdistr 20679 20679 0 0.00 0 17121 188 0 0
sp_pay_from_customer 7398 7398 0 0.00 5 15109 182 0 0
sp_pay_to_supplier 5967 5967 0 0.00 6 24214 202 0 0
sp_payment_common 13359 13359 0 0.00 2 10276 44 0 0
sp_qd_handle_on_cancel_clo 1843 1843 0 0.00 1 7763 314 0 0
sp_qd_handle_on_invoice_upd_sts 10631 10631 0 0.00 0 17643 512 0 0
sp_qd_handle_on_reserve_upd_sts 10285 10285 0 0.00 0 21821 58 0 0
sp_reserve_write_off 10285 10285 0 0.00 8 21971 277 0 0
sp_supplier_invoice 5632 5632 0 0.00 8 80400 2867 0 0
sp_supplier_order 5533 5533 0 0.00 8 48230 1321 0 0
srv_aggregate_perf_data 116 116 0 0.00 2297 167572 17428 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 663 146580 36410 0 0
srv_find_qd_qs_mism 1047687 1047687 0 0.00 0 15574 10 0 0
srv_make_invnt_saldo 542 542 0 0.00 59 208309 3754 0 0
srv_make_money_saldo 965 965 0 0.00 1 8104 338 0 0
srv_recalc_idx_stat 4 4 0 0.00 231394 591230 397627 0 0
t$perf-abend:sp_add_invoice_to_stock 91 91 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5041 5041 0 0.00 0 38193 884 0 0
t$perf-norm:sp_cancel_adding_invoice 430 430 0 0.00 0 6697 399 0 0
t$perf-norm:sp_cancel_client_order 1843 1843 0 0.00 0 6158 29 0 0
t$perf-norm:sp_cancel_customer_reserve 686 686 0 0.00 0 1558 27 0 0
t$perf-norm:sp_cancel_pay_from_customer 773 773 0 0.00 0 2357 18 0 0
t$perf-norm:sp_cancel_pay_to_supplier 489 489 0 0.00 0 444 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 394 394 0 0.00 0 696 24 0 0
t$perf-norm:sp_cancel_supplier_order 418 418 0 0.00 0 111777 6624 0 0
t$perf-norm:sp_cancel_write_off 1144 1144 0 0.00 0 894 18 0 0
t$perf-norm:sp_client_order 20682 20682 0 0.00 0 7721 33 0 0
t$perf-norm:sp_customer_reserve 1019 1019 0 0.00 0 894 14 0 0
t$perf-norm:sp_pay_from_customer 7398 7398 0 0.00 0 3760 18 0 0
t$perf-norm:sp_pay_to_supplier 5967 5967 0 0.00 0 3601 18 0 0
t$perf-norm:sp_reserve_write_off 10285 10285 0 0.00 0 5235 19 0 0
t$perf-norm:sp_supplier_invoice 5632 5632 0 0.00 0 3560 29 0 0
t$perf-norm:sp_supplier_order 5534 5534 0 0.00 0 7727 30 0 0
t$perf-norm:srv_aggregate_perf_data 116 116 0 0.00 0 506 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 156 7 0 0
t$perf-norm:srv_make_invnt_saldo 542 542 0 0.00 0 1054 16 0 0
t$perf-norm:srv_make_money_saldo 965 965 0 0.00 0 528 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 314 114 0 0
v_add_invoice_to_stock 10203 10021 182 1.78 0 5707 24 0 182
v_all_customers 38496 38496 0 0.00 0 61 4 0 0
v_all_suppliers 30954 30954 0 0.00 0 98 4 0 0
v_all_wares 41344 41344 0 0.00 0 100 4 0 0
v_cancel_adding_invoice 860 860 0 0.00 0 520 14 0 0
v_cancel_client_order 3686 3686 0 0.00 0 2084 10 0 0
v_cancel_customer_prepayment 1546 1546 0 0.00 0 3431 14 0 0
v_cancel_customer_reserve 1372 1372 0 0.00 0 2988 17 0 0
v_cancel_payment_to_supplier 978 978 0 0.00 0 5602 20 0 0
v_cancel_supplier_invoice 814 788 26 3.19 0 1182 22 0 26
v_cancel_supplier_order 836 836 0 0.00 0 1902 13 0 0
v_cancel_write_off 2288 2288 0 0.00 0 3243 27 0 0
v_max_id_clo_ord 5528 5528 0 0.00 0 280 5 0 0
v_max_id_clo_res 1019 1019 0 0.00 0 1094 17 0 0
v_max_id_ord_sup 5624 5624 0 0.00 0 4323 7 0 0
v_max_non_paid_invoice 1641 1641 0 0.00 0 77 7 0 0
v_max_non_paid_realizn 3760 3760 0 0.00 0 5273 11 0 0
v_min_id_clo_ord 5528 5528 0 0.00 0 565 7 0 0
v_min_id_clo_res 1019 1019 0 0.00 0 5562 21 0 0
v_min_id_ord_sup 5624 5624 0 0.00 0 7138 10 0 0
v_min_non_paid_invoice 5966 5966 0 0.00 0 4108 15 0 0
v_min_non_paid_realizn 7392 7392 0 0.00 0 6094 18 0 0
v_our_firm 10110 10110 0 0.00 0 60 4 0 0
v_reserve_write_off 20568 20568 0 0.00 0 8968 12 0 0
x_lock_depdocs_on_canc_invoice 11367 11367 0 0.00 0 17917 127 0 0
x_lock_depdocs_on_canc_sup_ord 418 418 0 0.00 0 11786 375 0 0
x_make_qstorno_1000_1200 5533 5533 0 0.00 2 48223 1163 0 0
x_make_qstorno_1200_2000 5632 5632 0 0.00 3 80384 2671 0 0
x_make_qstorno_2100_3300 464032 464032 0 0.00 0 75327 207 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 15-12-2022 07:38:31 to 15-12-2022 07:38:35.

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:25 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
05:08:26 34872 8192000000 9720703200 10020589568 797079632 8.867 100 89 179 0 826468544 7367056 22021856
05:10:05 23704 8192000000 9762606880 10071609344 837330544 9.273 100 92 184 0 872190352 12271584 22588224
05:11:36 16044 8192000000 9803621904 10126036992 871771520 9.618 100 83 166 0 912794704 21919936 19103248
05:12:56 6017 8192000000 9859869376 10195087360 921804288 10.114 100 76 145 1 975020496 37557264 15658944
05:14:07 5589 8192000000 9923885104 10268008448 969438672 10.581 100 79 156 1 1045387280 60075488 15873120
05:15:17 1951 8192000000 9974187984 10330255360 998895424 10.868 100 86 166 1 1085111056 69236784 16978848
05:16:21 1246 8192000000 10015957536 10380128256 1029066784 11.159 100 91 181 0 1134077472 87091616 17919072
05:17:24 1099 8192000000 10017750832 10389684224 1049019024 11.351 100 92 177 2 1140622592 73344000 18259568
05:18:28 1068 8192000000 10036205792 10414260224 1063002032 11.485 100 94 184 1 1160940704 78587808 19350864
05:19:31 895 8192000000 10055837488 10434076672 1074622160 11.596 100 95 181 1 1177164736 83857792 18684784
05:20:35 1040 8192000000 10045123456 10424225792 1081973376 11.666 100 92 177 0 1170627536 70120992 18533168
05:21:37 1122 8192000000 10065617952 10449588224 1087184768 11.716 100 94 181 2 1190259360 85028368 18046224
05:22:40 1182 8192000000 10082405024 10471899136 1097304864 11.812 100 93 179 2 1202840032 86625168 18910000
05:23:42 1196 8192000000 10067018544 10452529152 1099629296 11.834 100 94 174 1 1179011200 61566224 17815680
05:24:44 4433 8192000000 10063260880 10460807168 1105133824 11.886 100 65 128 0 1187611200 69778480 12698896
05:25:51 1070 8192000000 10057726032 10449391616 1112222752 11.953 100 86 161 2 1181446848 52434064 16790032
05:26:53 1163 8192000000 10068641872 10459770880 1108616432 11.919 100 97 187 0 1188958224 61835728 18506064
05:27:55 1247 8192000000 10072509040 10463113216 1098090384 11.820 100 93 181 1 1194391232 77264528 19036320
05:28:57 1280 8192000000 10067887536 10452717568 1091172240 11.754 100 98 192 0 1189172000 78494304 19505456
05:30:00 2654 8192000000 10019357424 10395103232 1063634848 11.491 99 84 160 3 1137771856 58232528 15904480
05:31:03 2865 8192000000 9997267168 10383638528 1065216192 11.506 99 61 99 4 1118385808 42458288 10711328
05:32:08 6739 8192000000 10016053008 10402332672 1053583296 11.395 99 56 102 2 1125395376 60173488 11638592
05:33:16 1243 8192000000 9991279520 10363924480 1042991184 11.293 100 92 177 0 1116442544 54994224 18457136
05:34:19 995 8192000000 10009291504 10384699392 1040672784 11.271 100 90 173 1 1127672784 69455344 17544656
05:35:21 1452 8192000000 10013447040 10387808256 1043494592 11.298 100 97 181 3 1140264880 78200992 18569296
05:36:24 955 8192000000 9986441024 10362281984 1023331792 11.104 99 87 168 1 1106584640 65600080 17652768
05:37:27 28312 8192000000 9951854976 10329092096 1043900272 11.302 100 71 120 0 1073155440 18433488 10821680
05:39:03 4864 8192000000 10026026352 10405285888 1062094432 11.477 100 73 144 1 1147520480 71259072 14166976
05:40:14 6595 8192000000 10042783168 10424176640 1081234928 11.659 100 71 123 3 1161390736 67771312 12384496
05:41:23 10989 8192000000 10087631808 10480168960 1092588720 11.767 100 72 135 2 1211310320 105494816 13226784
05:42:44 1121 8192000000 10131023088 10517938176 1103921280 11.875 100 94 176 0 1252507808 130330624 18255904
05:43:48 2162 8192000000 10160651904 10552524800 1121540544 12.042 100 89 168 1 1275733728 137062672 17130512
05:44:56 2970 8192000000 10169557664 10571083776 1139049616 12.207 100 85 164 3 1283865120 127577744 17237760
05:46:02 2831 8192000000 10193505968 10592509952 1150064544 12.310 100 79 148 2 1299913152 133541232 16307376
05:47:08 1133 8192000000 10245037728 10641436672 1168948736 12.487 100 84 154 2 1361681296 175524896 17207664
05:48:15 1572 8192000000 10278071696 10691780608 1173295936 12.528 100 95 175 1 1392716944 199145504 20275504
05:49:21 927 8192000000 10299838320 10700283904 1170011648 12.497 100 87 157 0 1411520688 223828000 17681040
05:50:24 999 8192000000 10289323408 10688184320 1170944112 12.506 100 90 172 1 1399135344 209323104 18868128
05:51:31 2196 8192000000 10316827104 10738761728 1179711040 12.588 100 67 127 0 1437537360 242769456 15056864
05:52:37 1916 8192000000 10358320128 10767466496 1191070528 12.693 100 93 180 1 1464476800 252428576 20977696
05:53:42 999 8192000000 10371932448 10789470208 1195368064 12.733 100 94 185 0 1479926112 262620688 21937360
05:54:46 1059 8192000000 10417481536 10822983680 1196371280 12.743 100 90 172 1 1524658352 308326832 19960240
05:55:52 652 8192000000 10418153568 10816921600 1169071504 12.488 100 98 187 1 1524284160 332561600 22651056
05:56:58 2020 8192000000 10462543536 10893561856 1190011904 12.683 100 90 176 5 1568613296 356414048 22187344
05:58:02 923 8192000000 10529013600 10952904704 1202397088 12.799 100 97 191 0 1632069520 406205824 23466608
05:59:05 961 8192000000 10545519168 10985582592 1205061424 12.823 100 98 189 0 1647606496 419630416 22914656
06:00:10 1402 8192000000 10581035920 11011825664 1208613376 12.856 100 97 186 0 1683791536 451902800 23275360
06:01:13 1419 8192000000 10583701328 11031965696 1197094768 12.749 100 88 175 1 1686951984 468067168 21790048
06:02:16 1278 8192000000 10543708064 11048398848 1200684128 12.783 100 79 158 0 1646791120 426512528 19594464
06:03:19 9551 8192000000 10547185440 11019059200 1206721712 12.839 100 58 116 1 1657868112 436128736 15017664
06:04:55 28998 8192000000 10508572256 10996940800 1190423360 12.687 100 43 84 0 1624609264 423199200 10986704
06:06:31 24196 8192000000 10472829568 11001090048 1175202976 12.545 100 31 61 0 1618299184 435078336 8017872
06:08:22 50434 8192000000 10149983168 10645114880 1108026944 11.914 97 15 30 0 1263654368 151842016 3785408
06:10:13 52063 8192000000 10082937440 10526273536 1137635568 12.193 100 29 51 0 1199129664 55891568 5602528
06:12:10 92540 8192000000 10095279344 10560290816 1132202208 12.142 99 14 28 0 1197581632 62737856 2641568
06:15:22 82324 8192000000 10105536832 10550005760 1101628592 11.853 96 15 30 0 1169819648 64881648 3309408
06:17:52 75962 8192000000 10106738752 10591305728 1144379392 12.257 100 43 77 1 1283434800 128758448 10296960
06:20:33 723 8192000000 10118576016 10543935488 1150730016 12.316 100 64 108 1 1213496656 51308272 11458368
06:21:39 31700 8192000000 10169892928 10601988096 1138666416 12.203 98 56 110 0 1236297056 85028480 12602160
06:23:14 37589 8192000000 10170572928 10658951168 1129686704 12.118 98 21 42 0 1246075872 111640336 4748832
06:24:52 54500 8192000000 10120395824 10605076480 1136099968 12.179 99 18 36 0 1226993312 85866432 5026912
06:26:47 20304 8192000000 10096606928 10599608320 1133746864 12.157 100 10 20 0 1197854928 61895456 2212608
06:28:08 81126 8192000000 10137445520 10598064128 1134706688 12.166 99 28 54 0 1218395392 78223920 5464784
06:30:31 85136 8192000000 10081649648 10596458496 1126805552 12.091 99 16 32 0 1179312320 49371408 3135360
06:33:01 33414 8192000000 10064638608 10526072832 1125193536 12.076 99 18 34 0 1161423824 32717632 3512656
06:34:35 16853 8192000000 10115270704 10545647616 1127151712 12.095 99 73 140 3 1191763296 50950400 13661184
06:35:52 47903 8192000000 10066596208 10503475200 1093580272 11.777 96 28 55 1 1121138656 21671264 5887120
06:37:45 44978 8192000000 10145605856 10594156544 1107471840 11.908 97 22 42 1 1198944176 86211744 5260592
06:39:34 40052 8192000000 10078249440 10522259456 1102379504 11.860 98 30 54 0 1143373600 35189568 5804528
06:41:17 8826 8192000000 10078248864 10506227712 1107398048 11.908 98 36 70 1 1161180592 46506288 7276256
06:42:29 51703 8192000000 10120766992 10572742656 1133270144 12.152 100 15 30 0 1201482448 65191840 3020464
06:44:24 111004 8192000000 10091688320 10536779776 1113294400 11.964 98 18 36 0 1178762720 61827040 3641280
06:47:26 2123 8192000000 10065327888 10525810688 1136186848 12.180 100 22 44 0 1173633952 33016608 4430496
06:48:29 55096 8192000000 10054228928 10520870912 1120569648 12.032 99 10 20 0 1148642032 26471088 1601296
06:50:27 51605 8192000000 10075307520 10532450304 1141171984 12.227 100 9 18 0 1178560224 35514240 1874000
06:52:21 71335 8192000000 10118130736 10591686656 1120831280 12.035 98 17 34 0 1193693312 68840864 4021168
06:54:33 19533 8192000000 10153725824 10590482432 1133891296 12.158 99 44 88 0 1234114192 90968240 9254656
06:55:57 40161 8192000000 10129500816 10598916096 1115367552 11.983 98 25 50 0 1248399680 127411696 5620432
06:57:38 48163 8192000000 10179062336 10617999360 1138250560 12.199 99 25 50 0 1260039360 116444048 5344752
06:59:28 44982 8192000000 10181100720 10634379264 1148560528 12.296 100 32 62 0 1286061520 130442672 7058320
07:01:13 48141 8192000000 10202818848 10671034368 1159330048 12.397 100 28 56 1 1295063808 129364512 6369248
07:03:13 34416 8192000000 10232407424 10686566400 1147455712 12.286 99 21 42 0 1307068880 154833136 4780032
07:04:48 102798 8192000000 10092700848 10623524864 1107727392 11.911 98 23 46 0 1199451008 86762864 4960752
07:07:36 113751 8192000000 10091298096 10582368256 1136437632 12.182 100 22 44 0 1203664336 62329712 4896992
07:10:49 72870 8192000000 10107815856 10566467584 1107933568 11.913 97 25 47 0 1155015968 42171936 4910464
07:13:02 91531 8192000000 10116012912 10604105728 1129902032 12.120 99 39 75 2 1196004656 58290864 7811760
07:15:34 38840 8192000000 10108417920 10545508352 1134970912 12.168 99 32 63 0 1191863664 50806848 6085904
07:17:22 72958 8192000000 10059498656 10490855424 1054204896 11.401 92 28 54 0 1102660672 43643520 4812256
07:19:37 146579 8192000000 10063082720 10543685632 1121193200 12.038 99 29 58 0 1153271072 25735072 6342800
07:24:37 45211 8192000000 10057969680 10535022592 1105483744 11.890 98 17 34 0 1137489952 28341664 3664544
07:26:39 88812 8192000000 10084233872 10566234112 1111430480 11.946 98 16 32 0 1171598544 56157088 4010976
07:29:10 43800 8192000000 10128042560 10569904128 1131631872 12.137 99 30 60 0 1215955456 77716000 6607584
07:30:54 44608 8192000000 10099382944 10544750592 1120793600 12.034 99 26 50 1 1196040896 70002608 5244688
07:32:44 39293 8192000000 10080078864 10534604800 1110539632 11.938 98 29 56 0 1173706816 57627456 5539728
07:34:25 47568 8192000000 10086757280 10557243392 1094312160 11.784 97 32 64 0 1167019536 65977392 6729984
07:36:18 23050 8192000000 10089820432 10516393984 1098007168 11.819 98 47 90 1 1167057872 58626128 10424576

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 15-12-2022 07:38:37 to 15-12-2022 07:38:53.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 91 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 15-12-2022 07:38:54 to 15-12-2022 07:38:56.

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          228072
MON$OLDEST_ACTIVE               230749
MON$OLDEST_SNAPSHOT             230749
MON$NEXT_TRANSACTION            230749
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                       2060256
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             797
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 ---
:46: 
:47: mscs-test-161.budzdorov.ru    Thu Dec 15 07:37:29 2022
:48:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:49: 
:50: 
:51: mscs-test-161.budzdorov.ru    Thu Dec 15 07:37:31 2022
:52:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:53: 
--- end of diff output ---

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

15.12.22 07:38:57. 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-15 07:38:59 No severe PSQL-related problems occured
15.12.22 07:39:01. End of report.