Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 14.03.2023 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 14971348 kB
MemAvailable 30406672 kB
Buffers 0 kB
Cached 16712056 kB
SwapCached 3268 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 2023-03-14 22:38:44
DTS_END                         2023-03-14 22:38:44.1410
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/50478
TRN_ID                          244828
ATT_ID                          685
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: 9611.85 24087 1153422
customer order: creation 176.86 343 21223
customer order: refuse 15.25 422 1830
order to supplier: creation 47.13 1238 5655
order to supplier: removal 3.58 102458 430
invoice (draft): creation 46.59 3183 5591
invoice (draft): removal 56.08 764 6729
invoice accept: apply 43.04 16987 5165
invoice accept: cancel 50.75 885 6090
customer reserve: creation 3952.11 167 474253
customer reserve: removal 4914.07 58 589688
realization accept: apply 85.35 206 10242
realization accept: cancel 89.36 117 10723
payment from customer: creation 60.48 126 7258
payment from customer: removal 5.81 78 697
payment to supplier: creation 49.58 140 5949
payment to supplier: removal 3.70 90 444
service: total inventory turnovers 4.70 1452 564
service: total monetary turnovers 6.54 54 785
service: aggregate perf. data 0.85 21481 102
service: refresh index statistics 0.03 331485 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 14-03-2023 22:39:49 to 14-03-2023 22:39:51.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4032
TEST_TIME 2 4282
TEST_TIME 3 3389
TEST_TIME 4 4010
TEST_TIME 5 4268
TEST_TIME 6 4957
TEST_TIME 7 5077
TEST_TIME 8 5021
TEST_TIME 9 5119
TEST_TIME 10 5778
TEST_TIME 11 5808
TEST_TIME 12 5880
TEST_TIME 13 6536
TEST_TIME 14 6906
TEST_TIME 15 6968
TEST_TIME 16 7315
TEST_TIME 17 7465
TEST_TIME 18 7388
TEST_TIME 19 7502
TEST_TIME 20 7943
TEST_TIME 21 7992
TEST_TIME 22 7914
TEST_TIME 23 7972
TEST_TIME 24 8394
TEST_TIME 25 8294
TEST_TIME 26 8393
TEST_TIME 27 8642
TEST_TIME 28 9236
TEST_TIME 29 9718
TEST_TIME 30 9785
TEST_TIME 31 10034
TEST_TIME 32 10222
TEST_TIME 33 10276
TEST_TIME 34 10557
TEST_TIME 35 10416
TEST_TIME 36 10282
TEST_TIME 37 10137
TEST_TIME 38 10060
TEST_TIME 39 10286
TEST_TIME 40 10166
TEST_TIME 41 10092
TEST_TIME 42 10044
TEST_TIME 43 10380
TEST_TIME 44 10354
TEST_TIME 45 10418
TEST_TIME 46 10384
TEST_TIME 47 10248
TEST_TIME 48 10128
TEST_TIME 49 10004
TEST_TIME 50 9998
TEST_TIME 51 9843
TEST_TIME 52 9822
TEST_TIME 53 9852
TEST_TIME 54 9827
TEST_TIME 55 9836
TEST_TIME 56 9832
TEST_TIME 57 9741
TEST_TIME 58 9654
TEST_TIME 59 9733
TEST_TIME 60 9788
TEST_TIME 61 9734
TEST_TIME 62 9667
TEST_TIME 63 9626
TEST_TIME 64 9635
TEST_TIME 65 9753
TEST_TIME 66 9823
TEST_TIME 67 9815
TEST_TIME 68 9774
TEST_TIME 69 9929
TEST_TIME 70 9849
TEST_TIME 71 9875
TEST_TIME 72 9848
TEST_TIME 73 9944
TEST_TIME 74 9899
TEST_TIME 75 9905
TEST_TIME 76 9877
TEST_TIME 77 9941
TEST_TIME 78 9833
TEST_TIME 79 9780
TEST_TIME 80 9768
TEST_TIME 81 9747
TEST_TIME 82 9747
TEST_TIME 83 9740
TEST_TIME 84 9683
TEST_TIME 85 9648
TEST_TIME 86 9585
TEST_TIME 87 9572
TEST_TIME 88 9655
TEST_TIME 89 9615
TEST_TIME 90 9681
TEST_TIME 91 9679
TEST_TIME 92 9680
TEST_TIME 93 9687
TEST_TIME 94 9695
TEST_TIME 95 9693
TEST_TIME 96 9733
TEST_TIME 97 9675
TEST_TIME 98 9769
TEST_TIME 99 9758
TEST_TIME 100 9767
TEST_TIME 101 9844
TEST_TIME 102 9843
TEST_TIME 103 9799
TEST_TIME 104 9751
TEST_TIME 105 9781
TEST_TIME 106 9740
TEST_TIME 107 9700
TEST_TIME 108 9700
TEST_TIME 109 9724
TEST_TIME 110 9690
TEST_TIME 111 9670
TEST_TIME 112 9658
TEST_TIME 113 9637
TEST_TIME 114 9597
TEST_TIME 115 9526
TEST_TIME 116 9627
TEST_TIME 117 9610
TEST_TIME 118 9644
TEST_TIME 119 9626
TEST_TIME 120 9614

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 14-03-2023 22:39:53 to 14-03-2023 22:39:56.

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1592490 1592490 0 0.00 0 20559 6 0 0
doc_list_biud 1592486 1592486 0 0.00 0 57153 28 0 0
sp_add_invoice_to_stock 5245 5165 80 1.52 53 151449 16988 0 80
sp_cancel_adding_invoice 6090 6090 0 0.00 5 89112 886 0 0
sp_cancel_client_order 1830 1830 0 0.00 4 18375 423 0 0
sp_cancel_customer_reserve 589688 589688 0 0.00 0 20082 58 0 0
sp_cancel_pay_from_customer 697 697 0 0.00 2 3465 79 0 0
sp_cancel_pay_to_supplier 444 444 0 0.00 3 3798 91 0 0
sp_cancel_supplier_invoice 6741 6729 12 0.17 1 57155 765 0 12
sp_cancel_supplier_order 430 430 0 0.00 7 913710 102459 0 0
sp_cancel_write_off 10723 10723 0 0.00 1 20751 118 0 0
sp_client_order 21223 21223 0 0.00 3 47110 343 0 0
sp_customer_reserve 474253 474253 0 0.00 0 58015 168 0 0
sp_fill_shopping_cart 32462 32462 0 0.00 1 12329 36 0 0
sp_fill_shopping_cart_clo_res 474249 474249 0 0.00 0 21208 13 0 0
sp_get_clo_for_invoice 5160 5160 0 0.00 0 26937 287 0 0
sp_kill_cost_storno 17954 17954 0 0.00 0 6520 57 0 0
sp_kill_qstorno_ret_qs2qd 1197258 1197258 0 0.00 0 57152 31 0 0
sp_kill_qty_storno 630890 630890 0 0.00 0 57153 70 0 0
sp_lock_selected_doc 21390 21390 0 0.00 0 9480 6 0 0
sp_make_cost_storno 28609 28609 0 0.00 0 13529 63 0 0
sp_multiply_rows_for_pdistr 28607 28607 0 0.00 0 6911 30 0 0
sp_multiply_rows_for_qdistr 21223 21223 0 0.00 0 15645 131 0 0
sp_pay_from_customer 7258 7258 0 0.00 4 19471 127 0 0
sp_pay_to_supplier 5949 5949 0 0.00 4 13536 141 0 0
sp_payment_common 13207 13207 0 0.00 2 18991 37 0 0
sp_qd_handle_on_cancel_clo 1830 1830 0 0.00 0 18339 378 0 0
sp_qd_handle_on_invoice_upd_sts 11249 11249 0 0.00 0 34203 379 0 0
sp_qd_handle_on_reserve_upd_sts 10241 10241 0 0.00 0 17239 77 0 0
sp_reserve_write_off 10242 10242 0 0.00 6 17313 207 0 0
sp_supplier_invoice 5591 5591 0 0.00 6 77182 3183 0 0
sp_supplier_order 5655 5655 0 0.00 5 23670 1239 0 0
srv_aggregate_perf_data 102 102 0 0.00 2921 208117 21481 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 2023 150976 44342 0 0
srv_find_qd_qs_mism 1104518 1104518 0 0.00 0 20449 5 0 0
srv_make_invnt_saldo 564 564 0 0.00 50 147687 1453 0 0
srv_make_money_saldo 785 785 0 0.00 1 18277 55 0 0
srv_recalc_idx_stat 4 4 0 0.00 93442 472872 331485 0 0
t$perf-abend:sp_add_invoice_to_stock 80 80 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5165 5165 0 0.00 0 21607 166 0 0
t$perf-norm:sp_cancel_adding_invoice 438 438 0 0.00 0 2016 91 0 0
t$perf-norm:sp_cancel_client_order 1830 1830 0 0.00 0 5310 14 0 0
t$perf-norm:sp_cancel_customer_reserve 713 713 0 0.00 0 8880 24 0 0
t$perf-norm:sp_cancel_pay_from_customer 697 697 0 0.00 0 4395 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 444 444 0 0.00 0 470 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 386 386 0 0.00 0 453 12 0 0
t$perf-norm:sp_cancel_supplier_order 430 430 0 0.00 0 24070 1641 0 0
t$perf-norm:sp_cancel_write_off 1052 1052 0 0.00 0 856 4 0 0
t$perf-norm:sp_client_order 21223 21223 0 0.00 0 16284 19 0 0
t$perf-norm:sp_customer_reserve 1065 1065 0 0.00 0 1278 8 0 0
t$perf-norm:sp_pay_from_customer 7258 7258 0 0.00 0 14900 11 0 0
t$perf-norm:sp_pay_to_supplier 5949 5949 0 0.00 0 11244 13 0 0
t$perf-norm:sp_reserve_write_off 10242 10242 0 0.00 0 22877 12 0 0
t$perf-norm:sp_supplier_invoice 5591 5591 0 0.00 0 7478 17 0 0
t$perf-norm:sp_supplier_order 5655 5655 0 0.00 0 6963 16 0 0
t$perf-norm:srv_aggregate_perf_data 102 102 0 0.00 0 354 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 910 42 0 0
t$perf-norm:srv_make_invnt_saldo 564 564 0 0.00 0 1898 31 0 0
t$perf-norm:srv_make_money_saldo 785 785 0 0.00 0 986 20 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 3 0 0
v_add_invoice_to_stock 10478 10318 160 1.52 0 6298 17 0 160
v_all_customers 38844 38844 0 0.00 0 18 1 0 0
v_all_suppliers 30870 30870 0 0.00 0 25 1 0 0
v_all_wares 42440 42440 0 0.00 0 50 1 0 0
v_cancel_adding_invoice 876 876 0 0.00 0 695 7 0 0
v_cancel_client_order 3660 3660 0 0.00 0 5695 11 0 0
v_cancel_customer_prepayment 1394 1394 0 0.00 0 2881 8 0 0
v_cancel_customer_reserve 1426 1426 0 0.00 0 3019 10 0 0
v_cancel_payment_to_supplier 888 888 0 0.00 0 3019 14 0 0
v_cancel_supplier_invoice 796 772 24 3.01 0 4849 28 0 24
v_cancel_supplier_order 860 860 0 0.00 0 1309 8 0 0
v_cancel_write_off 2104 2104 0 0.00 0 3025 17 0 0
v_max_id_clo_ord 5653 5653 0 0.00 0 862 2 0 0
v_max_id_clo_res 1065 1065 0 0.00 0 2637 14 0 0
v_max_id_ord_sup 5587 5587 0 0.00 0 6559 4 0 0
v_max_non_paid_invoice 1754 1754 0 0.00 0 1164 4 0 0
v_max_non_paid_realizn 3764 3764 0 0.00 0 18955 12 0 0
v_min_id_clo_ord 5653 5653 0 0.00 0 1641 4 0 0
v_min_id_clo_res 1065 1065 0 0.00 0 2177 8 0 0
v_min_id_ord_sup 5587 5587 0 0.00 0 4882 6 0 0
v_min_non_paid_invoice 5949 5949 0 0.00 0 11799 21 0 0
v_min_non_paid_realizn 7258 7258 0 0.00 0 11009 31 0 0
v_our_firm 10584 10584 0 0.00 0 20 1 0 0
v_reserve_write_off 20480 20480 0 0.00 0 5975 7 0 0
x_lock_depdocs_on_canc_invoice 12433 12433 0 0.00 0 14574 116 0 0
x_lock_depdocs_on_canc_sup_ord 430 430 0 0.00 0 10058 424 0 0
x_make_qstorno_1000_1200 5655 5655 0 0.00 2 23650 1158 0 0
x_make_qstorno_1200_2000 5591 5591 0 0.00 3 77146 3057 0 0
x_make_qstorno_2100_3300 474253 474253 0 0.00 0 58013 152 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 14-03-2023 22:39:57 to 14-03-2023 22:40:01.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
20:08:48 64 8192000000 8862106816 8895184896 54629104 0.662 18 11 12 0 55226448 196768 400576
20:09:49 34594 8192000000 9901497696 10208305152 981434240 10.698 100 88 176 0 1010252160 5775360 23042560
20:11:26 13103 8192000000 9926592880 10242793472 1013199296 11.006 100 85 170 0 1042917232 7495568 22222368
20:12:53 12835 8192000000 9971059600 10302418944 1058614656 11.443 100 79 158 0 1086979984 8385552 19979776
20:14:14 11922 8192000000 10024896272 10371567616 1116643088 11.995 100 73 146 0 1144389616 10221888 17524640
20:15:29 4124 8192000000 10071601952 10428280832 1161932976 12.421 100 81 160 0 1192908400 12633328 18342096
20:16:42 4349 8192000000 10123311584 10486882304 1204599472 12.819 100 83 165 0 1236339008 13405232 18334304
20:17:48 2608 8192000000 10153553600 10522574848 1236868848 13.117 100 89 178 0 1269804704 13932608 19003248
20:18:52 4321 8192000000 10174093984 10552950784 1265182880 13.378 100 72 142 1 1291722992 11631552 14908560
20:19:58 1464 8192000000 10201721568 10590683136 1295542256 13.655 100 81 163 0 1325230384 11520112 18168016
20:21:01 36438 8192000000 10205978800 10610089984 1310542544 13.791 100 57 111 1 1333399312 9774512 13082256
20:22:39 3406 8192000000 10225052048 10628575232 1318800880 13.866 100 49 98 0 1338486784 8427744 11258160
20:23:44 9806 8192000000 10208367216 10630668288 1328930912 13.957 100 15 31 0 1337888208 5609280 3348016
20:24:55 10382 8192000000 10224258992 10647658496 1333719344 14.001 100 21 42 1 1348452944 10129952 4603648
20:26:07 51560 8192000000 10230829136 10652848128 1337167824 14.032 100 24 46 0 1347956400 5942944 4845632
20:28:03 6195 8192000000 10219001728 10639708160 1310146416 13.787 98 27 55 0 1321305344 5573728 5585200
20:29:13 6830 8192000000 10196500112 10604101632 1283537008 13.545 97 17 34 0 1292212656 5285424 3390224
20:30:21 9025 8192000000 10157940416 10562699264 1217292336 12.937 95 20 41 0 1226844736 5236032 4316368
20:31:30 24077 8192000000 10145866176 10549149696 1168741824 12.485 92 22 44 0 1178937344 5012672 5182848
20:32:57 4356 8192000000 10138809632 10539032576 1248760080 13.227 100 30 59 2 1260993424 5749024 6484320
20:34:04 5476 8192000000 10163011888 10563960832 1267203136 13.396 100 34 68 0 1281215952 6193392 7819424
20:35:11 23090 8192000000 10196620560 10595655680 1283421776 13.544 100 30 57 1 1296080416 5199264 7459376
20:36:36 1000 8192000000 10201964240 10597126144 1294775920 13.648 100 63 129 3 1315532672 6358512 14398240
20:37:38 10897 8192000000 10189666112 10604965888 1301556992 13.709 100 24 46 0 1312317488 5990064 4770432
20:38:51 20295 8192000000 10232385328 10640711680 1327550080 13.945 100 40 76 0 1341504608 6019472 7935056
20:40:27 22858 8192000000 10250135120 10665705472 1351767728 14.163 100 54 107 0 1376688560 11956672 12964160
20:41:53 56165 8192000000 10265200320 10688847872 1356836784 14.209 100 33 66 1 1378951760 13792608 8322368
20:43:53 4920 8192000000 10287656096 10705473536 1373137248 14.355 100 55 106 1 1399446384 14367936 11941200
20:45:04 18851 8192000000 10285383440 10715115520 1380135904 14.418 100 37 73 1 1402646032 13402768 9107360
20:46:31 7237 8192000000 10308418624 10727981056 1390450624 14.510 100 55 108 1 1419077840 14997792 13629424
20:47:49 21563 8192000000 10312579120 10739265536 1396396896 14.563 100 42 80 0 1424204336 17406896 10400544
20:49:23 6743 8192000000 10309655120 10740391936 1387221184 14.481 99 51 102 2 1421795744 22516160 12058400
20:50:44 6770 8192000000 10328394240 10743427072 1388917616 14.496 100 60 120 1 1430238016 26054976 15265424
20:51:56 4470 8192000000 10331613248 10753445888 1399669424 14.592 100 63 125 0 1439154256 24806048 14678784
20:53:09 5324 8192000000 10333688016 10756358144 1390531648 14.511 99 62 123 0 1432371552 26742560 15097344
20:54:21 4557 8192000000 10352360144 10773131264 1410984000 14.693 100 72 140 0 1461948672 33084192 17880480
20:55:31 2997 8192000000 10310234624 10719129600 1366577040 14.296 100 74 148 1 1411847504 27545264 17725200
20:56:46 2774 8192000000 10319271696 10735628288 1379505280 14.412 100 64 127 0 1426964464 31676336 15782848
20:57:58 2022 8192000000 10316921840 10717839360 1358580096 14.225 100 82 165 2 1416320080 38125136 19614848
20:59:03 5064 8192000000 10299233248 10706436096 1348569472 14.135 99 51 101 1 1394211520 32890192 12751856
21:00:21 4522 8192000000 10307308336 10715017216 1356707968 14.208 99 65 130 1 1411762048 38078384 16975696
21:01:35 2078 8192000000 10352510432 10753912832 1387516960 14.484 100 86 170 1 1453765728 44849216 21399552
21:02:40 14963 8192000000 10318252848 10743660544 1356535472 14.206 98 37 74 0 1409286768 42516448 10234848
21:03:57 5018 8192000000 10338534000 10740523008 1375292352 14.374 99 62 124 1 1434579040 42198608 17088080
21:05:05 5882 8192000000 10356543360 10772967424 1388086992 14.489 100 51 99 1 1455339312 53013760 14238560
21:06:15 14951 8192000000 10345685312 10791251968 1388149488 14.489 100 37 75 0 1450064144 51165744 10748912
21:07:31 21565 8192000000 10321600384 10775945216 1389155872 14.498 100 30 61 2 1439207712 41524336 8527504
21:09:41 31216 8192000000 10317872576 10761216000 1376187296 14.382 100 25 50 0 1416651520 34308528 6155696
21:11:13 25030 8192000000 10310115648 10777198592 1381160640 14.427 100 13 26 0 1420754864 36186544 3407680
21:12:45 103560 8192000000 10287204304 10728640512 1364699936 14.280 99 10 20 0 1376898304 10472736 1725632
21:15:32 142823 8192000000 10305361536 10759376896 1395931088 14.559 100 26 50 0 1420646640 18070432 6645120
21:18:56 79197 8192000000 10324072720 10769375232 1396469104 14.564 100 16 32 0 1419011616 18883760 3658752
21:21:17 99897 8192000000 10323067104 10781020160 1395309440 14.553 100 5 10 0 1422668224 26577456 781328
21:24:04 150976 8192000000 10285551856 10730962944 1313263056 13.816 94 12 24 0 1328958832 13014208 2681568
21:28:24 69788 8192000000 10312908928 10761019392 1383241120 14.446 99 25 50 0 1400250688 12126128 4883440
21:31:24 33163 8192000000 10288329488 10744049664 1381782592 14.432 99 12 25 0 1392231840 7455408 2993840
21:32:59 37467 8192000000 10306196880 10753781760 1380042416 14.417 99 50 100 0 1399706736 8372576 11291744
21:35:11 45822 8192000000 10305203024 10764611584 1400597040 14.600 100 10 20 0 1415794992 12749888 2448064
21:36:58 51529 8192000000 10278181232 10740936704 1355775552 14.199 98 13 26 0 1376141088 16902624 3462912
21:38:58 82326 8192000000 10262122576 10721390592 1336965152 14.030 97 12 24 0 1349971168 10362768 2643248
21:41:22 34592 8192000000 10262624560 10717655040 1322549584 13.900 96 15 30 0 1336250464 10316976 3383904
21:43:29 64270 8192000000 10256494768 10709135360 1295738176 13.656 95 9 18 0 1310971696 13516496 1717024
21:45:57 31814 8192000000 10270818992 10716250112 1344215840 14.095 98 17 33 0 1363212224 15241680 3754704
21:47:29 63497 8192000000 10264136272 10706444288 1274501328 13.463 93 19 38 1 1294840656 15544192 4795136
21:49:35 24336 8192000000 10264840496 10711224320 1348970736 14.138 100 43 86 0 1371424080 13205280 9248064
21:51:07 54484 8192000000 10261138976 10705698816 1291288448 13.616 95 10 20 0 1305755824 12257856 2209520
21:53:08 52567 8192000000 10270135920 10709798912 1323796192 13.911 98 22 44 0 1338854000 10145216 4912592
21:55:04 64786 8192000000 10244313600 10699481088 1272755024 13.447 94 13 26 1 1289762272 14128976 2878272
21:57:16 122968 8192000000 10279412000 10734534656 1337579328 14.036 98 21 42 0 1363562256 20989280 4993648
22:00:28 54065 8192000000 10264353440 10721050624 1351319696 14.159 99 28 56 0 1368970320 10672400 6978224
22:02:30 67296 8192000000 10279427632 10737479680 1351143680 14.158 98 18 36 0 1371224624 16067328 4013616
22:05:20 77531 8192000000 10276581968 10722680832 1334305088 14.006 97 13 26 0 1357299456 19268592 3725776
22:09:09 77934 8192000000 10291650704 10754768896 1389376720 14.500 100 10 20 0 1405521616 14150288 1994608
22:11:31 61975 8192000000 10292095520 10749394944 1367642544 14.306 98 14 28 0 1379989472 9356064 2990864
22:13:33 69125 8192000000 10304871536 10763087872 1380783744 14.424 99 17 34 1 1397416512 13204976 3427792
22:15:43 16927 8192000000 10304022272 10750644224 1385797920 14.468 99 37 74 0 1406507728 12391200 8318608
22:17:00 76287 8192000000 10301578096 10753482752 1314933136 13.831 94 6 12 0 1329300816 13166944 1200736
22:19:20 121659 8192000000 10300726800 10744995840 1308936032 13.776 94 25 46 1 1327127632 13210400 4981200
22:22:31 120299 8192000000 10291493248 10750668800 1362227040 14.257 98 34 64 1 1380983120 10732720 8023360
22:25:34 17789 8192000000 10281844096 10730868736 1369141472 14.319 99 37 74 0 1388479904 11112256 8226176
22:26:53 97393 8192000000 10301400832 10766585856 1383653840 14.449 99 19 39 0 1404373488 16416992 4302656
22:29:31 35860 8192000000 10280940928 10743209984 1365505424 14.287 98 25 48 2 1379192432 7932096 5754912
22:31:31 36146 8192000000 10279098240 10737131520 1352144864 14.167 97 20 40 0 1363132384 6754048 4233472
22:33:08 60094 8192000000 10282688560 10736418816 1343053984 14.085 98 26 52 0 1369740720 20485168 6201568
22:35:13 50565 8192000000 10280707440 10744385536 1365390864 14.286 99 42 81 1 1388876416 14783952 8701600
22:37:05 19442 8192000000 10268901600 10711539712 1314764816 13.829 96 21 41 0 1328351584 9386512 4200256
22:38:41 5376 8192000000 9168727248 9424388096 330314576 3.875 25 4 8 0 333728480 2720112 693792

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 14-03-2023 22:40:03 to 14-03-2023 22:40:19.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 80 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 14-03-2023 22:40:20 to 14-03-2023 22:40:22.

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          245172
MON$OLDEST_ACTIVE               245173
MON$OLDEST_SNAPSHOT             245173
MON$NEXT_TRANSACTION            245173
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                       2061664
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.33666 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33666 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33666 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33666 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 ---
:236: 
:237: mscs-test-161.budzdorov.ru    Tue Mar 14 22:38:51 2023
:238:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:239: 
--- end of diff output ---

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

14.03.23 22:40:23. 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
2023-03-14 22:40:25 No severe PSQL-related problems occured
14.03.23 22:40:27. End of report.