Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 12.12.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 14815644 kB
MemAvailable 30070560 kB
Buffers 0 kB
Cached 16873076 kB
SwapCached 3300 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-12-12 22:38:33
DTS_END                         2022-12-12 22:38:33.9150
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/52882
TRN_ID                          248631
ATT_ID                          715
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: 9657.62 20697 1158914
customer order: creation 181.41 360 21769
customer order: refuse 15.80 328 1896
order to supplier: creation 48.74 1167 5849
order to supplier: removal 3.63 88238 436
invoice (draft): creation 48.41 3035 5809
invoice (draft): removal 54.92 743 6590
invoice accept: apply 43.50 17145 5220
invoice accept: cancel 49.27 766 5912
customer reserve: creation 4049.17 168 485900
customer reserve: removal 4849.12 52 581894
realization accept: apply 89.63 171 10755
realization accept: cancel 87.75 96 10530
payment from customer: creation 62.38 115 7485
payment from customer: removal 5.68 85 682
payment to supplier: creation 52.13 112 6256
payment to supplier: removal 3.37 81 404
service: total inventory turnovers 4.91 1082 589
service: total monetary turnovers 6.86 93 823
service: aggregate perf. data 0.93 18339 111
service: refresh index statistics 0.03 281767 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 12-12-2022 22:39:39 to 12-12-2022 22:39:41.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1516
TEST_TIME 2 4529
TEST_TIME 3 5039
TEST_TIME 4 5986
TEST_TIME 5 5649
TEST_TIME 6 5776
TEST_TIME 7 5519
TEST_TIME 8 5511
TEST_TIME 9 5518
TEST_TIME 10 6131
TEST_TIME 11 6181
TEST_TIME 12 6449
TEST_TIME 13 6776
TEST_TIME 14 6723
TEST_TIME 15 6836
TEST_TIME 16 7372
TEST_TIME 17 7529
TEST_TIME 18 8080
TEST_TIME 19 8280
TEST_TIME 20 8574
TEST_TIME 21 8750
TEST_TIME 22 8867
TEST_TIME 23 8773
TEST_TIME 24 9103
TEST_TIME 25 9618
TEST_TIME 26 9830
TEST_TIME 27 9810
TEST_TIME 28 9940
TEST_TIME 29 9826
TEST_TIME 30 10726
TEST_TIME 31 10881
TEST_TIME 32 10713
TEST_TIME 33 10731
TEST_TIME 34 10669
TEST_TIME 35 10499
TEST_TIME 36 10576
TEST_TIME 37 10866
TEST_TIME 38 10873
TEST_TIME 39 10799
TEST_TIME 40 10708
TEST_TIME 41 10541
TEST_TIME 42 10439
TEST_TIME 43 10252
TEST_TIME 44 10249
TEST_TIME 45 10223
TEST_TIME 46 10187
TEST_TIME 47 10113
TEST_TIME 48 10094
TEST_TIME 49 10041
TEST_TIME 50 10013
TEST_TIME 51 10109
TEST_TIME 52 10027
TEST_TIME 53 9964
TEST_TIME 54 9895
TEST_TIME 55 9872
TEST_TIME 56 9819
TEST_TIME 57 9737
TEST_TIME 58 9860
TEST_TIME 59 9778
TEST_TIME 60 9796
TEST_TIME 61 9781
TEST_TIME 62 9860
TEST_TIME 63 9827
TEST_TIME 64 9862
TEST_TIME 65 9934
TEST_TIME 66 9930
TEST_TIME 67 9982
TEST_TIME 68 10011
TEST_TIME 69 10111
TEST_TIME 70 10069
TEST_TIME 71 10177
TEST_TIME 72 10158
TEST_TIME 73 10080
TEST_TIME 74 9966
TEST_TIME 75 9934
TEST_TIME 76 9912
TEST_TIME 77 9859
TEST_TIME 78 9870
TEST_TIME 79 9857
TEST_TIME 80 9837
TEST_TIME 81 9821
TEST_TIME 82 9837
TEST_TIME 83 9787
TEST_TIME 84 9721
TEST_TIME 85 9681
TEST_TIME 86 9618
TEST_TIME 87 9677
TEST_TIME 88 9665
TEST_TIME 89 9664
TEST_TIME 90 9688
TEST_TIME 91 9708
TEST_TIME 92 9738
TEST_TIME 93 9735
TEST_TIME 94 9733
TEST_TIME 95 9792
TEST_TIME 96 9869
TEST_TIME 97 9846
TEST_TIME 98 9934
TEST_TIME 99 9931
TEST_TIME 100 9959
TEST_TIME 101 9971
TEST_TIME 102 9937
TEST_TIME 103 9879
TEST_TIME 104 9883
TEST_TIME 105 9832
TEST_TIME 106 9852
TEST_TIME 107 9815
TEST_TIME 108 9797
TEST_TIME 109 9774
TEST_TIME 110 9781
TEST_TIME 111 9735
TEST_TIME 112 9675
TEST_TIME 113 9665
TEST_TIME 114 9688
TEST_TIME 115 9659
TEST_TIME 116 9648
TEST_TIME 117 9653
TEST_TIME 118 9664
TEST_TIME 119 9698
TEST_TIME 120 9659

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 12-12-2022 22:39:42 to 12-12-2022 22:39:46.

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 1609064 1609064 0 0.00 0 41123 6 0 0
doc_list_biud 1609064 1609064 0 0.00 0 41628 24 0 0
sp_add_invoice_to_stock 5360 5220 140 2.61 32 209403 17145 0 140
sp_cancel_adding_invoice 5912 5912 0 0.00 3 66430 766 0 0
sp_cancel_client_order 1896 1896 0 0.00 4 7189 328 0 0
sp_cancel_customer_reserve 581894 581894 0 0.00 0 21209 52 0 0
sp_cancel_pay_from_customer 682 682 0 0.00 2 6174 86 0 0
sp_cancel_pay_to_supplier 404 404 0 0.00 3 3617 81 0 0
sp_cancel_supplier_invoice 6608 6590 18 0.27 1 41688 744 0 18
sp_cancel_supplier_order 436 436 0 0.00 8 977155 88239 0 0
sp_cancel_write_off 10530 10530 0 0.00 1 21758 97 0 0
sp_client_order 21769 21769 0 0.00 3 28476 360 0 0
sp_customer_reserve 485900 485900 0 0.00 0 176319 169 0 0
sp_fill_shopping_cart 33420 33420 0 0.00 1 8559 32 0 0
sp_fill_shopping_cart_clo_res 485893 485893 0 0.00 0 18549 12 0 0
sp_get_clo_for_invoice 5210 5210 0 0.00 0 34297 273 0 0
sp_kill_cost_storno 17528 17528 0 0.00 0 8072 50 0 0
sp_kill_qstorno_ret_qs2qd 1181344 1181344 0 0.00 0 41626 28 0 0
sp_kill_qty_storno 623221 623221 0 0.00 0 41628 62 0 0
sp_lock_selected_doc 21989 21989 0 0.00 0 8235 7 0 0
sp_make_cost_storno 29704 29704 0 0.00 0 41120 48 0 0
sp_multiply_rows_for_pdistr 29704 29704 0 0.00 0 7011 24 0 0
sp_multiply_rows_for_qdistr 21769 21769 0 0.00 0 18520 167 0 0
sp_pay_from_customer 7485 7485 0 0.00 4 41205 116 0 0
sp_pay_to_supplier 6256 6256 0 0.00 4 15517 113 0 0
sp_payment_common 13741 13741 0 0.00 1 40934 25 0 0
sp_qd_handle_on_cancel_clo 1896 1896 0 0.00 0 7182 306 0 0
sp_qd_handle_on_invoice_upd_sts 11120 11120 0 0.00 0 35421 317 0 0
sp_qd_handle_on_reserve_upd_sts 10755 10755 0 0.00 0 22002 66 0 0
sp_reserve_write_off 10755 10755 0 0.00 6 22171 171 0 0
sp_supplier_invoice 5809 5809 0 0.00 5 78826 3035 0 0
sp_supplier_order 5849 5849 0 0.00 5 35842 1168 0 0
srv_aggregate_perf_data 111 111 0 0.00 2612 160688 18339 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1017 121601 43258 0 0
srv_find_qd_qs_mism 1108734 1108734 0 0.00 0 16039 5 0 0
srv_make_invnt_saldo 589 589 0 0.00 38 102233 1083 0 0
srv_make_money_saldo 823 823 0 0.00 0 43606 94 0 0
srv_recalc_idx_stat 4 4 0 0.00 199478 438712 281767 0 0
t$perf-abend:sp_add_invoice_to_stock 140 140 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 18 18 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5220 5220 0 0.00 0 17257 215 0 0
t$perf-norm:sp_cancel_adding_invoice 444 444 0 0.00 0 13703 161 0 0
t$perf-norm:sp_cancel_client_order 1896 1896 0 0.00 0 14944 25 0 0
t$perf-norm:sp_cancel_customer_reserve 710 710 0 0.00 0 13929 36 0 0
t$perf-norm:sp_cancel_pay_from_customer 682 682 0 0.00 0 2065 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 404 404 0 0.00 0 4056 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 425 425 0 0.00 0 590 14 0 0
t$perf-norm:sp_cancel_supplier_order 436 436 0 0.00 0 21632 1472 0 0
t$perf-norm:sp_cancel_write_off 1029 1029 0 0.00 0 2774 10 0 0
t$perf-norm:sp_client_order 21769 21769 0 0.00 0 21907 20 0 0
t$perf-norm:sp_customer_reserve 1067 1067 0 0.00 0 946 4 0 0
t$perf-norm:sp_pay_from_customer 7485 7485 0 0.00 0 6439 11 0 0
t$perf-norm:sp_pay_to_supplier 6256 6256 0 0.00 0 8869 15 0 0
t$perf-norm:sp_reserve_write_off 10755 10755 0 0.00 0 11122 12 0 0
t$perf-norm:sp_supplier_invoice 5809 5809 0 0.00 0 7107 19 0 0
t$perf-norm:sp_supplier_order 5849 5849 0 0.00 0 10990 18 0 0
t$perf-norm:srv_aggregate_perf_data 111 111 0 0.00 0 270 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 172 6 0 0
t$perf-norm:srv_make_invnt_saldo 589 589 0 0.00 0 2293 20 0 0
t$perf-norm:srv_make_money_saldo 823 823 0 0.00 0 1806 16 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 10696 10416 280 2.61 0 11725 20 0 280
v_all_customers 39916 39916 0 0.00 0 153 1 0 0
v_all_suppliers 32438 32438 0 0.00 0 26 1 0 0
v_all_wares 43538 43538 0 0.00 0 24 1 0 0
v_cancel_adding_invoice 888 888 0 0.00 0 126 6 0 0
v_cancel_client_order 3792 3792 0 0.00 0 4480 6 0 0
v_cancel_customer_prepayment 1364 1364 0 0.00 0 1630 8 0 0
v_cancel_customer_reserve 1420 1420 0 0.00 0 1932 9 0 0
v_cancel_payment_to_supplier 808 808 0 0.00 0 2117 9 0 0
v_cancel_supplier_invoice 886 850 36 4.06 0 7812 29 0 36
v_cancel_supplier_order 872 872 0 0.00 0 2754 7 0 0
v_cancel_write_off 2058 2058 0 0.00 0 1247 14 0 0
v_max_id_clo_ord 5849 5849 0 0.00 0 3553 2 0 0
v_max_id_clo_res 1067 1067 0 0.00 0 2211 17 0 0
v_max_id_ord_sup 5802 5802 0 0.00 0 1916 3 0 0
v_max_non_paid_invoice 1688 1688 0 0.00 0 828 2 0 0
v_max_non_paid_realizn 3818 3818 0 0.00 0 1544 4 0 0
v_min_id_clo_ord 5849 5849 0 0.00 0 4537 8 0 0
v_min_id_clo_res 1067 1067 0 0.00 0 3733 11 0 0
v_min_id_ord_sup 5802 5802 0 0.00 0 6021 5 0 0
v_min_non_paid_invoice 6256 6256 0 0.00 0 5249 15 0 0
v_min_non_paid_realizn 7485 7485 0 0.00 0 40924 18 0 0
v_our_firm 10956 10956 0 0.00 0 19 1 0 0
v_reserve_write_off 21510 21510 0 0.00 0 10268 6 0 0
x_lock_depdocs_on_canc_invoice 12077 12077 0 0.00 0 11158 99 0 0
x_lock_depdocs_on_canc_sup_ord 436 436 0 0.00 0 5631 255 0 0
x_make_qstorno_1000_1200 5849 5849 0 0.00 1 35834 1099 0 0
x_make_qstorno_1200_2000 5809 5809 0 0.00 2 78820 2918 0 0
x_make_qstorno_2100_3300 485900 485900 0 0.00 0 176317 154 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 12-12-2022 22:39:47 to 12-12-2022 22:39:51.

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:37 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:38 25023 8192000000 9898864960 10206158848 981470816 10.699 100 87 173 0 1010045152 6088816 22485520
20:11:06 14299 8192000000 9938449936 10257506304 1020216336 11.074 100 89 179 0 1051651104 7851184 23583584
20:12:30 10584 8192000000 9969633472 10296942592 1051694896 11.377 100 90 180 0 1084034256 9165728 23173632
20:13:43 11486 8192000000 10009930800 10349416448 1092941216 11.771 100 75 151 0 1120967856 9647792 18378848
20:14:57 9927 8192000000 10087145776 10444677120 1166508096 12.464 100 76 152 0 1195084560 11257904 17318560
20:16:09 2918 8192000000 10127737728 10497249280 1218553792 12.948 100 77 154 0 1247825008 12450384 16820832
20:17:15 3741 8192000000 10150772064 10525622272 1240739008 13.153 100 84 168 0 1272189152 12768112 18682032
20:18:20 1915 8192000000 10189949376 10569547776 1275290832 13.470 100 91 183 1 1309618016 13790576 20536608
20:19:23 1376 8192000000 10198129712 10580086784 1287690944 13.583 100 84 168 2 1319186240 12757328 18737968
20:20:26 1110 8192000000 10196577920 10590744576 1293212976 13.633 100 68 135 0 1318177744 8308720 16656048
20:21:31 2578 8192000000 10207015632 10605232128 1302566640 13.719 100 59 119 0 1327786912 11904144 13316128
20:22:34 5203 8192000000 10192314800 10603913216 1308151184 13.769 100 32 64 1 1321825296 5494176 8179936
20:23:46 10322 8192000000 10199456608 10612523008 1312094112 13.805 100 16 32 2 1320341632 5142880 3104640
20:24:57 39582 8192000000 10209384576 10628210688 1320869136 13.885 100 23 47 1 1331206160 5195696 5141328
20:26:38 4262 8192000000 10219338080 10635694080 1325068896 13.923 100 31 62 0 1339431360 7384304 6978160
20:27:44 7000 8192000000 10204483680 10624126976 1318491248 13.863 100 18 36 1 1328305312 5642784 4171280
20:28:52 69468 8192000000 10123132320 10517643264 1165489024 12.455 89 25 50 1 1175825936 4631824 5705088
20:31:02 8251 8192000000 10157391888 10557820928 1233804704 13.089 97 37 75 0 1248612704 6131840 8676160
20:32:12 10041 8192000000 10128409664 10522591232 1191369760 12.696 95 28 56 1 1203103040 5074496 6658784
20:33:23 40504 8192000000 10136470352 10542272512 1242828960 13.172 99 30 57 1 1254572256 5027968 6715328
20:35:04 4970 8192000000 10175086512 10580074496 1281587216 13.528 100 33 62 1 1294810240 6209072 7013952
20:36:10 6264 8192000000 10187497616 10598641664 1295165968 13.651 100 21 42 0 1305397824 5424928 4806928
20:37:18 6809 8192000000 10193654736 10611417088 1306907056 13.758 100 26 52 0 1318784960 5195744 6682160
20:38:32 64025 8192000000 10233155472 10643165184 1321611600 13.891 100 33 66 0 1334621184 5485280 7524304
20:40:41 9909 8192000000 10254607152 10670075904 1352498432 14.170 100 47 91 0 1370972720 8806832 9667456
20:41:55 21733 8192000000 10283087696 10700791808 1370212208 14.329 100 49 97 1 1394990704 13714736 11063760
20:43:19 28409 8192000000 10296893648 10719580160 1385554768 14.466 100 47 94 0 1412641072 16132496 10953808
20:44:57 13448 8192000000 10304287024 10731708416 1395247488 14.553 100 35 68 1 1419409616 15840864 8321264
20:46:15 4684 8192000000 10319537008 10744373248 1399962448 14.595 100 59 117 1 1432784720 19221600 13600672
20:47:25 14568 8192000000 10321156304 10748379136 1394578720 14.547 99 56 110 0 1427191280 19400832 13211728
20:48:52 11930 8192000000 10332271584 10750754816 1392182896 14.525 99 62 124 0 1432058192 24906880 14968416
20:50:10 1016 8192000000 10339921680 10753323008 1397199296 14.570 99 78 156 1 1444907728 28877728 18830704
20:51:16 6599 8192000000 10354567584 10775871488 1408974528 14.675 99 62 124 0 1454832752 30536640 15321584
20:52:26 5464 8192000000 10356157408 10779488256 1409827648 14.682 99 54 108 0 1456978016 33468752 13681616
20:53:36 1011 8192000000 10373373104 10790793216 1422030496 14.791 100 89 176 1 1483036032 38482192 22523344
20:54:41 2388 8192000000 10340669168 10748739584 1387068448 14.480 99 81 161 1 1443289536 36660192 19560896
20:55:47 1787 8192000000 10371949120 10784555008 1409740608 14.682 100 83 166 0 1472023328 41657632 20625088
20:56:52 2306 8192000000 10335906400 10752155648 1386670192 14.476 99 65 126 0 1434768640 32358688 15739760
20:58:02 2252 8192000000 10342188720 10757976064 1387264272 14.481 99 71 138 0 1441645936 37153856 17227808
20:59:08 17933 8192000000 10322455920 10749607936 1359921888 14.237 98 32 63 1 1405906352 37177936 8806528
21:00:28 8446 8192000000 10336960176 10763919360 1393548128 14.538 100 44 90 0 1446346336 40905568 11892640
21:01:39 12278 8192000000 10326659520 10751672320 1376781920 14.388 100 43 84 1 1428508064 40848064 10878080
21:02:57 11268 8192000000 10324412256 10747928576 1358963936 14.228 99 38 71 1 1417836704 48779424 10093344
21:04:10 53100 8192000000 10313655488 10754252800 1345612832 14.108 97 24 46 0 1396222704 44436368 6173504
21:06:09 25004 8192000000 10313379424 10757214208 1369911152 14.326 100 15 30 0 1424612336 50390064 4311120
21:07:36 20926 8192000000 10284390464 10719969280 1323991504 13.913 98 30 60 0 1373497600 41791312 7714784
21:08:58 31461 8192000000 10275392352 10721353728 1357477744 14.215 100 9 18 0 1372774192 13589792 1706656
21:10:31 44408 8192000000 10281358096 10715619328 1348059056 14.130 99 21 41 0 1363667984 11239056 4369872
21:12:39 53870 8192000000 10304907280 10750525440 1383065200 14.444 100 20 38 2 1405396208 17856864 4474144
21:14:43 64918 8192000000 10321079136 10789285888 1397649760 14.574 100 9 19 0 1426929392 27193488 2086144
21:17:36 54974 8192000000 10313560592 10761441280 1383316544 14.446 99 15 30 0 1394971920 8442816 3212560
21:19:36 27324 8192000000 10309510912 10745753600 1367499648 14.305 98 51 97 2 1387069968 8505680 11064640
21:21:06 34931 8192000000 10304037792 10743926784 1371337344 14.339 99 57 110 0 1391092784 6734128 13021312
21:22:47 81403 8192000000 10312786720 10766004224 1385798976 14.468 99 25 51 2 1404538048 13094576 5644496
21:25:09 121601 8192000000 10319286048 10778390528 1387894688 14.487 99 21 41 0 1401128112 8571648 4661776
21:28:12 61492 8192000000 10329112240 10796273664 1418336976 14.758 100 34 69 0 1441920624 15256448 8327200
21:30:15 84892 8192000000 10299071552 10760564736 1372208736 14.347 97 16 33 1 1383067312 6957616 3900960
21:32:41 54913 8192000000 10291881728 10749632512 1341461264 14.071 96 25 50 0 1360236400 12992208 5782928
21:34:44 57740 8192000000 10295759712 10758623232 1381845952 14.433 100 32 61 2 1401531008 12572480 7112576
21:36:45 68199 8192000000 10271199488 10738950144 1348797440 14.137 98 12 24 1 1367807248 16558624 2451184
21:39:06 58302 8192000000 10297022800 10760241152 1361967600 14.255 98 10 20 0 1375288400 10880576 2440224
21:41:06 22829 8192000000 10269220720 10721869824 1314485888 13.827 94 24 46 0 1327736992 8307424 4943680
21:42:33 61784 8192000000 10281205552 10747854848 1341360512 14.070 97 23 46 0 1361856272 14942064 5553696
21:45:15 35409 8192000000 10274252816 10726141952 1319338624 13.871 96 26 52 0 1343099520 18017376 5743520
21:46:58 99899 8192000000 10282541552 10728660992 1322069376 13.895 96 19 38 0 1340161072 13842768 4248928
21:49:38 69322 8192000000 10267820176 10720845824 1299797952 13.693 95 7 14 0 1314346208 13068512 1479744
21:51:48 98007 8192000000 10266693728 10705670144 1351403808 14.160 100 29 55 3 1367614032 9902896 6307328
21:54:27 45231 8192000000 10275773184 10718175232 1358386192 14.223 100 57 114 2 1383549984 11754080 13409712
21:56:22 69452 8192000000 10267227792 10737332224 1347714256 14.127 98 14 28 1 1364928400 13963120 3251024
21:58:43 51143 8192000000 10273732016 10723586048 1348829216 14.137 98 30 60 0 1363717536 8325024 6563296
22:00:34 50660 8192000000 10238246416 10698649600 1318481312 13.863 97 6 13 0 1326586240 6818768 1286160
22:03:00 52212 8192000000 10260153344 10724012032 1358288928 14.222 100 12 24 1 1376326096 15922144 2115024
22:05:20 50711 8192000000 10262717376 10737319936 1362355728 14.259 100 16 32 1 1382264800 15690496 4218576
22:07:10 57559 8192000000 10273499008 10730336256 1346430480 14.115 98 35 70 0 1362803648 8815424 7557744
22:09:12 57111 8192000000 10281901712 10742714368 1375744144 14.378 100 13 26 0 1390166704 11985344 2437216
22:11:10 48939 8192000000 10299238400 10753224704 1363667808 14.270 98 15 30 0 1378495840 11547440 3280592
22:13:02 74393 8192000000 10292920400 10766065664 1351425568 14.160 97 17 34 0 1375148400 19845472 3877360
22:15:19 43224 8192000000 10318487232 10760781824 1372733392 14.352 98 38 77 0 1389451152 8790240 7927520
22:17:07 75209 8192000000 10288250144 10745851904 1344003072 14.093 96 19 38 0 1357674704 10063472 3608160
22:19:24 49283 8192000000 10309694416 10763976704 1368192832 14.311 99 12 24 1 1380168400 9565552 2410016
22:21:18 86111 8192000000 10276613168 10738196480 1345467984 14.107 97 17 34 0 1363055808 13681856 3905968
22:23:46 32801 8192000000 10298393216 10748538880 1386094960 14.471 100 39 78 0 1406327280 11628448 8603872
22:25:19 66661 8192000000 10270289552 10728636416 1306583392 13.755 94 9 18 1 1316584992 7970800 2030800
22:27:27 66718 8192000000 10256590128 10720137216 1317269424 13.852 96 18 34 0 1331922624 10555776 4097424
22:29:36 52288 8192000000 10263010224 10721423360 1344810080 14.101 98 11 22 0 1355672720 8435392 2427248
22:31:57 56836 8192000000 10251936480 10728329216 1336874128 14.029 98 8 16 1 1350905600 12160576 1870896
22:33:54 34644 8192000000 10272204768 10724261888 1351831888 14.164 99 42 85 0 1371367504 9152784 10382832
22:35:31 61631 8192000000 10289012096 10742284288 1350603712 14.153 98 10 20 0 1368824352 15751248 2469392
22:37:44 43985 8192000000 10276064320 10716364800 1326745744 13.938 97 27 54 0 1341527552 8832640 5949168

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 12-12-2022 22:39:53 to 12-12-2022 22:40:08.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 140 v_add_invoice_to_stock 335544517
except 18 v_cancel_supplier_invoice 335544517

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

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          247596
MON$OLDEST_ACTIVE               248810
MON$OLDEST_SNAPSHOT             248810
MON$NEXT_TRANSACTION            248810
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                       2052768
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.33647 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33647 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33647 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33647 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 ---
:188: 
:189: mscs-test-161.budzdorov.ru    Mon Dec 12 22:38:38 2022
:190:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:191: 
:192: 
:193: mscs-test-161.budzdorov.ru    Mon Dec 12 22:38:40 2022
:194:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:195: 
:196: 
:197: mscs-test-161.budzdorov.ru    Mon Dec 12 22:38:41 2022
:198:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:199: 
--- end of diff output ---

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

12.12.22 22:40:12. 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-12-12 22:40:15 No severe PSQL-related problems occured
12.12.22 22:40:16. End of report.