Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 31.12.2022 04: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 14735360 kB
MemAvailable 30389536 kB
Buffers 20 kB
Cached 16920820 kB
SwapCached 11640 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-31 04:37:22
DTS_END                         2022-12-31 04:37:22.7940
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/33908
TRN_ID                          217823
ATT_ID                          603
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: 9147.39 23674 1097687
customer order: creation 167.58 467 20109
customer order: refuse 14.22 380 1706
order to supplier: creation 44.54 1319 5345
order to supplier: removal 3.31 181543 397
invoice (draft): creation 45.21 3035 5425
invoice (draft): removal 51.51 962 6181
invoice accept: apply 41.40 24501 4968
invoice accept: cancel 46.64 1225 5597
customer reserve: creation 3861.37 229 463364
customer reserve: removal 4583.45 103 550014
realization accept: apply 82.08 292 9849
realization accept: cancel 79.71 164 9565
payment from customer: creation 58.03 200 6963
payment from customer: removal 5.72 139 686
payment to supplier: creation 47.58 224 5709
payment to supplier: removal 3.10 157 372
service: total inventory turnovers 3.70 4692 444
service: total monetary turnovers 7.32 347 878
service: aggregate perf. data 0.92 17753 110
service: refresh index statistics 0.04 235757 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 31-12-2022 04:39:49 to 31-12-2022 04:39:51.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8957
TEST_TIME 2 7609
TEST_TIME 3 7261
TEST_TIME 4 7094
TEST_TIME 5 6913
TEST_TIME 6 6628
TEST_TIME 7 6470
TEST_TIME 8 6675
TEST_TIME 9 6742
TEST_TIME 10 6925
TEST_TIME 11 7094
TEST_TIME 12 7167
TEST_TIME 13 7429
TEST_TIME 14 7493
TEST_TIME 15 7763
TEST_TIME 16 8156
TEST_TIME 17 8291
TEST_TIME 18 8148
TEST_TIME 19 8080
TEST_TIME 20 7968
TEST_TIME 21 7999
TEST_TIME 22 8093
TEST_TIME 23 8267
TEST_TIME 24 8549
TEST_TIME 25 8500
TEST_TIME 26 8614
TEST_TIME 27 8675
TEST_TIME 28 8921
TEST_TIME 29 9071
TEST_TIME 30 9509
TEST_TIME 31 9810
TEST_TIME 32 10094
TEST_TIME 33 10639
TEST_TIME 34 11049
TEST_TIME 35 11346
TEST_TIME 36 11461
TEST_TIME 37 11227
TEST_TIME 38 11402
TEST_TIME 39 11190
TEST_TIME 40 10962
TEST_TIME 41 10852
TEST_TIME 42 10672
TEST_TIME 43 10826
TEST_TIME 44 10672
TEST_TIME 45 10597
TEST_TIME 46 10524
TEST_TIME 47 10378
TEST_TIME 48 10293
TEST_TIME 49 10176
TEST_TIME 50 10255
TEST_TIME 51 10172
TEST_TIME 52 10169
TEST_TIME 53 10182
TEST_TIME 54 10178
TEST_TIME 55 10106
TEST_TIME 56 9946
TEST_TIME 57 9919
TEST_TIME 58 9857
TEST_TIME 59 9807
TEST_TIME 60 9726
TEST_TIME 61 9709
TEST_TIME 62 9672
TEST_TIME 63 9784
TEST_TIME 64 9718
TEST_TIME 65 9688
TEST_TIME 66 9645
TEST_TIME 67 9587
TEST_TIME 68 9578
TEST_TIME 69 9518
TEST_TIME 70 9413
TEST_TIME 71 9537
TEST_TIME 72 9466
TEST_TIME 73 9396
TEST_TIME 74 9321
TEST_TIME 75 9289
TEST_TIME 76 9242
TEST_TIME 77 9171
TEST_TIME 78 9202
TEST_TIME 79 9252
TEST_TIME 80 9287
TEST_TIME 81 9246
TEST_TIME 82 9280
TEST_TIME 83 9285
TEST_TIME 84 9316
TEST_TIME 85 9396
TEST_TIME 86 9421
TEST_TIME 87 9483
TEST_TIME 88 9462
TEST_TIME 89 9398
TEST_TIME 90 9435
TEST_TIME 91 9368
TEST_TIME 92 9345
TEST_TIME 93 9356
TEST_TIME 94 9281
TEST_TIME 95 9271
TEST_TIME 96 9273
TEST_TIME 97 9251
TEST_TIME 98 9246
TEST_TIME 99 9232
TEST_TIME 100 9200
TEST_TIME 101 9217
TEST_TIME 102 9228
TEST_TIME 103 9191
TEST_TIME 104 9171
TEST_TIME 105 9227
TEST_TIME 106 9236
TEST_TIME 107 9232
TEST_TIME 108 9234
TEST_TIME 109 9306
TEST_TIME 110 9491
TEST_TIME 111 9456
TEST_TIME 112 9407
TEST_TIME 113 9367
TEST_TIME 114 9358
TEST_TIME 115 9305
TEST_TIME 116 9279
TEST_TIME 117 9286
TEST_TIME 118 9278
TEST_TIME 119 9240
TEST_TIME 120 9169

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 31-12-2022 04:39:52 to 31-12-2022 04:39:56.

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 1524721 1524721 0 0.00 0 25701 12 0 0
doc_list_biud 1524719 1524719 0 0.00 0 40001 43 0 0
sp_add_invoice_to_stock 5085 4968 117 2.30 29 194466 24502 0 117
sp_cancel_adding_invoice 5597 5597 0 0.00 5 68105 1226 0 0
sp_cancel_client_order 1706 1706 0 0.00 6 10335 381 0 0
sp_cancel_customer_reserve 550014 550014 0 0.00 1 25207 104 0 0
sp_cancel_pay_from_customer 686 686 0 0.00 4 5559 139 0 0
sp_cancel_pay_to_supplier 372 372 0 0.00 3 6915 157 0 0
sp_cancel_supplier_invoice 6190 6181 9 0.14 2 40008 963 0 9
sp_cancel_supplier_order 397 397 0 0.00 22 1470808 181544 0 0
sp_cancel_write_off 9565 9565 0 0.00 2 7569 165 0 0
sp_client_order 20109 20109 0 0.00 5 16950 468 0 0
sp_customer_reserve 463364 463364 0 0.00 0 39118 230 0 0
sp_fill_shopping_cart 30873 30873 0 0.00 1 16692 89 0 0
sp_fill_shopping_cart_clo_res 463339 463339 0 0.00 0 13743 20 0 0
sp_get_clo_for_invoice 4925 4925 0 0.00 0 21200 328 0 0
sp_kill_cost_storno 16220 16220 0 0.00 0 25693 72 0 0
sp_kill_qstorno_ret_qs2qd 1116171 1116171 0 0.00 0 39998 44 0 0
sp_kill_qty_storno 588225 588225 0 0.00 0 40001 102 0 0
sp_lock_selected_doc 20418 20418 0 0.00 0 9413 9 0 0
sp_make_cost_storno 27438 27438 0 0.00 0 18632 85 0 0
sp_multiply_rows_for_pdistr 27437 27437 0 0.00 0 8955 38 0 0
sp_multiply_rows_for_qdistr 20109 20109 0 0.00 0 10077 167 0 0
sp_pay_from_customer 6963 6963 0 0.00 5 13935 201 0 0
sp_pay_to_supplier 5709 5709 0 0.00 6 18642 225 0 0
sp_payment_common 12672 12672 0 0.00 2 10800 52 0 0
sp_qd_handle_on_cancel_clo 1706 1706 0 0.00 1 7840 302 0 0
sp_qd_handle_on_invoice_upd_sts 10513 10513 0 0.00 0 13563 534 0 0
sp_qd_handle_on_reserve_upd_sts 9849 9849 0 0.00 0 14847 70 0 0
sp_reserve_write_off 9849 9849 0 0.00 8 18235 293 0 0
sp_supplier_invoice 5425 5425 0 0.00 9 80261 3036 0 0
sp_supplier_order 5345 5345 0 0.00 8 20031 1319 0 0
srv_aggregate_perf_data 110 110 0 0.00 2267 207927 17754 0 0
srv_fill_mon_cache_memory 73 73 0 0.00 761 156387 33145 0 0
srv_find_qd_qs_mism 1049620 1049620 0 0.00 0 9586 9 0 0
srv_make_invnt_saldo 444 444 0 0.00 46 79086 4692 0 0
srv_make_money_saldo 878 878 0 0.00 1 15989 347 0 0
srv_recalc_idx_stat 5 5 0 0.00 38083 474009 235757 0 0
t$perf-abend:sp_add_invoice_to_stock 117 117 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4968 4968 0 0.00 0 10289 937 0 0
t$perf-norm:sp_cancel_adding_invoice 420 420 0 0.00 1 5777 492 0 0
t$perf-norm:sp_cancel_client_order 1706 1706 0 0.00 0 5205 28 0 0
t$perf-norm:sp_cancel_customer_reserve 690 690 0 0.00 0 1990 32 0 0
t$perf-norm:sp_cancel_pay_from_customer 686 686 0 0.00 0 5931 30 0 0
t$perf-norm:sp_cancel_pay_to_supplier 372 372 0 0.00 0 679 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 395 395 0 0.00 0 3468 44 0 0
t$perf-norm:sp_cancel_supplier_order 397 397 0 0.00 0 95391 7487 0 0
t$perf-norm:sp_cancel_write_off 987 987 0 0.00 0 9978 34 0 0
t$perf-norm:sp_client_order 20109 20109 0 0.00 0 7232 38 0 0
t$perf-norm:sp_customer_reserve 938 938 0 0.00 0 536 14 0 0
t$perf-norm:sp_pay_from_customer 6963 6963 0 0.00 0 5077 20 0 0
t$perf-norm:sp_pay_to_supplier 5709 5709 0 0.00 0 8808 24 0 0
t$perf-norm:sp_reserve_write_off 9849 9849 0 0.00 0 6018 27 0 0
t$perf-norm:sp_supplier_invoice 5425 5425 0 0.00 0 10182 34 0 0
t$perf-norm:sp_supplier_order 5345 5345 0 0.00 0 8407 33 0 0
t$perf-norm:srv_aggregate_perf_data 110 110 0 0.00 0 1085 25 0 0
t$perf-norm:srv_fill_mon_cache_memory 73 73 0 0.00 0 223 6 0 0
t$perf-norm:srv_make_invnt_saldo 444 444 0 0.00 0 2094 20 0 0
t$perf-norm:srv_make_money_saldo 878 878 0 0.00 0 368 6 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 10 190 126 0 0
v_add_invoice_to_stock 10066 9832 234 2.32 0 6756 28 0 234
v_all_customers 36614 36614 0 0.00 0 150 4 0 0
v_all_suppliers 30162 30162 0 0.00 0 69 4 0 0
v_all_wares 40218 40218 0 0.00 0 125 4 0 0
v_cancel_adding_invoice 840 840 0 0.00 0 4642 21 0 0
v_cancel_client_order 3412 3412 0 0.00 0 1930 9 0 0
v_cancel_customer_prepayment 1372 1372 0 0.00 0 3274 14 0 0
v_cancel_customer_reserve 1380 1380 0 0.00 0 6715 19 0 0
v_cancel_payment_to_supplier 744 744 0 0.00 0 4676 20 0 0
v_cancel_supplier_invoice 808 790 18 2.22 0 603 25 0 18
v_cancel_supplier_order 794 794 0 0.00 0 3165 16 0 0
v_cancel_write_off 1974 1974 0 0.00 0 3771 25 0 0
v_max_id_clo_ord 5344 5344 0 0.00 0 1365 5 0 0
v_max_id_clo_res 938 938 0 0.00 0 696 8 0 0
v_max_id_ord_sup 5420 5420 0 0.00 0 3475 8 0 0
v_max_non_paid_invoice 1392 1392 0 0.00 0 69 8 0 0
v_max_non_paid_realizn 3676 3676 0 0.00 0 1819 8 0 0
v_min_id_clo_ord 5344 5344 0 0.00 0 2497 9 0 0
v_min_id_clo_res 938 938 0 0.00 0 2478 11 0 0
v_min_id_ord_sup 5420 5420 0 0.00 0 9653 18 0 0
v_min_non_paid_invoice 5709 5709 0 0.00 0 8113 21 0 0
v_min_non_paid_realizn 6963 6963 0 0.00 0 8980 25 0 0
v_our_firm 10178 10178 0 0.00 0 157 5 0 0
v_reserve_write_off 19698 19698 0 0.00 0 4780 11 0 0
x_lock_depdocs_on_canc_invoice 11383 11383 0 0.00 0 22428 125 0 0
x_lock_depdocs_on_canc_sup_ord 397 397 0 0.00 0 13096 423 0 0
x_make_qstorno_1000_1200 5345 5345 0 0.00 2 20000 1149 0 0
x_make_qstorno_1200_2000 5425 5425 0 0.00 3 71180 2794 0 0
x_make_qstorno_2100_3300 463364 463364 0 0.00 0 37222 198 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 31-12-2022 04:39:57 to 31-12-2022 04:40:01.

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
02:07:26 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
02:08:28 35035 8192000000 9732984192 10030706688 802619696 8.923 100 90 180 0 832344416 7950848 21773872
02:10:10 16007 8192000000 9751876224 10058907648 829176560 9.191 100 87 174 0 862687952 12773888 20737504
02:11:31 16719 8192000000 9781096736 10099228672 851981104 9.420 100 83 165 0 893502176 22176016 19345056
02:12:48 7199 8192000000 9849231952 10180239360 910702480 10.004 100 81 162 0 963244528 34846160 17695888
02:14:03 3409 8192000000 9900108304 10243747840 954472144 10.435 100 85 168 0 1020800352 49006128 17322080
02:15:10 1952 8192000000 9970850768 10323894272 988548320 10.767 100 90 171 0 1088143360 82540416 17054624
02:16:14 1868 8192000000 9986107728 10350260224 1018184896 11.054 100 92 179 0 1105962992 70645520 17132576
02:17:18 1529 8192000000 10024469776 10392424448 1040952592 11.274 100 93 184 0 1141575696 81610688 19012416
02:18:22 1074 8192000000 10041995344 10415271936 1056084688 11.419 100 97 190 1 1162152768 87684192 18383888
02:19:25 1161 8192000000 10051997200 10430849024 1064178512 11.496 100 94 184 0 1170803904 87241920 19383472
02:20:28 1440 8192000000 10032014096 10413789184 1071680704 11.568 100 72 139 0 1154153616 69238304 13234608
02:21:30 1502 8192000000 10056289280 10443735040 1075410576 11.604 100 85 159 1 1178162672 86414608 16337488
02:22:33 1173 8192000000 10050615440 10431139840 1077696368 11.626 100 94 170 1 1169256256 75081216 16478672
02:23:35 1073 8192000000 10059292752 10447339520 1092349296 11.765 100 94 179 0 1181432944 70573216 18510432
02:24:37 1313 8192000000 10056223904 10443759616 1094110048 11.782 100 98 186 2 1177196272 63786528 19299696
02:25:39 1254 8192000000 10073611776 10461986816 1102011648 11.857 100 94 183 0 1192875072 73042864 17820560
02:26:42 1102 8192000000 10084452368 10475454464 1101510192 11.852 100 99 183 1 1203775792 85014560 17251040
02:27:44 982 8192000000 10044491472 10434207744 1102106528 11.858 100 100 196 0 1167799712 45855952 19837232
02:28:46 1132 8192000000 10058244000 10448822272 1092868576 11.770 100 97 181 2 1178312400 67254192 18189632
02:29:48 1538 8192000000 10069816160 10465378304 1100408032 11.842 100 92 173 0 1195364816 76097808 18858976
02:30:51 1621 8192000000 10012591648 10407403520 1075467440 11.604 99 69 122 0 1139418272 49853280 14097552
02:31:54 1157 8192000000 10000488880 10380619776 1042032240 11.284 98 83 157 0 1124253216 65178912 17042064
02:32:55 1155 8192000000 9999402032 10381717504 1048177968 11.343 100 88 169 2 1125468960 59926032 17364960
02:33:57 1035 8192000000 10008744432 10387783680 1052909744 11.389 100 90 175 1 1128405584 56808464 18687376
02:34:59 1609 8192000000 9930566608 10295361536 1000120704 10.880 98 86 158 2 1053396512 37116656 16159152
02:36:02 823 8192000000 9984605296 10359304192 1037962880 11.245 100 93 171 3 1107728704 49657328 20108496
02:37:04 3737 8192000000 10011158000 10394357760 1051173152 11.372 100 67 126 0 1135516128 71669024 12673952
02:38:10 3274 8192000000 10027946960 10405253120 1071660704 11.568 100 82 156 6 1148874272 62653216 14560352
02:39:26 1362 8192000000 10049429696 10428346368 1088555504 11.729 100 95 177 1 1166353072 59207056 18590512
02:40:31 939 8192000000 10091271168 10475175936 1111030016 11.942 100 91 172 3 1202914832 75055360 16829456
02:41:37 9177 8192000000 10096300416 10487554048 1122083776 12.047 100 70 140 0 1217899520 80645632 15170112
02:42:52 14841 8192000000 10158017936 10564743168 1130044736 12.122 100 70 138 1 1263795424 118623248 15127440
02:44:19 3592 8192000000 10184592912 10585317376 1141483104 12.229 100 77 148 3 1298313264 140887360 15942800
02:45:36 5549 8192000000 10221449232 10625658880 1156247936 12.368 100 66 130 1 1334153168 163316832 14588400
02:46:44 967 8192000000 10268560720 10667188224 1170872064 12.505 100 98 190 1 1383155616 190586960 21696592
02:47:50 3578 8192000000 10308596384 10716786688 1176926048 12.562 100 77 154 0 1425758016 231935936 16896032
02:48:58 1771 8192000000 10319685600 10726371328 1182711968 12.615 100 78 155 1 1431690480 231109248 17869264
02:50:02 1011 8192000000 10354772304 10774073344 1187426000 12.659 100 84 166 0 1472817424 266006720 19384704
02:51:06 914 8192000000 10377405184 10786693120 1197613856 12.754 100 97 188 2 1487077968 267750832 21713280
02:52:10 2577 8192000000 10395314480 10827845632 1196314480 12.742 100 85 155 1 1512798864 298215856 18268528
02:53:15 822 8192000000 10407417520 10845614080 1200288736 12.779 100 95 183 0 1514718000 291864768 22564496
02:54:19 3519 8192000000 10445097088 10866802688 1194840000 12.728 100 71 142 0 1550292080 338995536 16456544
02:55:25 1852 8192000000 10414895904 10837893120 1195955056 12.739 100 62 116 1 1524291008 314433488 13902464
02:56:35 1080 8192000000 10450748272 10860658688 1171166368 12.508 99 83 157 2 1556867376 367855008 17846000
02:57:42 1975 8192000000 10514425440 10928345088 1187123664 12.657 100 89 176 0 1618111760 409036496 21951600
02:58:48 1057 8192000000 10548617392 10964779008 1187957088 12.664 100 93 180 1 1646976528 436727184 22292256
02:59:52 756 8192000000 10550716416 10990878720 1196037616 12.740 100 97 188 0 1656376832 437391504 22947712
03:00:54 1016 8192000000 10545544736 10983694336 1179784064 12.588 99 88 172 1 1651175424 449931216 21460144
03:02:00 2982 8192000000 10602520816 11035344896 1189236240 12.676 100 68 134 2 1704732352 498283568 17212544
03:03:06 5827 8192000000 10603183120 11073904640 1190205536 12.685 100 51 102 0 1754179616 550746976 13227104
03:04:15 4735 8192000000 10642315520 11071946752 1189832400 12.682 100 57 113 0 1741826736 537302912 14691424
03:05:28 3673 8192000000 10618208320 11073622016 1175566576 12.549 100 67 128 0 1722745040 530176976 17001488
03:06:34 3549 8192000000 10568582240 11053539328 1163484384 12.436 100 57 114 2 1668557120 490718848 14353888
03:07:39 20361 8192000000 10525452224 11030319104 1169724416 12.494 100 41 80 0 1675824672 495264624 10835632
03:09:04 22269 8192000000 10460750832 10968285184 1161558560 12.418 100 29 58 0 1571676448 402713280 7404608
03:10:31 34544 8192000000 10302727872 10856529920 1156551248 12.371 100 18 36 0 1480041440 318621056 4869136
03:12:29 37179 8192000000 10117098480 10619621376 1136788016 12.185 99 15 28 0 1230186512 90158400 3240096
03:14:07 27303 8192000000 10093684352 10534449152 1119027232 12.018 98 32 58 0 1183700512 59654448 5018832
03:15:34 49242 8192000000 10072679296 10544807936 1128426560 12.107 100 12 24 0 1174096256 43526320 2143376
03:17:24 75083 8192000000 10132224208 10586828800 1129358336 12.115 99 21 39 0 1224710416 90849216 4502864
03:19:49 96060 8192000000 10073102912 10512183296 1093947184 11.780 96 19 38 0 1169341856 71309616 4085056
03:22:27 80999 8192000000 10071444672 10512486400 1116519984 11.994 98 14 28 0 1141598416 22743184 2335248
03:24:52 63663 8192000000 10091188080 10549850112 1139733920 12.213 100 23 46 0 1200624752 56000096 4890736
03:26:57 156386 8192000000 10110803440 10584932352 1118598384 12.014 98 7 15 0 1148599552 28895952 1105216
03:30:34 88720 8192000000 10090038192 10544865280 1130951888 12.130 99 7 14 1 1166053152 33759216 1342048
03:33:31 59803 8192000000 10077652304 10530516992 1132394720 12.144 99 45 86 0 1179371648 37849504 9127424
03:35:35 61001 8192000000 10118999280 10557341696 1129176272 12.114 98 57 113 0 1213109616 71345040 12588304
03:37:44 17819 8192000000 10118598736 10551431168 1151765728 12.326 100 63 121 0 1202213776 37954416 12493632
03:39:03 21374 8192000000 10147295248 10571849728 1151958032 12.328 100 57 107 2 1233757136 70335776 11463328
03:40:25 25385 8192000000 10082044224 10573332480 1124752784 12.072 99 30 59 1 1158794224 27601712 6439728
03:41:51 38414 8192000000 10077466528 10537488384 1110491200 11.937 98 15 29 1 1163987456 50474144 3022112
03:43:31 30062 8192000000 10081401952 10530856960 1136053552 12.178 100 17 32 0 1173634064 34086320 3494192
03:45:04 36825 8192000000 10057651440 10512195584 1098827424 11.827 97 18 36 0 1154481648 51868480 3785744
03:46:48 64848 8192000000 10115762528 10580836352 1133395568 12.153 100 23 46 0 1204468128 65260080 5812480
03:49:26 39859 8192000000 10044742096 10512760832 1116759952 11.996 99 20 41 0 1142365792 21581024 4024816
03:51:13 87307 8192000000 10057238464 10557100032 1135950320 12.177 100 9 18 0 1176955264 39014016 1990928
03:53:47 22193 8192000000 10066229920 10517663744 1129820048 12.120 100 23 46 0 1161788496 27314592 4653856
03:55:14 38260 8192000000 10092514752 10537439232 1115782496 11.987 99 37 70 0 1203713408 80267024 7663888
03:56:52 58318 8192000000 10079158480 10534244352 1099773296 11.835 98 20 40 0 1160528928 55960496 4795136
03:58:51 43460 8192000000 10122825808 10562064384 1096329088 11.803 97 35 69 0 1218004592 114247968 7427536
04:00:54 58226 8192000000 10133830016 10589589504 1087295008 11.717 97 26 52 0 1206621200 114014064 5312128
04:02:57 44412 8192000000 10081155344 10523004928 1097782992 11.817 98 19 38 0 1170888768 69407632 3698144
04:04:43 29503 8192000000 10105114320 10575908864 1114101616 11.971 99 34 66 0 1197714768 76951792 6661360
04:06:15 92963 8192000000 10093750304 10588385280 1124112496 12.066 100 42 82 1 1198965328 66450176 8402656
04:09:15 48725 8192000000 10105622160 10585300992 1137232160 12.189 100 48 96 1 1212069328 64308368 10528800
04:11:07 14619 8192000000 10053550864 10552676352 1130392528 12.125 100 4 6 1 1157399520 26403536 603456
04:12:22 79398 8192000000 10065877824 10547273728 1126143104 12.085 99 12 23 0 1161757024 33664896 1949024
04:14:54 27059 8192000000 10076747312 10508910592 1110173888 11.934 99 42 84 0 1166536624 47581872 8780864
04:16:21 39186 8192000000 10103432304 10540744704 1104864656 11.884 97 19 38 0 1167179488 58448704 3866128
04:18:05 26208 8192000000 10183256352 10622865408 1150088832 12.310 100 47 94 0 1272336384 112242640 10004912
04:19:44 34421 8192000000 10178564880 10616057856 1145677424 12.269 100 31 58 1 1262223808 110209216 6337168
04:21:29 11294 8192000000 10222973904 10651754496 1159444224 12.398 100 48 96 1 1316310336 146079824 10786288
04:22:42 30562 8192000000 10243680976 10690289664 1160513952 12.408 100 31 62 1 1317067440 149920624 6632864
04:24:14 60465 8192000000 10286207376 10740305920 1158860848 12.393 100 16 32 0 1344065904 181073520 4131536
04:26:39 44935 8192000000 10134484240 10632404992 1137478000 12.192 100 18 34 0 1332234768 189859072 4897696
04:28:26 96347 8192000000 10088501760 10545012736 1131738432 12.138 100 33 66 1 1180432400 42229664 6464304
04:31:03 60495 8192000000 10090788672 10569281536 1131440960 12.135 99 15 30 0 1195855888 61659376 2755552
04:33:05 44739 8192000000 10101692560 10578571264 1116211024 11.991 98 11 23 0 1179877568 61226992 2439552
04:34:54 66268 8192000000 10075907760 10553040896 1123284048 12.058 99 12 24 0 1189156800 63698880 2173872
04:37:06 30243 8192000000 9035670848 9284673536 201972720 2.406 18 4 7 0 206007152 3428560 605872

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 31-12-2022 04:40:03 to 31-12-2022 04:40:19.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 117 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 31-12-2022 04:40:20 to 31-12-2022 04:40:22.

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          218444
MON$OLDEST_ACTIVE               218445
MON$OLDEST_SNAPSHOT             218445
MON$NEXT_TRANSACTION            218445
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                       2098304
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             770
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 ---
--- end of diff output ---

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

31.12.22 04:40:23. 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-31 04:40:26 No severe PSQL-related problems occured
31.12.22 04:40:27. End of report.