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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15259108 kB
MemAvailable 30437016 kB
Buffers 0 kB
Cached 16476528 kB
SwapCached 3028 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 2023-03-07 10:37:26
DTS_END                         2023-03-07 10:37:26.3940
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/34524
TRN_ID                          215595
ATT_ID                          557
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: 8573.88 23678 1028866
customer order: creation 168.78 464 20253
customer order: refuse 13.53 412 1623
order to supplier: creation 45.07 1343 5408
order to supplier: removal 3.05 165366 366
invoice (draft): creation 46.37 3402 5564
invoice (draft): removal 45.28 1035 5433
invoice accept: apply 41.55 25545 4986
invoice accept: cancel 41.10 1282 4932
customer reserve: creation 3823.48 244 458817
customer reserve: removal 4067.35 97 488082
realization accept: apply 81.62 275 9794
realization accept: cancel 69.76 178 8371
payment from customer: creation 57.38 172 6885
payment from customer: removal 5.29 177 635
payment to supplier: creation 47.82 197 5738
payment to supplier: removal 3.63 125 436
service: total inventory turnovers 3.95 3695 474
service: total monetary turnovers 7.97 344 956
service: aggregate perf. data 0.90 15668 108
service: refresh index statistics 0.04 253541 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 07-03-2023 10:39:13 to 07-03-2023 10:39:15.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6728
TEST_TIME 2 7077
TEST_TIME 3 6378
TEST_TIME 4 5740
TEST_TIME 5 6265
TEST_TIME 6 5932
TEST_TIME 7 6421
TEST_TIME 8 6345
TEST_TIME 9 6363
TEST_TIME 10 6381
TEST_TIME 11 6574
TEST_TIME 12 6922
TEST_TIME 13 6862
TEST_TIME 14 7218
TEST_TIME 15 7269
TEST_TIME 16 7542
TEST_TIME 17 7662
TEST_TIME 18 7826
TEST_TIME 19 7803
TEST_TIME 20 7906
TEST_TIME 21 8176
TEST_TIME 22 8157
TEST_TIME 23 8321
TEST_TIME 24 8526
TEST_TIME 25 8855
TEST_TIME 26 9023
TEST_TIME 27 9393
TEST_TIME 28 10135
TEST_TIME 29 10452
TEST_TIME 30 10511
TEST_TIME 31 10926
TEST_TIME 32 11121
TEST_TIME 33 10866
TEST_TIME 34 10602
TEST_TIME 35 10449
TEST_TIME 36 10276
TEST_TIME 37 10333
TEST_TIME 38 10192
TEST_TIME 39 10028
TEST_TIME 40 9995
TEST_TIME 41 9857
TEST_TIME 42 9764
TEST_TIME 43 9635
TEST_TIME 44 9563
TEST_TIME 45 9483
TEST_TIME 46 9404
TEST_TIME 47 9355
TEST_TIME 48 9284
TEST_TIME 49 9205
TEST_TIME 50 9162
TEST_TIME 51 9061
TEST_TIME 52 9047
TEST_TIME 53 9172
TEST_TIME 54 9154
TEST_TIME 55 9143
TEST_TIME 56 9147
TEST_TIME 57 9141
TEST_TIME 58 9136
TEST_TIME 59 9118
TEST_TIME 60 9190
TEST_TIME 61 9164
TEST_TIME 62 9179
TEST_TIME 63 9127
TEST_TIME 64 9053
TEST_TIME 65 8988
TEST_TIME 66 8921
TEST_TIME 67 8870
TEST_TIME 68 8858
TEST_TIME 69 8776
TEST_TIME 70 8805
TEST_TIME 71 8817
TEST_TIME 72 8754
TEST_TIME 73 8759
TEST_TIME 74 8716
TEST_TIME 75 8763
TEST_TIME 76 8711
TEST_TIME 77 8705
TEST_TIME 78 8742
TEST_TIME 79 8709
TEST_TIME 80 8778
TEST_TIME 81 8950
TEST_TIME 82 8978
TEST_TIME 83 8942
TEST_TIME 84 9094
TEST_TIME 85 9030
TEST_TIME 86 8942
TEST_TIME 87 8902
TEST_TIME 88 8887
TEST_TIME 89 8888
TEST_TIME 90 8842
TEST_TIME 91 8807
TEST_TIME 92 8818
TEST_TIME 93 8772
TEST_TIME 94 8744
TEST_TIME 95 8792
TEST_TIME 96 8774
TEST_TIME 97 8744
TEST_TIME 98 8757
TEST_TIME 99 8729
TEST_TIME 100 8720
TEST_TIME 101 8696
TEST_TIME 102 8832
TEST_TIME 103 8851
TEST_TIME 104 8938
TEST_TIME 105 8933
TEST_TIME 106 8925
TEST_TIME 107 8945
TEST_TIME 108 8931
TEST_TIME 109 8871
TEST_TIME 110 8831
TEST_TIME 111 8867
TEST_TIME 112 8845
TEST_TIME 113 8797
TEST_TIME 114 8765
TEST_TIME 115 8736
TEST_TIME 116 8697
TEST_TIME 117 8702
TEST_TIME 118 8670
TEST_TIME 119 8648
TEST_TIME 120 8597

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 07-03-2023 10:39:17 to 07-03-2023 10:39:20.

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 1449966 1449966 0 0.00 0 19359 13 0 0
doc_list_biud 1449957 1449957 0 0.00 0 52284 39 0 0
sp_add_invoice_to_stock 5091 4986 105 2.06 50 212733 25545 0 105
sp_cancel_adding_invoice 4932 4932 0 0.00 5 77453 1283 0 0
sp_cancel_client_order 1623 1623 0 0.00 6 17073 413 0 0
sp_cancel_customer_reserve 488082 488082 0 0.00 1 52286 98 0 0
sp_cancel_pay_from_customer 635 635 0 0.00 4 12071 177 0 0
sp_cancel_pay_to_supplier 436 436 0 0.00 4 6156 125 0 0
sp_cancel_supplier_invoice 5438 5433 5 0.09 2 21497 1035 0 5
sp_cancel_supplier_order 366 366 0 0.00 10 1592839 165367 0 0
sp_cancel_write_off 8371 8371 0 0.00 1 14479 178 0 0
sp_client_order 20253 20253 0 0.00 5 22217 464 0 0
sp_customer_reserve 458817 458817 0 0.00 0 80158 244 0 0
sp_fill_shopping_cart 31208 31208 0 0.00 1 18728 90 0 0
sp_fill_shopping_cart_clo_res 458795 458795 0 0.00 0 12492 19 0 0
sp_get_clo_for_invoice 4954 4954 0 0.00 0 33702 334 0 0
sp_kill_cost_storno 14374 14374 0 0.00 0 14799 71 0 0
sp_kill_qstorno_ret_qs2qd 990334 990334 0 0.00 0 52280 43 0 0
sp_kill_qty_storno 523553 523553 0 0.00 0 52284 99 0 0
sp_lock_selected_doc 20270 20270 0 0.00 0 6913 7 0 0
sp_make_cost_storno 27370 27370 0 0.00 0 14681 74 0 0
sp_multiply_rows_for_pdistr 27368 27368 0 0.00 0 19333 41 0 0
sp_multiply_rows_for_qdistr 20252 20252 0 0.00 0 11802 165 0 0
sp_pay_from_customer 6885 6885 0 0.00 5 19439 173 0 0
sp_pay_to_supplier 5738 5738 0 0.00 6 19393 198 0 0
sp_payment_common 12621 12621 0 0.00 2 6351 45 0 0
sp_qd_handle_on_cancel_clo 1623 1623 0 0.00 0 7735 306 0 0
sp_qd_handle_on_invoice_upd_sts 9884 9884 0 0.00 0 24235 524 0 0
sp_qd_handle_on_reserve_upd_sts 9794 9794 0 0.00 0 10636 60 0 0
sp_reserve_write_off 9794 9794 0 0.00 9 19451 276 0 0
sp_supplier_invoice 5564 5564 0 0.00 9 92298 3402 0 0
sp_supplier_order 5408 5408 0 0.00 8 37107 1343 0 0
srv_aggregate_perf_data 108 108 0 0.00 2488 156329 15668 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 703 136095 39582 0 0
srv_find_qd_qs_mism 979922 979922 0 0.00 0 11371 10 0 0
srv_make_invnt_saldo 474 474 0 0.00 43 48524 3695 0 0
srv_make_money_saldo 956 956 0 0.00 1 14119 345 0 0
srv_recalc_idx_stat 5 5 0 0.00 34180 365409 253542 0 0
t$perf-abend:sp_add_invoice_to_stock 105 105 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4990 4990 0 0.00 1 11611 988 0 0
t$perf-norm:sp_cancel_adding_invoice 403 403 0 0.00 1 7366 409 0 0
t$perf-norm:sp_cancel_client_order 1623 1623 0 0.00 0 914 25 0 0
t$perf-norm:sp_cancel_customer_reserve 696 696 0 0.00 0 686 25 0 0
t$perf-norm:sp_cancel_pay_from_customer 635 635 0 0.00 0 3353 23 0 0
t$perf-norm:sp_cancel_pay_to_supplier 436 436 0 0.00 0 760 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 382 382 0 0.00 0 1556 42 0 0
t$perf-norm:sp_cancel_supplier_order 366 366 0 0.00 0 88109 7415 0 0
t$perf-norm:sp_cancel_write_off 987 987 0 0.00 0 3588 26 0 0
t$perf-norm:sp_client_order 20253 20253 0 0.00 0 5803 34 0 0
t$perf-norm:sp_customer_reserve 929 929 0 0.00 0 1059 15 0 0
t$perf-norm:sp_pay_from_customer 6885 6885 0 0.00 0 4062 20 0 0
t$perf-norm:sp_pay_to_supplier 5738 5738 0 0.00 0 5422 20 0 0
t$perf-norm:sp_reserve_write_off 9794 9794 0 0.00 0 7238 23 0 0
t$perf-norm:sp_supplier_invoice 5564 5564 0 0.00 0 5177 32 0 0
t$perf-norm:sp_supplier_order 5409 5409 0 0.00 0 3614 32 0 0
t$perf-norm:srv_aggregate_perf_data 108 108 0 0.00 0 262 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 336 9 0 0
t$perf-norm:srv_make_invnt_saldo 474 474 0 0.00 0 2631 18 0 0
t$perf-norm:srv_make_money_saldo 956 956 0 0.00 0 1986 7 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 3 156 38 0 0
v_add_invoice_to_stock 10106 9896 210 2.07 0 11897 29 0 210
v_all_customers 36986 36986 0 0.00 0 231 4 0 0
v_all_suppliers 30126 30126 0 0.00 0 230 4 0 0
v_all_wares 40500 40500 0 0.00 0 111 4 0 0
v_cancel_adding_invoice 806 806 0 0.00 0 2241 16 0 0
v_cancel_client_order 3246 3246 0 0.00 0 16867 30 0 0
v_cancel_customer_prepayment 1270 1270 0 0.00 0 11931 38 0 0
v_cancel_customer_reserve 1392 1392 0 0.00 0 2244 15 0 0
v_cancel_payment_to_supplier 872 872 0 0.00 0 6109 20 0 0
v_cancel_supplier_invoice 774 764 10 1.29 0 6074 46 0 10
v_cancel_supplier_order 732 732 0 0.00 0 1305 12 0 0
v_cancel_write_off 1974 1974 0 0.00 0 5857 32 0 0
v_max_id_clo_ord 5399 5399 0 0.00 0 1990 6 0 0
v_max_id_clo_res 929 929 0 0.00 0 1921 14 0 0
v_max_id_ord_sup 5555 5555 0 0.00 0 5792 9 0 0
v_max_non_paid_invoice 1628 1628 0 0.00 0 96 8 0 0
v_max_non_paid_realizn 3613 3613 0 0.00 0 472 7 0 0
v_min_id_clo_ord 5399 5399 0 0.00 0 2777 8 0 0
v_min_id_clo_res 929 929 0 0.00 0 2018 14 0 0
v_min_id_ord_sup 5555 5555 0 0.00 0 6342 15 0 0
v_min_non_paid_invoice 5737 5737 0 0.00 0 6346 18 0 0
v_min_non_paid_realizn 6884 6884 0 0.00 0 3694 14 0 0
v_our_firm 10056 10056 0 0.00 0 233 5 0 0
v_reserve_write_off 19584 19584 0 0.00 0 7303 13 0 0
x_lock_depdocs_on_canc_invoice 9983 9983 0 0.00 0 19004 136 0 0
x_lock_depdocs_on_canc_sup_ord 366 366 0 0.00 0 18592 405 0 0
x_make_qstorno_1000_1200 5408 5408 0 0.00 2 37093 1179 0 0
x_make_qstorno_1200_2000 5564 5564 0 0.00 4 92222 3141 0 0
x_make_qstorno_2100_3300 458815 458815 0 0.00 0 80144 212 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 07-03-2023 10:39:21 to 07-03-2023 10:39:26.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
08:07:30 2 8192000000 8796952944 8807501824 3189136 0.038 1 1 2 0 3286752 61840 35776
08:08:31 37947 8192000000 9713976176 10009653248 787362176 8.768 100 92 184 0 818194016 8133536 22698304
08:10:18 26955 8192000000 9762503360 10071449600 828530256 9.184 100 85 170 0 861737536 13085584 20121696
08:11:47 12962 8192000000 9800791232 10119172096 866196816 9.562 100 89 178 0 913619392 26642416 20780160
08:13:04 7504 8192000000 9865699776 10196791296 902184032 9.920 100 79 158 0 971869712 52243936 17441744
08:14:25 4261 8192000000 9920377344 10264076288 958130944 10.471 100 90 180 0 1037834784 60851968 18851872
08:15:33 1801 8192000000 9968653680 10322554880 995923792 10.839 100 93 180 1 1092720592 78539456 18257344
08:16:38 1406 8192000000 10006371552 10371256320 1025118880 11.121 100 94 178 0 1121113488 78624944 17369664
08:17:42 1046 8192000000 10019811984 10388783104 1045303008 11.316 100 93 181 2 1139304928 75473744 18528176
08:18:45 4618 8192000000 10015632704 10386194432 1060151840 11.458 100 93 175 0 1136602208 59065392 17384976
08:19:52 1257 8192000000 10045191872 10422509568 1065095920 11.505 100 99 191 2 1158165168 73869312 19199936
08:20:55 1618 8192000000 10032677792 10411311104 1075643104 11.606 100 91 178 0 1155450336 61962816 17844416
08:21:59 1221 8192000000 10048260256 10434641920 1078932096 11.637 100 97 192 2 1173739472 74178048 20629328
08:23:01 1466 8192000000 10059024704 10448134144 1085802400 11.703 100 84 159 1 1186725824 85213408 15710016
08:24:03 1111 8192000000 10056174864 10443026432 1090991952 11.752 100 91 179 0 1177541888 68542752 18007184
08:25:05 1168 8192000000 10066998912 10455343104 1093105744 11.772 100 95 179 1 1189910112 78753776 18050592
08:26:07 1160 8192000000 10049499520 10434441216 1095030416 11.790 100 99 177 3 1172743440 58897200 18815824
08:27:09 1090 8192000000 10073164448 10462162944 1091565808 11.758 100 98 191 1 1191125552 80630128 18929616
08:28:12 1097 8192000000 10079068256 10468470784 1093229168 11.773 100 96 180 1 1204201008 91520112 19451728
08:29:14 1170 8192000000 10038898176 10422018048 1095068480 11.791 100 98 188 1 1158315888 44231120 19016288
08:30:16 1020 8192000000 10028923504 10417381376 1082914640 11.675 100 88 168 0 1155343376 56013920 16414816
08:31:18 1695 8192000000 10034435936 10421788672 1080620768 11.653 100 88 172 1 1164782640 66480400 17681472
08:32:20 1502 8192000000 10011353616 10393735168 1051447312 11.375 100 82 159 0 1133568528 65970320 16150896
08:33:22 1291 8192000000 10004373904 10376495104 1036808096 11.234 100 92 170 1 1127377680 73660304 16909280
08:34:24 1142 8192000000 10019048976 10399334400 1056938720 11.427 100 88 172 0 1139944688 65371104 17634864
08:35:27 1416 8192000000 9985466752 10358734848 1025873728 11.129 99 84 158 3 1106605904 64121920 16610256
08:36:30 783 8192000000 9975454144 10354593792 1026688864 11.137 100 81 151 1 1100486496 58298592 15499040
08:37:34 1007 8192000000 9996599120 10374885376 1046900672 11.331 100 79 134 2 1113139840 54118992 12120176
08:38:39 16343 8192000000 9992840816 10372685824 1054995264 11.409 100 80 129 3 1113545680 45504432 13045984
08:40:01 4782 8192000000 10028014112 10416623616 1083875040 11.684 100 63 118 1 1151188080 55208768 12104272
08:41:08 5887 8192000000 10081552608 10474717184 1103614496 11.872 100 68 128 1 1199901424 84379776 11907152
08:42:18 3113 8192000000 10120679824 10510921728 1118822112 12.016 100 87 156 3 1239180048 103403312 16954624
08:43:27 1237 8192000000 10160512496 10554187776 1120985056 12.036 100 91 173 4 1279566304 139566336 19014912
08:44:33 930 8192000000 10188890512 10582814720 1134723552 12.166 100 91 174 0 1304243552 151132048 18387952
08:45:38 2330 8192000000 10197536944 10591682560 1137534704 12.192 100 85 161 2 1309232032 155123680 16573648
08:46:46 1240 8192000000 10215436288 10614386688 1139440656 12.210 100 86 166 2 1336133952 176579296 20114000
08:47:49 5601 8192000000 10261945168 10665476096 1149993840 12.309 100 71 131 1 1370808016 206306736 14507440
08:49:03 2534 8192000000 10280791616 10699485184 1168253344 12.481 100 88 168 2 1397508112 210284176 18970592
08:50:08 1126 8192000000 10309163936 10712186880 1178090800 12.572 100 96 187 0 1417565408 219053696 20420912
08:51:12 1281 8192000000 10323108640 10738122752 1182596832 12.614 100 96 184 0 1428968448 224580080 21791536
08:52:17 1305 8192000000 10363564320 10767204352 1183083520 12.619 100 95 181 1 1470977264 267319328 20574416
08:53:22 937 8192000000 10372956080 10806489088 1186375840 12.650 100 96 189 0 1476574064 268516464 21681760
08:54:27 1664 8192000000 10374661504 10807656448 1189861952 12.682 100 91 178 0 1488526496 277961264 20703280
08:55:32 985 8192000000 10428410080 10836783104 1195664160 12.736 100 88 173 1 1538576672 323444192 19468320
08:56:35 805 8192000000 10445233984 10865082368 1192091248 12.703 100 97 188 0 1550154320 336122480 21940592
08:57:40 806 8192000000 10487591456 10895556608 1177945792 12.571 100 96 184 3 1590908736 390925376 22037568
08:58:43 1410 8192000000 10523593792 10960797696 1189300256 12.677 100 83 162 0 1629949712 421215008 19434448
08:59:50 807 8192000000 10493652272 10918547456 1167400048 12.473 99 95 181 3 1597640336 408908384 21331904
09:00:54 2555 8192000000 10518277536 10948362240 1173597712 12.530 100 83 160 3 1624915856 431650336 19667808
09:02:00 700 8192000000 10474854624 10929098752 1176804208 12.560 100 89 171 0 1580471264 382279824 21387232
09:03:04 2828 8192000000 10412590160 10856185856 1152043536 12.329 100 61 115 2 1518585168 352227760 14313872
09:04:08 4033 8192000000 10369550464 10848792576 1161906704 12.421 100 85 162 3 1473658816 292283328 19468784
09:05:14 8037 8192000000 10312317200 10823442432 1164315888 12.444 100 64 126 0 1415679536 236272336 15091312
09:06:26 26933 8192000000 10202777680 10692190208 1129917056 12.121 98 25 50 0 1336298368 199433232 6948080
09:07:57 86138 8192000000 10131518160 10659811328 1114370624 11.974 97 12 22 0 1234647872 117464608 2812640
09:10:28 5224 8192000000 10098695536 10511618048 1154461424 12.351 100 75 137 2 1189208960 19918720 14828816
09:11:34 71554 8192000000 10105767440 10548670464 1118478352 12.013 97 46 90 0 1210762240 82558688 9725200
09:13:51 71275 8192000000 10073318928 10554699776 1108853792 11.922 97 19 37 1 1173714864 61200896 3660176
09:16:36 112821 8192000000 10087070400 10539524096 1119390256 12.021 97 18 36 0 1157728528 34528320 3809952
09:19:29 54643 8192000000 10111920576 10546708480 1117137728 12.000 97 37 74 2 1195406720 70275840 7993152
09:21:39 113678 8192000000 10139256592 10616311808 1144380336 12.257 100 21 42 0 1230904128 81891680 4632112
09:24:34 13419 8192000000 10156392960 10629836800 1155291696 12.359 100 19 38 0 1274355840 115054896 4009248
09:25:48 27304 8192000000 10093825296 10536529920 1096572736 11.805 96 42 81 2 1164271536 57597632 10101168
09:27:25 70231 8192000000 10141239136 10603528192 1143342432 12.247 100 16 33 0 1217198704 69777184 4079088
09:29:44 64206 8192000000 10067438752 10538106880 1124905664 12.073 99 10 20 0 1199418272 71980928 2531680
09:31:49 71819 8192000000 10087307536 10541510656 1147017040 12.281 100 42 82 0 1231330944 75974752 8339152
09:34:01 47875 8192000000 10107411696 10564984832 1130306976 12.124 99 34 68 0 1185639616 48839008 6493632
09:35:51 48462 8192000000 10090388464 10547752960 1109436032 11.927 97 30 58 1 1175753088 60604720 5712336
09:37:40 71613 8192000000 10078788144 10510479360 1084928192 11.694 95 30 60 2 1134781296 43759104 6094000
09:39:55 123232 8192000000 10061348272 10509889536 1097054176 11.810 97 30 61 0 1149330864 46197328 6079360
09:43:00 48470 8192000000 10090642368 10548883456 1123739088 12.062 99 27 52 0 1197471248 68323712 5408448
09:44:50 59466 8192000000 10086720368 10563121152 1131077808 12.132 100 16 32 0 1217717552 83522304 3117440
09:46:53 89506 8192000000 10052076736 10562899968 1077389424 11.623 95 11 22 0 1145923840 66073728 2460688
09:49:23 42789 8192000000 10085237904 10527092736 1115715216 11.986 98 25 48 1 1164995952 44294640 4986096
09:51:06 22920 8192000000 10100322848 10537897984 1097624448 11.815 96 26 50 0 1189413520 86591792 5197280
09:52:29 40871 8192000000 10154330400 10606587904 1125884000 12.083 99 27 53 0 1238187616 106544256 5759360
09:54:19 65224 8192000000 10164891936 10654715904 1144660752 12.259 100 43 84 1 1310272672 155748720 9863200
09:56:25 29049 8192000000 10192170736 10626117632 1128637600 12.109 100 33 66 0 1277572656 141765888 7169168
09:57:55 40978 8192000000 10113751248 10669797376 1132328960 12.143 100 24 48 1 1317795152 179836768 5629424
09:59:36 24786 8192000000 10121931056 10572947456 1138818464 12.204 100 32 64 0 1212439664 67228912 6392288
10:01:03 64310 8192000000 10066904368 10588995584 1089018848 11.733 96 21 38 1 1157233952 64635344 3579760
10:03:08 111547 8192000000 10069020528 10525405184 1102422080 11.861 98 23 46 0 1182120672 74435744 5262848
10:06:04 81609 8192000000 10093289696 10566864896 1134267936 12.162 100 19 38 0 1192955616 54367424 4320256
10:08:26 92027 8192000000 10082019920 10577571840 1127593776 12.099 99 14 29 0 1211958336 81120064 3244496
10:11:10 74347 8192000000 10057551872 10537537536 1096519584 11.805 97 19 38 1 1150003744 49753360 3730800
10:13:25 73775 8192000000 10079069664 10516520960 1104456016 11.880 98 27 52 0 1146056160 36230048 5370096
10:15:39 40297 8192000000 10140286624 10587955200 1145042800 12.263 100 28 56 0 1227450352 76230352 6177200
10:17:28 25725 8192000000 10188939344 10629980160 1148093376 12.292 100 30 60 0 1290604048 135957104 6553568
10:18:58 45442 8192000000 10199374288 10733289472 1114801984 11.978 97 26 52 0 1293111408 172617744 5691680
10:21:29 72072 8192000000 10082734816 10541326336 1121091200 12.037 98 19 36 1 1182619008 58278464 3249344
10:23:46 71460 8192000000 10112040240 10578145280 1091567008 11.758 96 40 78 0 1185456960 86059792 7830160
10:26:00 136095 8192000000 10101676288 10552107008 1095865072 11.798 97 26 50 1 1164321200 63118912 5337216
10:29:21 66679 8192000000 10049498112 10540806144 1121795632 12.044 100 8 16 0 1170003344 46350400 1857312
10:31:32 56780 8192000000 10069901312 10528559104 1131975344 12.140 100 18 36 0 1173090208 37577200 3537664
10:34:06 67574 8192000000 10063138176 10553643008 1106403968 11.898 97 14 28 0 1157229152 47823952 3001232
10:36:18 32355 8192000000 10119073024 10571460608 1138524528 12.202 100 38 76 0 1211764384 64573824 8666032

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 07-03-2023 10:39:27 to 07-03-2023 10:39:43.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 105 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:03, from 07-03-2023 10:39:44 to 07-03-2023 10:39:47.

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          213395
MON$OLDEST_ACTIVE               217550
MON$OLDEST_SNAPSHOT             217550
MON$NEXT_TRANSACTION            217550
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                       2045344
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             769
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2906 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2906 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2906 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2906 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 ---
:115: 
:116: mscs-test-161.budzdorov.ru    Tue Mar  7 10:37:31 2023
:117:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:118: 
:119: 
:120: mscs-test-161.budzdorov.ru    Tue Mar  7 10:37:31 2023
:121:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:122: 
:123: 
:124: mscs-test-161.budzdorov.ru    Tue Mar  7 10:37:36 2023
:125:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:126: 
:127: 
:128: mscs-test-161.budzdorov.ru    Tue Mar  7 10:37:37 2023
:129:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:130: 
:131: 
:132: mscs-test-161.budzdorov.ru    Tue Mar  7 10:37:38 2023
:133:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:134: 
--- end of diff output ---

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

07.03.23 10:39:48. 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
2023-03-07 10:39:50 No severe PSQL-related problems occured
07.03.23 10:39:52. End of report.