Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 10.08.2022 19: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 15991068 kB
MemAvailable 30963952 kB
Buffers 0 kB
Cached 15750616 kB
SwapCached 2508 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-08-10 19:38:28
DTS_END                         2022-08-10 19:38:28.4040
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_ORDER(38:5) ==> X_MAKE_QSTORNO_1000_1200(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/34138
TRN_ID                          243022
ATT_ID                          726
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: 9301.53 21193 1116184
customer order: creation 177.84 330 21341
customer order: refuse 14.90 342 1788
order to supplier: creation 47.43 1111 5692
order to supplier: removal 3.41 95341 409
invoice (draft): creation 47.02 2991 5642
invoice (draft): removal 51.03 772 6123
invoice accept: apply 42.98 18112 5157
invoice accept: cancel 45.94 808 5513
customer reserve: creation 4012.82 179 481538
customer reserve: removal 4561.70 56 547404
realization accept: apply 87.23 205 10468
realization accept: cancel 76.68 121 9201
payment from customer: creation 60.42 131 7250
payment from customer: removal 5.74 76 689
payment to supplier: creation 49.58 163 5950
payment to supplier: removal 3.73 90 447
service: total inventory turnovers 5.22 993 626
service: total monetary turnovers 6.96 28 835
service: aggregate perf. data 0.89 17338 107
service: refresh index statistics 0.03 284664 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 10-08-2022 19:39:57 to 10-08-2022 19:39:59.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4448
TEST_TIME 2 3970
TEST_TIME 3 3971
TEST_TIME 4 4291
TEST_TIME 5 5371
TEST_TIME 6 5241
TEST_TIME 7 5219
TEST_TIME 8 5412
TEST_TIME 9 6074
TEST_TIME 10 6327
TEST_TIME 11 6204
TEST_TIME 12 6534
TEST_TIME 13 6955
TEST_TIME 14 6898
TEST_TIME 15 6724
TEST_TIME 16 6849
TEST_TIME 17 6890
TEST_TIME 18 7020
TEST_TIME 19 7384
TEST_TIME 20 7727
TEST_TIME 21 7699
TEST_TIME 22 7914
TEST_TIME 23 8350
TEST_TIME 24 8747
TEST_TIME 25 8773
TEST_TIME 26 8994
TEST_TIME 27 9672
TEST_TIME 28 10379
TEST_TIME 29 10529
TEST_TIME 30 10525
TEST_TIME 31 10288
TEST_TIME 32 10476
TEST_TIME 33 10577
TEST_TIME 34 10579
TEST_TIME 35 10643
TEST_TIME 36 10439
TEST_TIME 37 10184
TEST_TIME 38 10239
TEST_TIME 39 10029
TEST_TIME 40 9990
TEST_TIME 41 9852
TEST_TIME 42 9796
TEST_TIME 43 9829
TEST_TIME 44 10021
TEST_TIME 45 9926
TEST_TIME 46 9925
TEST_TIME 47 9836
TEST_TIME 48 9687
TEST_TIME 49 9708
TEST_TIME 50 9731
TEST_TIME 51 9722
TEST_TIME 52 9664
TEST_TIME 53 9626
TEST_TIME 54 9735
TEST_TIME 55 9744
TEST_TIME 56 9796
TEST_TIME 57 9759
TEST_TIME 58 9742
TEST_TIME 59 9734
TEST_TIME 60 9768
TEST_TIME 61 9861
TEST_TIME 62 9917
TEST_TIME 63 9862
TEST_TIME 64 9757
TEST_TIME 65 9742
TEST_TIME 66 9641
TEST_TIME 67 9642
TEST_TIME 68 9627
TEST_TIME 69 9572
TEST_TIME 70 9566
TEST_TIME 71 9496
TEST_TIME 72 9437
TEST_TIME 73 9369
TEST_TIME 74 9490
TEST_TIME 75 9511
TEST_TIME 76 9508
TEST_TIME 77 9493
TEST_TIME 78 9610
TEST_TIME 79 9632
TEST_TIME 80 9675
TEST_TIME 81 9633
TEST_TIME 82 9594
TEST_TIME 83 9585
TEST_TIME 84 9661
TEST_TIME 85 9640
TEST_TIME 86 9675
TEST_TIME 87 9585
TEST_TIME 88 9513
TEST_TIME 89 9543
TEST_TIME 90 9520
TEST_TIME 91 9483
TEST_TIME 92 9459
TEST_TIME 93 9434
TEST_TIME 94 9422
TEST_TIME 95 9435
TEST_TIME 96 9454
TEST_TIME 97 9438
TEST_TIME 98 9388
TEST_TIME 99 9466
TEST_TIME 100 9455
TEST_TIME 101 9448
TEST_TIME 102 9464
TEST_TIME 103 9500
TEST_TIME 104 9476
TEST_TIME 105 9498
TEST_TIME 106 9546
TEST_TIME 107 9563
TEST_TIME 108 9564
TEST_TIME 109 9495
TEST_TIME 110 9467
TEST_TIME 111 9452
TEST_TIME 112 9407
TEST_TIME 113 9401
TEST_TIME 114 9368
TEST_TIME 115 9390
TEST_TIME 116 9352
TEST_TIME 117 9335
TEST_TIME 118 9288
TEST_TIME 119 9364
TEST_TIME 120 9304

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 10-08-2022 19:40:00 to 10-08-2022 19:40:04.

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 1561056 1561056 0 0.00 0 23956 6 0 0
doc_list_biud 1561054 1561054 0 0.00 0 43543 25 0 0
sp_add_invoice_to_stock 5243 5157 86 1.64 25 235152 18113 0 86
sp_cancel_adding_invoice 5513 5513 0 0.00 4 73862 808 0 0
sp_cancel_client_order 1788 1788 0 0.00 3 13532 343 0 0
sp_cancel_customer_reserve 547404 547404 0 0.00 0 43544 56 0 0
sp_cancel_pay_from_customer 689 689 0 0.00 3 5703 76 0 0
sp_cancel_pay_to_supplier 447 447 0 0.00 3 7937 91 0 0
sp_cancel_supplier_invoice 6129 6123 6 0.09 2 25104 772 0 6
sp_cancel_supplier_order 409 409 0 0.00 8 1051700 95341 0 0
sp_cancel_write_off 9201 9201 0 0.00 1 20469 122 0 0
sp_client_order 21341 21341 0 0.00 3 25223 331 0 0
sp_customer_reserve 481538 481538 0 0.00 0 52127 179 0 0
sp_fill_shopping_cart 32670 32670 0 0.00 1 12171 38 0 0
sp_fill_shopping_cart_clo_res 481536 481536 0 0.00 0 22483 13 0 0
sp_get_clo_for_invoice 5152 5152 0 0.00 0 16202 285 0 0
sp_kill_cost_storno 15850 15850 0 0.00 0 11804 58 0 0
sp_kill_qstorno_ret_qs2qd 1110541 1110541 0 0.00 0 43540 31 0 0
sp_kill_qty_storno 586057 586057 0 0.00 0 43542 66 0 0
sp_lock_selected_doc 21433 21433 0 0.00 0 8691 7 0 0
sp_make_cost_storno 28819 28819 0 0.00 0 23949 62 0 0
sp_multiply_rows_for_pdistr 28819 28819 0 0.00 0 12015 31 0 0
sp_multiply_rows_for_qdistr 21341 21341 0 0.00 0 9414 148 0 0
sp_pay_from_customer 7250 7250 0 0.00 4 21455 132 0 0
sp_pay_to_supplier 5950 5950 0 0.00 4 20688 164 0 0
sp_payment_common 13199 13199 0 0.00 2 12598 36 0 0
sp_qd_handle_on_cancel_clo 1788 1788 0 0.00 0 13517 308 0 0
sp_qd_handle_on_invoice_upd_sts 10665 10665 0 0.00 0 16389 303 0 0
sp_qd_handle_on_reserve_upd_sts 10467 10467 0 0.00 0 25690 65 0 0
sp_reserve_write_off 10468 10468 0 0.00 6 25726 205 0 0
sp_supplier_invoice 5642 5642 0 0.00 5 63825 2992 0 0
sp_supplier_order 5692 5692 0 0.00 5 36617 1112 0 0
srv_aggregate_perf_data 107 107 0 0.00 2271 187257 17339 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1289 148222 45861 0 0
srv_find_qd_qs_mism 1066391 1066391 0 0.00 0 16169 5 0 0
srv_make_invnt_saldo 626 626 0 0.00 29 34731 994 0 0
srv_make_money_saldo 835 835 0 0.00 0 4649 28 0 0
srv_recalc_idx_stat 4 4 0 0.00 219501 392779 284665 0 0
t$perf-abend:sp_add_invoice_to_stock 86 86 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5157 5157 0 0.00 0 12032 160 0 0
t$perf-norm:sp_cancel_adding_invoice 400 400 0 0.00 0 8231 102 0 0
t$perf-norm:sp_cancel_client_order 1788 1788 0 0.00 0 2194 8 0 0
t$perf-norm:sp_cancel_customer_reserve 671 671 0 0.00 0 762 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 689 689 0 0.00 0 5066 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 447 447 0 0.00 0 1970 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 400 400 0 0.00 0 695 9 0 0
t$perf-norm:sp_cancel_supplier_order 409 409 0 0.00 0 19831 1421 0 0
t$perf-norm:sp_cancel_write_off 1011 1011 0 0.00 0 1365 7 0 0
t$perf-norm:sp_client_order 21341 21341 0 0.00 0 25138 16 0 0
t$perf-norm:sp_customer_reserve 1031 1031 0 0.00 0 7489 22 0 0
t$perf-norm:sp_pay_from_customer 7250 7250 0 0.00 0 14390 13 0 0
t$perf-norm:sp_pay_to_supplier 5950 5950 0 0.00 0 6902 11 0 0
t$perf-norm:sp_reserve_write_off 10468 10468 0 0.00 0 15798 12 0 0
t$perf-norm:sp_supplier_invoice 5642 5642 0 0.00 0 9355 16 0 0
t$perf-norm:sp_supplier_order 5692 5692 0 0.00 0 7298 14 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 263 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 1141 20 0 0
t$perf-norm:srv_make_invnt_saldo 626 626 0 0.00 0 2061 15 0 0
t$perf-norm:srv_make_money_saldo 835 835 0 0.00 0 2161 16 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 0 10 4 0 0
v_add_invoice_to_stock 10474 10302 172 1.64 0 21831 22 0 172
v_all_customers 38684 38684 0 0.00 0 30 0 0 0
v_all_suppliers 31384 31384 0 0.00 0 19 1 0 0
v_all_wares 42680 42680 0 0.00 0 101 1 0 0
v_cancel_adding_invoice 800 800 0 0.00 0 666 6 0 0
v_cancel_client_order 3576 3576 0 0.00 0 2532 7 0 0
v_cancel_customer_prepayment 1378 1378 0 0.00 0 2748 7 0 0
v_cancel_customer_reserve 1342 1342 0 0.00 0 1806 5 0 0
v_cancel_payment_to_supplier 894 894 0 0.00 0 2736 8 0 0
v_cancel_supplier_invoice 812 800 12 1.47 0 21786 50 0 12
v_cancel_supplier_order 818 818 0 0.00 0 2807 9 0 0
v_cancel_write_off 2022 2022 0 0.00 0 20385 25 0 0
v_max_id_clo_ord 5692 5692 0 0.00 0 3230 2 0 0
v_max_id_clo_res 1031 1031 0 0.00 0 1852 10 0 0
v_max_id_ord_sup 5638 5638 0 0.00 0 4648 4 0 0
v_max_non_paid_invoice 1588 1588 0 0.00 0 1308 5 0 0
v_max_non_paid_realizn 3859 3859 0 0.00 0 3398 4 0 0
v_min_id_clo_ord 5692 5692 0 0.00 0 6945 8 0 0
v_min_id_clo_res 1031 1031 0 0.00 0 1949 4 0 0
v_min_id_ord_sup 5638 5638 0 0.00 0 8075 10 0 0
v_min_non_paid_invoice 5950 5950 0 0.00 0 11939 35 0 0
v_min_non_paid_realizn 7249 7249 0 0.00 0 12595 24 0 0
v_our_firm 10776 10776 0 0.00 0 14 1 0 0
v_reserve_write_off 20934 20934 0 0.00 0 20862 12 0 0
x_lock_depdocs_on_canc_invoice 11236 11236 0 0.00 0 9577 107 0 0
x_lock_depdocs_on_canc_sup_ord 409 409 0 0.00 0 8979 330 0 0
x_make_qstorno_1000_1200 5692 5692 0 0.00 1 36604 1037 0 0
x_make_qstorno_1200_2000 5642 5642 0 0.00 2 62330 2853 0 0
x_make_qstorno_2100_3300 481538 481538 0 0.00 0 44154 164 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 10-08-2022 19:40:05 to 10-08-2022 19:40:09.

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
17:08:32 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:32 21768 8192000000 9889493808 10196746240 971238736 10.599 100 88 174 1 1000819024 5879456 23700832
17:10:58 23502 8192000000 9944933648 10263994368 1027051712 11.140 100 89 178 0 1058916080 8573200 23291168
17:12:25 19435 8192000000 9976938000 10306224128 1058879984 11.446 100 90 180 0 1091258256 9687600 22690672
17:13:56 9577 8192000000 10033586416 10377326592 1111992176 11.951 100 81 162 0 1142445600 11280752 19172672
17:15:09 4536 8192000000 10078374656 10434646016 1165400848 12.454 100 76 152 0 1194861888 12037616 17423424
17:16:19 2225 8192000000 10111097200 10480279552 1203561344 12.809 100 80 160 0 1235308112 13353312 18393456
17:17:23 2410 8192000000 10152992640 10528448512 1242849232 13.172 100 88 174 1 1274679824 12626176 19204416
17:18:27 2233 8192000000 10179332048 10559328256 1269878784 13.421 100 84 162 2 1299060096 11415728 17765584
17:19:31 1839 8192000000 10201309952 10587086848 1288703104 13.592 100 85 170 1 1320692688 13094176 18895408
17:20:34 16463 8192000000 10194883584 10593591296 1295563904 13.655 100 71 142 0 1319899216 7098896 17236416
17:21:51 6618 8192000000 10190564032 10603552768 1305949008 13.749 100 20 38 0 1317614400 6949328 4716064
17:23:01 10814 8192000000 10200484544 10616274944 1316250880 13.843 100 18 36 0 1326616192 6509584 3855728
17:24:15 7879 8192000000 10208052336 10628366336 1326163536 13.932 100 16 31 0 1334658784 4908352 3586896
17:25:24 58026 8192000000 10242070864 10649792512 1333026944 13.994 100 55 110 0 1353829072 7466528 13335600
17:27:23 5518 8192000000 10243064976 10656387072 1337525568 14.035 100 31 62 0 1350639728 5779808 7334352
17:28:31 5249 8192000000 10228112992 10639880192 1306553360 13.755 98 31 62 1 1319599072 5414144 7631568
17:29:36 62589 8192000000 10153661072 10569490432 1224694240 13.005 93 41 83 0 1242785312 8785744 9305328
17:31:43 9291 8192000000 10146628064 10543456256 1216364896 12.928 96 13 27 0 1224119632 4927312 2827424
17:32:55 5440 8192000000 10139215712 10543910912 1245328992 13.195 99 29 60 0 1258248624 6130816 6788816
17:34:03 48703 8192000000 10171676016 10576195584 1276147120 13.478 100 35 68 0 1291252384 6732832 8372432
17:36:01 4646 8192000000 10174413120 10589089792 1288504768 13.591 100 27 54 0 1301214064 7013104 5696192
17:37:06 9137 8192000000 10192791920 10605355008 1300506080 13.700 100 25 50 0 1311249568 5254992 5488496
17:38:20 10799 8192000000 10213575504 10628898816 1322475456 13.899 100 15 30 1 1330750960 5251024 3024480
17:39:37 42993 8192000000 10253245376 10669056000 1346213120 14.113 100 46 92 0 1364435920 6710144 11512656
17:41:29 16957 8192000000 10261445648 10684506112 1358140800 14.221 100 24 48 0 1373828464 9484032 6203632
17:42:50 23038 8192000000 10278138224 10705846272 1366905904 14.299 100 35 70 0 1394006624 19256912 7843808
17:44:28 6892 8192000000 10292373504 10716323840 1383461776 14.447 100 47 91 3 1406480352 12503808 10514768
17:45:43 38347 8192000000 10311365472 10741108736 1393560096 14.538 100 38 76 0 1421128480 17908192 9660192
17:47:43 3965 8192000000 10330280992 10757632000 1404551424 14.636 100 59 119 0 1443225472 24656848 14017200
17:48:52 6640 8192000000 10334717648 10758254592 1407104112 14.658 100 53 105 0 1441471856 22252592 12115152
17:50:05 9263 8192000000 10349563008 10772688896 1412611008 14.707 100 60 118 1 1456164320 29436176 14117136
17:51:26 13058 8192000000 10322160272 10753421312 1382596880 14.440 98 45 90 0 1417780608 24237584 10946144
17:52:58 5343 8192000000 10350111216 10776989696 1418244240 14.757 100 60 120 0 1462158512 29074288 14839984
17:54:07 4386 8192000000 10375992496 10802544640 1430938672 14.870 100 51 102 0 1481384160 37163328 13282160
17:55:31 3920 8192000000 10357476736 10775519232 1406338256 14.651 100 61 120 1 1460589232 39021984 15228992
17:56:48 1289 8192000000 10359840112 10773700608 1384325216 14.455 99 74 147 1 1448259872 45119712 18814944
17:57:54 3955 8192000000 10338813136 10760654848 1378126784 14.400 99 59 118 1 1442546816 48900768 15519264
17:59:02 6245 8192000000 10351529424 10768019456 1398478320 14.581 100 60 120 0 1459349664 45137760 15733584
18:00:15 2566 8192000000 10387058832 10796359680 1408641088 14.672 100 81 162 0 1485990464 56297488 21051888
18:01:37 19427 8192000000 10346629040 10780901376 1373728384 14.360 98 35 69 0 1444560816 60956192 9876240
18:02:59 15524 8192000000 10320199504 10749374464 1371725232 14.343 100 29 58 0 1429539600 49860448 7953920
18:04:20 8503 8192000000 10331874832 10765975552 1368327680 14.312 100 35 71 1 1437169152 58636480 10204992
18:05:30 31642 8192000000 10266705744 10715025408 1303341408 13.726 97 16 32 0 1350796240 42551984 4902848
18:07:08 66344 8192000000 10242174576 10667065344 1244124304 13.184 93 16 30 1 1264582288 16887904 3570080
18:09:23 33373 8192000000 10270603344 10698354688 1347348480 14.124 100 26 51 1 1370188240 17328576 5511184
18:11:12 34813 8192000000 10261234960 10704650240 1346634240 14.117 99 19 37 0 1367063024 16464640 3964144
18:12:53 84124 8192000000 10276791232 10722459648 1334157760 14.005 97 9 18 0 1349657152 13703088 1796304
18:15:27 56140 8192000000 10288398816 10735271936 1370578960 14.332 100 35 69 0 1390075632 11135984 8360688
18:17:24 40042 8192000000 10298018096 10738630656 1380138000 14.418 100 37 74 0 1398798048 9829936 8830112
18:19:12 24641 8192000000 10314216896 10752466944 1387113920 14.480 100 37 72 0 1413784800 18285216 8385664
18:20:47 39458 8192000000 10305957488 10752917504 1388883056 14.496 100 29 57 0 1418071024 22474048 6713920
18:22:27 49171 8192000000 10291265408 10753474560 1382639232 14.440 100 15 30 0 1401196320 15144800 3412288
18:24:24 39916 8192000000 10294408848 10753028096 1379001936 14.408 99 9 19 0 1393378608 12406608 1970064
18:26:04 37481 8192000000 10293336208 10755473408 1394684912 14.548 100 4 8 0 1407394192 11853056 856224
18:29:37 39038 8192000000 10309767248 10773159936 1406784608 14.655 100 20 40 2 1420500912 8836992 4879312
18:31:24 40916 8192000000 10318403232 10786840576 1416471184 14.741 100 30 59 0 1432216272 9474720 6270368
18:33:17 78723 8192000000 10321085344 10789117952 1387511296 14.484 98 14 28 0 1399634992 9257808 2865888
18:35:40 57107 8192000000 10326820048 10776391680 1374901280 14.371 97 14 28 0 1385761664 7915552 2944832
18:37:39 54506 8192000000 10294480784 10743033856 1343819248 14.092 96 20 40 0 1359652192 11854128 3978816
18:39:35 81847 8192000000 10290397696 10755158016 1333853024 14.002 95 6 12 0 1348635824 13737280 1045520
18:42:00 111970 8192000000 10277634272 10735923200 1379579584 14.413 100 36 72 1 1397106992 9348544 8178864
18:44:58 46258 8192000000 10276642064 10721890304 1350650640 14.153 99 51 101 0 1376943840 15348192 10945008
18:46:54 53102 8192000000 10249041328 10712121344 1326653088 13.937 97 8 16 0 1339063712 10649088 1761536
18:48:52 75102 8192000000 10242182640 10698391552 1294422864 13.645 95 16 32 0 1310677632 11982560 4272208
18:51:12 57616 8192000000 10238691904 10704678912 1304321168 13.735 97 11 22 0 1324475712 17414816 2739728
18:53:20 95988 8192000000 10247879664 10698584064 1282440736 13.535 95 22 44 1 1299714000 11810640 5462624
18:56:07 76442 8192000000 10251789920 10707632128 1308064288 13.769 97 20 38 0 1325509584 13234352 4210944
18:58:24 103703 8192000000 10263662160 10703273984 1285729072 13.565 95 19 38 0 1299776800 10248400 3799328
19:01:15 15658 8192000000 10258794992 10693218304 1332497920 13.990 99 35 69 0 1348984032 9110496 7375616
19:02:31 40392 8192000000 10253437632 10697785344 1342207680 14.077 100 21 41 0 1357262112 10651328 4403104
19:04:12 21453 8192000000 10290872512 10720894976 1349116400 14.140 100 46 92 0 1382074800 22848624 10109776
19:05:40 76644 8192000000 10269499616 10715152384 1356383888 14.205 100 29 58 0 1373632480 10244624 7003968
19:08:25 45397 8192000000 10257676080 10715131904 1362563728 14.260 100 5 10 0 1371750672 8265440 921504
19:10:13 51264 8192000000 10261526976 10725347328 1369258320 14.320 100 18 37 1 1382590592 9202432 4129840
19:12:18 58984 8192000000 10285646448 10742210560 1371341840 14.339 99 13 27 0 1382186368 7960928 2883600
19:14:21 33885 8192000000 10294817536 10754670592 1395417216 14.554 100 29 58 0 1410095744 8407232 6271296
19:15:56 132144 8192000000 10313432352 10784628736 1389635232 14.503 99 26 52 0 1406597968 11136224 5826512
19:19:08 40872 8192000000 10313016896 10762027008 1383344464 14.446 99 22 44 0 1396359472 8057904 4957104
19:20:56 74143 8192000000 10292882464 10747744256 1339979248 14.057 96 24 48 0 1357346128 12104016 5262864
19:23:11 148221 8192000000 10289280864 10748108800 1304168784 13.733 94 7 14 0 1316084160 10648592 1266784
19:26:39 100995 8192000000 10267274560 10716291072 1324632992 13.919 96 18 36 0 1336927152 7158528 5135632
19:29:24 73765 8192000000 10295957424 10728456192 1368052128 14.310 100 52 104 1 1387181696 6957088 12172480
19:31:39 74423 8192000000 10257283728 10721554432 1344574720 14.099 98 11 22 0 1356046912 8827984 2644208
19:34:25 75556 8192000000 10262107264 10720194560 1332011360 13.985 97 19 38 0 1344294976 8116832 4166784
19:36:57 72789 8192000000 10263772752 10725974016 1302201984 13.715 95 7 14 0 1321739584 17856544 1681056

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 10-08-2022 19:40:11 to 10-08-2022 19:40:25.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 86 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 10-08-2022 19:40:26 to 10-08-2022 19:40:28.

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          241567
MON$OLDEST_ACTIVE               243148
MON$OLDEST_SNAPSHOT             243148
MON$NEXT_TRANSACTION            243148
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                       2027296
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.33616 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33616 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33616 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33616 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 ---
:39: 
:40: mscs-test-161.budzdorov.ru    Wed Aug 10 19:38:33 2022
:41:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:42: 
:43: 
:44: mscs-test-161.budzdorov.ru    Wed Aug 10 19:38:34 2022
:45:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:46: 
:47: 
:48: mscs-test-161.budzdorov.ru    Wed Aug 10 19:38:36 2022
:49:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:50: 
--- end of diff output ---

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

10.08.22 19:40:29. 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-08-10 19:40:32 No severe PSQL-related problems occured
10.08.22 19:40:33. End of report.