Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 27.09.2022 22: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 15371232 kB
MemAvailable 30613340 kB
Buffers 0 kB
Cached 16368364 kB
SwapCached 2052 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 3.0.11
DB_NAME                         /home/bases/oltp30-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/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/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=3333
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/oltp30-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-oltp30
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-09-27 22:38:27
DTS_END                         2022-09-27 22:38:27.7710
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/37070
TRN_ID                          239492
ATT_ID                          650
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: 9152.28 22925 1098274
customer order: creation 165.53 363 19863
customer order: refuse 14.52 362 1742
order to supplier: creation 45.13 1248 5416
order to supplier: removal 3.28 109438 393
invoice (draft): creation 46.01 3648 5521
invoice (draft): removal 52.14 842 6257
invoice accept: apply 41.33 17899 4959
invoice accept: cancel 47.27 909 5672
customer reserve: creation 3755.29 179 450635
customer reserve: removal 4691.02 60 562922
realization accept: apply 82.75 184 9930
realization accept: cancel 81.32 113 9758
payment from customer: creation 57.84 122 6941
payment from customer: removal 5.43 79 652
payment to supplier: creation 47.78 124 5733
payment to supplier: removal 3.56 76 427
service: total inventory turnovers 4.67 1310 560
service: total monetary turnovers 6.57 37 788
service: aggregate perf. data 0.84 23730 101
service: refresh index statistics 0.03 297784 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 27-09-2022 22:39:51 to 27-09-2022 22:39:54.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2403
TEST_TIME 2 4691
TEST_TIME 3 5260
TEST_TIME 4 5541
TEST_TIME 5 5295
TEST_TIME 6 5689
TEST_TIME 7 5468
TEST_TIME 8 5362
TEST_TIME 9 5310
TEST_TIME 10 5765
TEST_TIME 11 6073
TEST_TIME 12 6382
TEST_TIME 13 6677
TEST_TIME 14 6946
TEST_TIME 15 7205
TEST_TIME 16 7384
TEST_TIME 17 7887
TEST_TIME 18 7740
TEST_TIME 19 8054
TEST_TIME 20 8339
TEST_TIME 21 8273
TEST_TIME 22 8443
TEST_TIME 23 8859
TEST_TIME 24 9332
TEST_TIME 25 9510
TEST_TIME 26 9701
TEST_TIME 27 9782
TEST_TIME 28 9638
TEST_TIME 29 9657
TEST_TIME 30 9785
TEST_TIME 31 10446
TEST_TIME 32 10960
TEST_TIME 33 10823
TEST_TIME 34 10633
TEST_TIME 35 10667
TEST_TIME 36 10504
TEST_TIME 37 10531
TEST_TIME 38 10470
TEST_TIME 39 10369
TEST_TIME 40 10413
TEST_TIME 41 10319
TEST_TIME 42 10249
TEST_TIME 43 10165
TEST_TIME 44 10078
TEST_TIME 45 10014
TEST_TIME 46 9915
TEST_TIME 47 9886
TEST_TIME 48 10044
TEST_TIME 49 9997
TEST_TIME 50 9863
TEST_TIME 51 9842
TEST_TIME 52 9753
TEST_TIME 53 9589
TEST_TIME 54 9698
TEST_TIME 55 9608
TEST_TIME 56 9542
TEST_TIME 57 9533
TEST_TIME 58 9506
TEST_TIME 59 9469
TEST_TIME 60 9540
TEST_TIME 61 9534
TEST_TIME 62 9543
TEST_TIME 63 9539
TEST_TIME 64 9546
TEST_TIME 65 9599
TEST_TIME 66 9655
TEST_TIME 67 9755
TEST_TIME 68 9682
TEST_TIME 69 9645
TEST_TIME 70 9607
TEST_TIME 71 9632
TEST_TIME 72 9679
TEST_TIME 73 9671
TEST_TIME 74 9651
TEST_TIME 75 9567
TEST_TIME 76 9524
TEST_TIME 77 9445
TEST_TIME 78 9440
TEST_TIME 79 9420
TEST_TIME 80 9399
TEST_TIME 81 9400
TEST_TIME 82 9334
TEST_TIME 83 9261
TEST_TIME 84 9318
TEST_TIME 85 9382
TEST_TIME 86 9350
TEST_TIME 87 9352
TEST_TIME 88 9329
TEST_TIME 89 9458
TEST_TIME 90 9428
TEST_TIME 91 9438
TEST_TIME 92 9509
TEST_TIME 93 9511
TEST_TIME 94 9574
TEST_TIME 95 9567
TEST_TIME 96 9656
TEST_TIME 97 9652
TEST_TIME 98 9740
TEST_TIME 99 9658
TEST_TIME 100 9588
TEST_TIME 101 9528
TEST_TIME 102 9547
TEST_TIME 103 9526
TEST_TIME 104 9490
TEST_TIME 105 9475
TEST_TIME 106 9435
TEST_TIME 107 9452
TEST_TIME 108 9444
TEST_TIME 109 9390
TEST_TIME 110 9368
TEST_TIME 111 9347
TEST_TIME 112 9326
TEST_TIME 113 9330
TEST_TIME 114 9302
TEST_TIME 115 9268
TEST_TIME 116 9242
TEST_TIME 117 9210
TEST_TIME 118 9189
TEST_TIME 119 9199
TEST_TIME 120 9154

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 27-09-2022 22:39:55 to 27-09-2022 22:39:58.

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 1516284 1516284 0 0.00 0 15056 6 0 0
doc_list_biud 1516284 1516284 0 0.00 0 79969 29 0 0
sp_add_invoice_to_stock 5074 4959 115 2.26 43 163052 17900 0 115
sp_cancel_adding_invoice 5672 5672 0 0.00 2 55035 910 0 0
sp_cancel_client_order 1742 1742 0 0.00 4 13857 363 0 0
sp_cancel_customer_reserve 562922 562922 0 0.00 0 57005 60 0 0
sp_cancel_pay_from_customer 652 652 0 0.00 3 2305 80 0 0
sp_cancel_pay_to_supplier 427 427 0 0.00 2 2935 76 0 0
sp_cancel_supplier_invoice 6273 6257 16 0.25 1 79985 843 0 16
sp_cancel_supplier_order 393 393 0 0.00 7 982206 109438 0 0
sp_cancel_write_off 9758 9758 0 0.00 1 10436 114 0 0
sp_client_order 19863 19863 0 0.00 3 42023 364 0 0
sp_customer_reserve 450635 450635 0 0.00 0 102823 179 0 0
sp_fill_shopping_cart 30788 30788 0 0.00 1 11777 44 0 0
sp_fill_shopping_cart_clo_res 450632 450632 0 0.00 0 27835 13 0 0
sp_get_clo_for_invoice 4956 4956 0 0.00 0 48057 372 0 0
sp_kill_cost_storno 16509 16509 0 0.00 0 12803 60 0 0
sp_kill_qstorno_ret_qs2qd 1142252 1142252 0 0.00 0 79959 33 0 0
sp_kill_qty_storno 601630 601630 0 0.00 0 79963 71 0 0
sp_lock_selected_doc 20601 20601 0 0.00 0 9049 6 0 0
sp_make_cost_storno 27560 27560 0 0.00 0 13022 52 0 0
sp_multiply_rows_for_pdistr 27560 27560 0 0.00 0 7248 25 0 0
sp_multiply_rows_for_qdistr 19863 19863 0 0.00 0 13204 151 0 0
sp_pay_from_customer 6941 6941 0 0.00 4 11487 123 0 0
sp_pay_to_supplier 5733 5733 0 0.00 5 17018 125 0 0
sp_payment_common 12674 12674 0 0.00 1 11097 32 0 0
sp_qd_handle_on_cancel_clo 1742 1742 0 0.00 0 13846 335 0 0
sp_qd_handle_on_invoice_upd_sts 10628 10628 0 0.00 0 37897 373 0 0
sp_qd_handle_on_reserve_upd_sts 9930 9930 0 0.00 0 20584 70 0 0
sp_reserve_write_off 9930 9930 0 0.00 6 21414 185 0 0
sp_supplier_invoice 5521 5521 0 0.00 5 64984 3649 0 0
sp_supplier_order 5416 5416 0 0.00 4 38486 1249 0 0
srv_aggregate_perf_data 101 101 0 0.00 1934 551838 23730 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 626 151970 47610 0 0
srv_find_qd_qs_mism 1052086 1052086 0 0.00 0 15052 5 0 0
srv_make_invnt_saldo 560 560 0 0.00 31 80509 1310 0 0
srv_make_money_saldo 788 788 0 0.00 0 5291 37 0 0
srv_recalc_idx_stat 4 4 0 0.00 192768 371489 297785 0 0
t$perf-abend:sp_add_invoice_to_stock 115 115 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 16 16 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4959 4959 0 0.00 0 12057 183 0 0
t$perf-norm:sp_cancel_adding_invoice 401 401 0 0.00 0 13740 116 0 0
t$perf-norm:sp_cancel_client_order 1742 1742 0 0.00 0 3593 11 0 0
t$perf-norm:sp_cancel_customer_reserve 664 664 0 0.00 0 5873 31 0 0
t$perf-norm:sp_cancel_pay_from_customer 652 652 0 0.00 0 554 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 427 427 0 0.00 0 2368 8 0 0
t$perf-norm:sp_cancel_supplier_invoice 428 428 0 0.00 0 1230 15 0 0
t$perf-norm:sp_cancel_supplier_order 393 393 0 0.00 0 28412 1951 0 0
t$perf-norm:sp_cancel_write_off 1008 1008 0 0.00 0 2473 7 0 0
t$perf-norm:sp_client_order 19864 19864 0 0.00 0 11338 18 0 0
t$perf-norm:sp_customer_reserve 977 977 0 0.00 0 1523 8 0 0
t$perf-norm:sp_pay_from_customer 6941 6941 0 0.00 0 14029 15 0 0
t$perf-norm:sp_pay_to_supplier 5733 5733 0 0.00 0 5864 13 0 0
t$perf-norm:sp_reserve_write_off 9930 9930 0 0.00 0 8603 11 0 0
t$perf-norm:sp_supplier_invoice 5521 5521 0 0.00 0 7918 19 0 0
t$perf-norm:sp_supplier_order 5418 5418 0 0.00 0 12308 22 0 0
t$perf-norm:srv_aggregate_perf_data 101 101 0 0.00 0 193 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 393 13 0 0
t$perf-norm:srv_make_invnt_saldo 560 560 0 0.00 0 1785 22 0 0
t$perf-norm:srv_make_money_saldo 788 788 0 0.00 0 13399 47 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 3 0 0
v_add_invoice_to_stock 10142 9912 230 2.26 0 6894 21 0 230
v_all_customers 36264 36264 0 0.00 0 118 1 0 0
v_all_suppliers 29998 29998 0 0.00 0 28 1 0 0
v_all_wares 39726 39726 0 0.00 0 25 1 0 0
v_cancel_adding_invoice 802 802 0 0.00 0 198 5 0 0
v_cancel_client_order 3484 3484 0 0.00 0 3496 6 0 0
v_cancel_customer_prepayment 1304 1304 0 0.00 0 2154 5 0 0
v_cancel_customer_reserve 1328 1328 0 0.00 0 1659 7 0 0
v_cancel_payment_to_supplier 854 854 0 0.00 0 2914 9 0 0
v_cancel_supplier_invoice 888 856 32 3.60 0 1775 14 0 32
v_cancel_supplier_order 786 786 0 0.00 0 5295 17 0 0
v_cancel_write_off 2016 2016 0 0.00 0 5232 21 0 0
v_max_id_clo_ord 5416 5416 0 0.00 0 944 2 0 0
v_max_id_clo_res 977 977 0 0.00 0 1695 13 0 0
v_max_id_ord_sup 5509 5509 0 0.00 0 4285 5 0 0
v_max_non_paid_invoice 1659 1659 0 0.00 0 1209 3 0 0
v_max_non_paid_realizn 3752 3752 0 0.00 0 4143 6 0 0
v_min_id_clo_ord 5416 5416 0 0.00 0 1370 4 0 0
v_min_id_clo_res 977 977 0 0.00 0 3483 6 0 0
v_min_id_ord_sup 5509 5509 0 0.00 0 5053 6 0 0
v_min_non_paid_invoice 5733 5733 0 0.00 0 9265 19 0 0
v_min_non_paid_realizn 6941 6941 0 0.00 0 11092 22 0 0
v_our_firm 9840 9840 0 0.00 0 29 1 0 0
v_reserve_write_off 19860 19860 0 0.00 0 5887 7 0 0
x_lock_depdocs_on_canc_invoice 11501 11501 0 0.00 0 11548 108 0 0
x_lock_depdocs_on_canc_sup_ord 393 393 0 0.00 0 4927 365 0 0
x_make_qstorno_1000_1200 5416 5416 0 0.00 1 38480 1171 0 0
x_make_qstorno_1200_2000 5521 5521 0 0.00 2 62084 3472 0 0
x_make_qstorno_2100_3300 450635 450635 0 0.00 0 102821 163 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 27-09-2022 22:40:00 to 27-09-2022 22:40:04.

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
20:08:31 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:33 29048 8192000000 9909974976 10219917312 983486688 10.718 100 89 178 0 1012847296 5891392 23469216
20:11:08 27052 8192000000 9960382480 10286514176 1038205328 11.247 100 89 176 0 1069327776 8208960 22913488
20:12:37 11918 8192000000 9999018928 10334539776 1081869744 11.665 100 88 176 0 1113656320 9493360 22293216
20:13:52 10389 8192000000 10045013728 10392535040 1125838896 12.082 100 85 168 0 1157526224 11348752 20338576
20:15:10 6229 8192000000 10087178080 10445545472 1169869152 12.496 100 90 180 1 1203036624 13087424 20080048
20:16:20 3771 8192000000 10122085984 10490617856 1212958016 12.897 100 84 170 1 1245261344 13644160 18659168
20:17:26 1628 8192000000 10165201712 10538385408 1251076784 13.248 100 87 174 2 1283157536 12924160 19156592
20:18:29 2156 8192000000 10179200736 10558685184 1271442944 13.435 100 85 167 1 1301909296 12286224 18180128
20:19:33 3379 8192000000 10193508240 10594144256 1297583760 13.673 100 63 126 0 1323777792 12737856 13456176
20:20:43 5542 8192000000 10193450768 10599321600 1304703904 13.738 100 54 109 2 1325979312 9491280 11784128
20:21:51 5532 8192000000 10201745328 10611101696 1313207328 13.815 100 36 72 1 1329048432 7711616 8129488
20:22:58 7417 8192000000 10209652192 10620530688 1321166224 13.887 100 30 60 0 1334818032 7052256 6599552
20:24:07 71081 8192000000 10220593872 10638127104 1327019600 13.940 100 37 73 1 1341684320 5657168 9007552
20:26:19 6310 8192000000 10222567248 10645028864 1331423952 13.980 100 23 46 0 1343353216 6797216 5132048
20:27:26 4092 8192000000 10207403520 10628423680 1310907344 13.794 99 17 34 1 1319695312 4949376 3838592
20:28:31 53541 8192000000 10192218480 10606497792 1283899520 13.549 98 24 48 0 1295738880 5275088 6564272
20:30:25 5839 8192000000 10140589120 10540822528 1210149440 12.870 95 26 53 0 1221437728 4988624 6299664
20:31:32 7565 8192000000 10133859872 10536292352 1225530576 13.013 98 21 42 0 1236323088 5763584 5028928
20:32:40 13462 8192000000 10144759792 10550247424 1256911680 13.302 100 16 30 1 1266361840 5160048 4290112
20:33:54 16291 8192000000 10163649280 10558689280 1255683760 13.290 99 57 115 0 1276778624 6802288 14292576
20:35:10 7825 8192000000 10185952544 10596814848 1291378752 13.617 100 26 52 1 1303038128 5915216 5744160
20:36:21 5525 8192000000 10191302928 10603532288 1301993200 13.713 100 23 46 0 1312052336 5319200 4739936
20:37:28 12660 8192000000 10198733104 10613547008 1307665184 13.765 100 25 50 0 1318717920 5335616 5717120
20:38:41 59535 8192000000 10241837984 10649260032 1333144544 13.996 100 37 71 1 1346769248 6314816 7309888
20:40:45 10082 8192000000 10266113424 10682568704 1355186080 14.194 100 46 90 0 1381102704 16276416 9640208
20:42:00 23435 8192000000 10277768064 10699788288 1366941152 14.300 100 38 77 0 1387879520 11882832 9055536
20:43:30 16508 8192000000 10303125264 10719518720 1388647184 14.494 100 65 125 1 1418323312 15529088 14147040
20:44:50 6133 8192000000 10303528992 10729013248 1394890976 14.549 100 45 88 1 1418829056 14048464 9889616
20:46:04 10786 8192000000 10323677936 10748919808 1409272192 14.677 100 47 94 1 1438021120 17736736 11012192
20:47:18 12681 8192000000 10327159392 10754961408 1412541136 14.707 100 37 72 0 1441559344 19771520 9246688
20:48:40 16718 8192000000 10343824672 10766651392 1401075952 14.605 99 57 114 0 1444755232 29750640 13928640
20:50:03 4043 8192000000 10340043520 10765791232 1404151088 14.632 100 63 124 0 1451903072 32065600 15686384
20:51:19 5496 8192000000 10335957392 10753466368 1398456832 14.581 100 68 136 0 1448988864 33889056 16642976
20:52:30 628 8192000000 10323522032 10727817216 1375379216 14.375 99 88 174 0 1427658688 31490720 20788752
20:53:35 3394 8192000000 10325266160 10736869376 1377358192 14.393 100 71 140 1 1431504528 37791248 16355088
20:54:46 3415 8192000000 10318446928 10726141952 1365788736 14.289 100 79 157 0 1426095632 41477312 18829584
20:55:55 12462 8192000000 10304614880 10724163584 1355353968 14.196 99 47 92 0 1402436944 35546592 11536384
20:57:17 1281 8192000000 10325120800 10735140864 1370469408 14.331 100 66 132 1 1427856944 40711168 16676368
20:58:24 7278 8192000000 10309321264 10732441600 1362624128 14.261 99 31 62 0 1409908320 39097664 8186528
20:59:51 2715 8192000000 10329543744 10740944896 1378128720 14.400 100 70 136 0 1438307904 42203920 17975264
21:00:56 4249 8192000000 10350700880 10760425472 1382092160 14.435 100 73 143 0 1448324448 47198656 19033632
21:02:08 625 8192000000 10355971408 10765959168 1384348256 14.455 100 88 175 0 1457192800 50749536 22095008
21:03:12 13313 8192000000 10328599504 10752786432 1365855600 14.290 98 35 69 0 1412465504 37518944 9090960
21:04:29 26856 8192000000 10332471632 10762088448 1390041936 14.506 100 28 56 0 1435044960 37441792 7561232
21:05:59 14673 8192000000 10335079904 10774663168 1384033024 14.453 99 24 48 0 1432685264 42438000 6214240
21:07:16 23209 8192000000 10325581200 10768486400 1376754400 14.388 100 25 50 1 1432837120 48745696 7337024
21:08:58 6425 8192000000 10326412304 10755067904 1365980720 14.291 100 43 88 0 1429233504 51722336 11530448
21:10:25 31354 8192000000 10294422736 10721460224 1348340768 14.133 97 21 38 2 1359092848 5813424 4938656
21:11:59 65404 8192000000 10301816064 10743631872 1390523968 14.511 100 20 40 0 1413183472 18065872 4593632
21:14:04 94337 8192000000 10315434928 10766127104 1400604144 14.600 100 21 42 0 1422536608 16498448 5434016
21:16:42 47044 8192000000 10326388864 10766778368 1407868352 14.665 100 44 87 1 1427833792 9946032 10019408
21:18:30 69322 8192000000 10306636832 10757713920 1364768432 14.280 97 19 38 0 1378464304 9286848 4409024
21:20:40 24677 8192000000 10327879136 10766708736 1404266560 14.633 100 39 76 0 1423072816 9472800 9333456
21:22:05 95273 8192000000 10275823840 10725146624 1345783232 14.110 96 13 27 0 1364351984 15703664 2865088
21:24:42 24764 8192000000 10318865584 10776973312 1404205328 14.632 100 18 37 1 1428968800 20239424 4524048
21:26:15 20470 8192000000 10327565232 10785673216 1416076432 14.738 100 45 85 1 1446250000 20093040 10080528
21:27:37 151970 8192000000 10308764096 10772066304 1389988960 14.506 99 19 38 0 1408135488 13539216 4607312
21:31:15 72779 8192000000 10294083232 10762035200 1386637056 14.476 99 33 64 0 1410182096 15893712 7651328
21:33:45 79392 8192000000 10306039216 10771857408 1376648288 14.387 98 8 16 0 1395770752 16741600 2380864
21:36:05 64314 8192000000 10301691120 10781630464 1382679056 14.440 99 10 21 0 1407638864 22523696 2436112
21:38:09 64880 8192000000 10295352048 10747547648 1368716064 14.316 98 18 38 0 1380717696 8150912 3850720
21:40:18 80636 8192000000 10273021680 10722168832 1293965520 13.640 93 10 20 0 1307679984 12020736 1693728
21:42:39 107993 8192000000 10285192464 10747211776 1319025488 13.868 95 14 27 0 1334700832 12624624 3050720
21:45:27 80767 8192000000 10271573424 10708328448 1234433072 13.095 90 13 26 0 1252050960 14781824 2836064
21:47:49 33279 8192000000 10275254288 10706374656 1323802256 13.911 98 39 78 0 1345819728 13506384 8511088
21:49:25 77313 8192000000 10271153456 10722951168 1334983232 14.012 98 31 62 1 1353164400 11526960 6654208
21:51:44 83080 8192000000 10257492064 10710532096 1327679664 13.946 97 12 24 0 1346070848 15648640 2742544
21:54:16 47225 8192000000 10258653776 10688782336 1316010912 13.841 97 27 54 0 1328661872 7387984 5262976
21:57:08 70351 8192000000 10249607424 10707550208 1336076960 14.022 98 12 24 0 1349108928 10532480 2499488
21:59:31 79092 8192000000 10263865344 10715279360 1362584736 14.261 100 22 42 0 1378595120 11006560 5003824
22:02:30 94496 8192000000 10245252528 10696216576 1270132912 13.423 93 13 26 0 1287706448 14759728 2813808
22:05:09 54148 8192000000 10287981568 10741276672 1377880432 14.398 100 34 68 0 1400188864 14365104 7943328
22:07:03 73309 8192000000 10286525040 10747035648 1370486128 14.331 99 11 23 0 1387960992 14831216 2643648
22:09:19 91003 8192000000 10302008608 10764734464 1397090304 14.569 100 15 30 0 1417334496 16971168 3273024
22:11:51 49125 8192000000 10309143808 10766233600 1377919360 14.398 98 16 32 0 1392920304 11815408 3185536
22:13:44 59878 8192000000 10313342880 10777477120 1356354384 14.205 96 23 46 0 1378446096 16987232 5104480
22:15:45 57524 8192000000 10322544000 10786156544 1393947312 14.541 99 14 27 0 1413072752 15838128 3287312
22:17:43 105709 8192000000 10319574912 10778750976 1405940864 14.648 99 19 39 0 1421679392 11603104 4135424
22:20:31 75994 8192000000 10305420240 10773671936 1375209104 14.374 97 12 24 0 1387884352 9222736 3452512
22:23:05 49434 8192000000 10300544192 10764808192 1382672432 14.440 98 18 38 1 1401500880 14405360 4423088
22:25:01 76838 8192000000 10290224480 10745909248 1343508704 14.089 96 25 50 1 1367821744 18129328 6183712
22:27:35 109516 8192000000 10277430896 10725044224 1310861456 13.794 94 20 38 0 1323210320 8426848 3922016
22:30:51 64143 8192000000 10256809872 10717028352 1321489776 13.890 96 10 20 0 1332330336 8357184 2483376
22:32:56 65339 8192000000 10275532016 10723065856 1351606832 14.162 98 8 16 0 1360104800 6496848 2001120
22:35:17 66100 8192000000 10250964096 10707410944 1332508528 13.990 97 20 40 0 1344150960 7106768 4535664
22:37:35 55189 8192000000 9139138896 9386303488 287603904 3.391 22 5 10 0 291000592 2396640 1000048

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 27-09-2022 22:40:06 to 27-09-2022 22:40:21.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 115 v_add_invoice_to_stock 335544517
except 16 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 27-09-2022 22:40:22 to 27-09-2022 22:40:24.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          239491
MON$OLDEST_ACTIVE               239891
MON$OLDEST_SNAPSHOT             239891
MON$NEXT_TRANSACTION            239891
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-03 16:41:55.3470
MON$PAGES                       2057504
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33631 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33631 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33631 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33631 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.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 ---
:132: 
:133: mscs-test-161.budzdorov.ru    Tue Sep 27 22:38:32 2022
:134:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:135: 
:136: 
:137: mscs-test-161.budzdorov.ru    Tue Sep 27 22:38:32 2022
:138:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:139: 
--- end of diff output ---

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

27.09.22 22:40:25. There are 3 logs in /var/tmp/logs-oltp30 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-09-27 22:40:27 No severe PSQL-related problems occured
27.09.22 22:40:29. End of report.