Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 11.10.2022 16: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 15383756 kB
MemAvailable 30552944 kB
Buffers 0 kB
Cached 16355200 kB
SwapCached 2068 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-10-11 16:38:33
DTS_END                         2022-10-11 16:38:33.4000
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/51216
TRN_ID                          239998
ATT_ID                          665
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: 9367.02 18081 1124042
customer order: creation 178.74 365 21449
customer order: refuse 14.62 392 1754
order to supplier: creation 46.65 1196 5598
order to supplier: removal 3.68 94832 442
invoice (draft): creation 46.58 3110 5590
invoice (draft): removal 53.43 679 6412
invoice accept: apply 42.78 18208 5134
invoice accept: cancel 48.61 786 5833
customer reserve: creation 3886.50 185 466380
customer reserve: removal 4740.72 58 568886
realization accept: apply 83.83 187 10060
realization accept: cancel 89.04 113 10685
payment from customer: creation 59.63 141 7155
payment from customer: removal 5.48 85 657
payment to supplier: creation 50.68 132 6082
payment to supplier: removal 3.40 89 408
service: total inventory turnovers 5.01 975 601
service: total monetary turnovers 6.81 37 817
service: aggregate perf. data 0.79 22153 95
service: refresh index statistics 0.03 217901 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 11-10-2022 16:40:08 to 11-10-2022 16:40:10.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5355
TEST_TIME 2 5379
TEST_TIME 3 4397
TEST_TIME 4 4868
TEST_TIME 5 4584
TEST_TIME 6 5022
TEST_TIME 7 4759
TEST_TIME 8 4958
TEST_TIME 9 4930
TEST_TIME 10 5023
TEST_TIME 11 5140
TEST_TIME 12 5698
TEST_TIME 13 6114
TEST_TIME 14 6353
TEST_TIME 15 6597
TEST_TIME 16 6839
TEST_TIME 17 7076
TEST_TIME 18 7666
TEST_TIME 19 7846
TEST_TIME 20 7807
TEST_TIME 21 7785
TEST_TIME 22 7834
TEST_TIME 23 7825
TEST_TIME 24 8453
TEST_TIME 25 8501
TEST_TIME 26 8747
TEST_TIME 27 9118
TEST_TIME 28 9294
TEST_TIME 29 10609
TEST_TIME 30 10668
TEST_TIME 31 10625
TEST_TIME 32 10466
TEST_TIME 33 10555
TEST_TIME 34 10332
TEST_TIME 35 10223
TEST_TIME 36 10081
TEST_TIME 37 10042
TEST_TIME 38 10010
TEST_TIME 39 10008
TEST_TIME 40 10034
TEST_TIME 41 9899
TEST_TIME 42 9917
TEST_TIME 43 9915
TEST_TIME 44 9848
TEST_TIME 45 9864
TEST_TIME 46 9875
TEST_TIME 47 9826
TEST_TIME 48 9851
TEST_TIME 49 9809
TEST_TIME 50 9799
TEST_TIME 51 9746
TEST_TIME 52 9740
TEST_TIME 53 9713
TEST_TIME 54 9653
TEST_TIME 55 9753
TEST_TIME 56 9803
TEST_TIME 57 9786
TEST_TIME 58 9772
TEST_TIME 59 9708
TEST_TIME 60 9921
TEST_TIME 61 10081
TEST_TIME 62 10096
TEST_TIME 63 10062
TEST_TIME 64 10104
TEST_TIME 65 10044
TEST_TIME 66 9969
TEST_TIME 67 9881
TEST_TIME 68 9876
TEST_TIME 69 9817
TEST_TIME 70 9757
TEST_TIME 71 9730
TEST_TIME 72 9743
TEST_TIME 73 9755
TEST_TIME 74 9702
TEST_TIME 75 9632
TEST_TIME 76 9563
TEST_TIME 77 9556
TEST_TIME 78 9662
TEST_TIME 79 9644
TEST_TIME 80 9599
TEST_TIME 81 9656
TEST_TIME 82 9676
TEST_TIME 83 9678
TEST_TIME 84 9652
TEST_TIME 85 9665
TEST_TIME 86 9754
TEST_TIME 87 9707
TEST_TIME 88 9823
TEST_TIME 89 9822
TEST_TIME 90 9762
TEST_TIME 91 9667
TEST_TIME 92 9637
TEST_TIME 93 9610
TEST_TIME 94 9542
TEST_TIME 95 9525
TEST_TIME 96 9554
TEST_TIME 97 9550
TEST_TIME 98 9573
TEST_TIME 99 9531
TEST_TIME 100 9586
TEST_TIME 101 9604
TEST_TIME 102 9604
TEST_TIME 103 9596
TEST_TIME 104 9591
TEST_TIME 105 9615
TEST_TIME 106 9634
TEST_TIME 107 9629
TEST_TIME 108 9684
TEST_TIME 109 9681
TEST_TIME 110 9648
TEST_TIME 111 9572
TEST_TIME 112 9585
TEST_TIME 113 9550
TEST_TIME 114 9532
TEST_TIME 115 9508
TEST_TIME 116 9472
TEST_TIME 117 9456
TEST_TIME 118 9432
TEST_TIME 119 9410
TEST_TIME 120 9375

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 11-10-2022 16:40:12 to 11-10-2022 16:40:15.

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 1558031 1558031 0 0.00 0 21232 6 0 0
doc_list_biud 1558030 1558030 0 0.00 0 40315 27 0 0
sp_add_invoice_to_stock 5242 5134 108 2.06 37 184145 18209 0 108
sp_cancel_adding_invoice 5833 5833 0 0.00 5 63727 786 0 0
sp_cancel_client_order 1754 1754 0 0.00 4 18455 393 0 0
sp_cancel_customer_reserve 568886 568886 0 0.00 0 24797 59 0 0
sp_cancel_pay_from_customer 657 657 0 0.00 2 4544 86 0 0
sp_cancel_pay_to_supplier 408 408 0 0.00 3 4918 90 0 0
sp_cancel_supplier_invoice 6419 6412 7 0.10 1 40325 680 0 7
sp_cancel_supplier_order 442 442 0 0.00 13 1025001 94833 0 0
sp_cancel_write_off 10685 10685 0 0.00 1 24654 113 0 0
sp_client_order 21449 21449 0 0.00 3 36546 365 0 0
sp_customer_reserve 466380 466380 0 0.00 0 84317 185 0 0
sp_fill_shopping_cart 32625 32625 0 0.00 1 32795 35 0 0
sp_fill_shopping_cart_clo_res 466366 466366 0 0.00 0 15424 13 0 0
sp_get_clo_for_invoice 5118 5118 0 0.00 0 21080 249 0 0
sp_kill_cost_storno 17583 17583 0 0.00 0 6622 51 0 0
sp_kill_qstorno_ret_qs2qd 1155311 1155311 0 0.00 0 40310 31 0 0
sp_kill_qty_storno 609190 609190 0 0.00 0 40314 68 0 0
sp_lock_selected_doc 20914 20914 0 0.00 0 7737 7 0 0
sp_make_cost_storno 28415 28415 0 0.00 0 15542 56 0 0
sp_multiply_rows_for_pdistr 28415 28415 0 0.00 0 11183 27 0 0
sp_multiply_rows_for_qdistr 21449 21449 0 0.00 0 15544 153 0 0
sp_pay_from_customer 7155 7155 0 0.00 4 17523 142 0 0
sp_pay_to_supplier 6082 6082 0 0.00 5 16538 132 0 0
sp_payment_common 13237 13237 0 0.00 1 11091 39 0 0
sp_qd_handle_on_cancel_clo 1754 1754 0 0.00 0 18451 343 0 0
sp_qd_handle_on_invoice_upd_sts 10951 10951 0 0.00 0 39405 310 0 0
sp_qd_handle_on_reserve_upd_sts 10060 10060 0 0.00 0 22198 61 0 0
sp_reserve_write_off 10060 10060 0 0.00 6 22248 187 0 0
sp_supplier_invoice 5590 5590 0 0.00 6 100629 3111 0 0
sp_supplier_order 5598 5598 0 0.00 5 42406 1196 0 0
srv_aggregate_perf_data 96 95 1 1.04 2454 146719 22153 0 1
srv_fill_mon_cache_memory 63 63 0 0.00 1739 161811 46051 0 0
srv_find_qd_qs_mism 1076469 1076469 0 0.00 0 21230 5 0 0
srv_make_invnt_saldo 601 601 0 0.00 34 42017 975 0 0
srv_make_money_saldo 817 817 0 0.00 1 11512 37 0 0
srv_recalc_idx_stat 4 4 0 0.00 74295 444157 217901 0 0
t$perf-abend:sp_add_invoice_to_stock 108 108 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5134 5134 0 0.00 0 17576 187 0 0
t$perf-norm:sp_cancel_adding_invoice 409 409 0 0.00 0 3450 105 0 0
t$perf-norm:sp_cancel_client_order 1754 1754 0 0.00 0 3982 15 0 0
t$perf-norm:sp_cancel_customer_reserve 684 684 0 0.00 0 973 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 657 657 0 0.00 0 1213 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 408 408 0 0.00 0 7187 47 0 0
t$perf-norm:sp_cancel_supplier_invoice 404 404 0 0.00 0 3348 25 0 0
t$perf-norm:sp_cancel_supplier_order 442 442 0 0.00 0 30343 1589 0 0
t$perf-norm:sp_cancel_write_off 980 980 0 0.00 0 5397 21 0 0
t$perf-norm:sp_client_order 21449 21449 0 0.00 0 5418 16 0 0
t$perf-norm:sp_customer_reserve 1034 1034 0 0.00 0 1197 4 0 0
t$perf-norm:sp_pay_from_customer 7155 7155 0 0.00 0 5519 11 0 0
t$perf-norm:sp_pay_to_supplier 6082 6082 0 0.00 0 7330 12 0 0
t$perf-norm:sp_reserve_write_off 10060 10060 0 0.00 0 13832 16 0 0
t$perf-norm:sp_supplier_invoice 5590 5590 0 0.00 0 9439 19 0 0
t$perf-norm:sp_supplier_order 5598 5598 0 0.00 0 4846 18 0 0
t$perf-norm:srv_aggregate_perf_data 95 95 0 0.00 0 130 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 1883 30 0 0
t$perf-norm:srv_make_invnt_saldo 601 601 0 0.00 0 724 17 0 0
t$perf-norm:srv_make_money_saldo 817 817 0 0.00 0 12425 27 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 19 8 0 0
v_add_invoice_to_stock 10448 10232 216 2.06 0 5758 21 0 216
v_all_customers 38584 38584 0 0.00 0 31 1 0 0
v_all_suppliers 31142 31142 0 0.00 0 61 1 0 0
v_all_wares 42896 42896 0 0.00 0 61 1 0 0
v_cancel_adding_invoice 818 818 0 0.00 0 1964 11 0 0
v_cancel_client_order 3508 3508 0 0.00 0 2352 8 0 0
v_cancel_customer_prepayment 1314 1314 0 0.00 0 4528 10 0 0
v_cancel_customer_reserve 1368 1368 0 0.00 0 1317 4 0 0
v_cancel_payment_to_supplier 816 816 0 0.00 0 4296 14 0 0
v_cancel_supplier_invoice 822 808 14 1.70 0 2744 18 0 14
v_cancel_supplier_order 884 884 0 0.00 0 2300 10 0 0
v_cancel_write_off 1960 1960 0 0.00 0 3032 16 0 0
v_max_id_clo_ord 5597 5597 0 0.00 0 1109 2 0 0
v_max_id_clo_res 1034 1034 0 0.00 0 4884 17 0 0
v_max_id_ord_sup 5579 5579 0 0.00 0 7370 8 0 0
v_max_non_paid_invoice 1687 1687 0 0.00 0 232 2 0 0
v_max_non_paid_realizn 3950 3950 0 0.00 0 4665 6 0 0
v_min_id_clo_ord 5597 5597 0 0.00 0 4412 8 0 0
v_min_id_clo_res 1034 1034 0 0.00 0 5215 13 0 0
v_min_id_ord_sup 5579 5579 0 0.00 0 2752 5 0 0
v_min_non_paid_invoice 6082 6082 0 0.00 0 11086 28 0 0
v_min_non_paid_realizn 7155 7155 0 0.00 0 10078 35 0 0
v_our_firm 10722 10722 0 0.00 0 22 1 0 0
v_reserve_write_off 20116 20116 0 0.00 0 4134 8 0 0
x_lock_depdocs_on_canc_invoice 11841 11841 0 0.00 0 17196 119 0 0
x_lock_depdocs_on_canc_sup_ord 442 442 0 0.00 0 3330 307 0 0
x_make_qstorno_1000_1200 5598 5598 0 0.00 1 42400 1123 0 0
x_make_qstorno_1200_2000 5590 5590 0 0.00 2 92352 2985 0 0
x_make_qstorno_2100_3300 466380 466380 0 0.00 0 84315 169 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 11-10-2022 16:40:16 to 11-10-2022 16:40:20.

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
14:08:37 3 8192000000 8800721504 8812593152 6871728 0.083 2 2 3 0 7014176 65600 76848
14:09:38 15136 8192000000 9895740640 10206875648 983617984 10.719 100 83 166 0 1010256880 5459504 21179392
14:10:59 19226 8192000000 9942076368 10262065152 1021433648 11.086 100 91 182 0 1052908944 8094224 23381072
14:12:28 17179 8192000000 9981691536 10316619776 1067391456 11.527 100 83 166 0 1097956256 9956176 20608624
14:13:53 12505 8192000000 10034655744 10383876096 1122683616 12.052 100 83 166 0 1153385712 10964992 19737104
14:15:11 6732 8192000000 10078036656 10433433600 1165642912 12.456 100 84 168 0 1198159328 13113424 19402992
14:16:19 3910 8192000000 10106379216 10475507712 1197283264 12.751 100 77 155 0 1227837584 12827600 17726720
14:17:24 2120 8192000000 10156009392 10531512320 1244382224 13.187 100 74 148 0 1273699264 12794544 16522496
14:18:27 2730 8192000000 10172741088 10550595584 1261622464 13.345 100 81 161 0 1293954160 14985760 17345936
14:19:32 1129 8192000000 10211020672 10593845248 1294747552 13.647 100 89 175 2 1326305680 12772736 18785392
14:20:34 13702 8192000000 10205453168 10602627072 1301347856 13.707 100 61 122 2 1326317328 11410416 13559056
14:21:49 4132 8192000000 10203635760 10612445184 1309793856 13.784 100 39 78 0 1327458416 8532912 9131648
14:22:55 4668 8192000000 10212983904 10620985344 1317864768 13.857 100 42 82 0 1335041552 7446704 9730080
14:24:02 57716 8192000000 10236166512 10643038208 1330825456 13.975 100 39 76 2 1345315488 5260128 9229904
14:26:16 3248 8192000000 10239510720 10650038272 1337602960 14.036 100 39 76 1 1354611872 8354176 8654736
14:27:21 43091 8192000000 10233330800 10654212096 1341096064 14.067 100 33 66 0 1355078880 6926048 7056768
14:29:06 2553 8192000000 10232414384 10645876736 1335543888 14.017 100 38 75 1 1350922112 5136656 10241568
14:30:12 6400 8192000000 10181348784 10595360768 1276566672 13.482 98 20 40 0 1291122032 9784896 4770464
14:31:19 53911 8192000000 10133353088 10532196352 1174425984 12.538 92 28 56 0 1187481824 6279936 6775904
14:33:20 5895 8192000000 10151039520 10551767040 1252226576 13.259 99 24 48 0 1263523872 5848656 5448640
14:34:30 9494 8192000000 10158379600 10565566464 1250797472 13.246 98 26 52 0 1262662528 5302912 6562144
14:35:43 60564 8192000000 10178141280 10593021952 1292126048 13.624 100 24 46 0 1303531824 5527120 5878656
14:37:44 6116 8192000000 10194823872 10614480896 1312753840 13.811 100 35 68 1 1327711712 6584368 8373504
14:38:57 13322 8192000000 10240013760 10646413312 1336589056 14.027 100 55 108 0 1356367984 7759920 12019008
14:40:14 31368 8192000000 10257610064 10675757056 1351347664 14.160 100 39 79 0 1367796992 7779328 8670000
14:42:00 59513 8192000000 10273298464 10700394496 1370973392 14.336 100 27 54 0 1388816128 11651984 6190752
14:44:06 17369 8192000000 10307140272 10725302272 1385904048 14.469 100 45 89 1 1413596672 17812288 9880336
14:45:33 9494 8192000000 10308980240 10731655168 1396711248 14.566 100 54 106 0 1422984704 13534160 12739296
14:46:52 8757 8192000000 10325205216 10750255104 1409512528 14.680 100 45 89 1 1436213344 16663440 10037376
14:48:05 5026 8192000000 10332291904 10758410240 1411358560 14.696 100 51 99 0 1444029984 20922400 11749024
14:49:24 6851 8192000000 10341157216 10764574720 1412299904 14.704 100 58 116 0 1453546128 27641120 13605104
14:50:38 4726 8192000000 10358024560 10776875008 1412937664 14.710 100 65 127 0 1457194400 29228800 15027936
14:51:48 9058 8192000000 10353114496 10775425024 1414711376 14.726 100 65 129 1 1464093344 33835920 15546048
14:53:02 3494 8192000000 10362599456 10782900224 1414847120 14.727 100 74 148 0 1473014288 39956688 18210480
14:54:12 4726 8192000000 10345997728 10756853760 1389152096 14.498 100 73 144 2 1447697120 40843264 17701760
14:55:25 2306 8192000000 10369425280 10788773888 1415450704 14.732 100 85 164 2 1475100784 39305488 20344592
14:56:32 1737 8192000000 10352776256 10771390464 1397268368 14.571 99 73 141 0 1458361552 43809792 17283392
14:57:40 2798 8192000000 10368322832 10781868032 1414641312 14.725 100 79 156 2 1472752144 38418704 19692128
14:58:46 6112 8192000000 10343658144 10768519168 1397350416 14.571 100 43 86 0 1450881360 42245344 11285600
14:59:57 6163 8192000000 10359779696 10778492928 1400916288 14.603 100 47 94 1 1462422000 49155744 12349968
15:01:07 8400 8192000000 10345319472 10767323136 1386993952 14.479 100 39 78 0 1451595888 53860496 10741440
15:02:21 7375 8192000000 10356696032 10785034240 1385948896 14.470 99 42 84 1 1455062592 57385120 11728576
15:03:45 34416 8192000000 10334969424 10774966272 1376503264 14.385 99 25 50 0 1432359920 48773408 7083248
15:05:22 9975 8192000000 10337383232 10778181632 1372468656 14.349 100 26 52 0 1434689248 54796736 7423856
15:06:59 43683 8192000000 10296835824 10750099456 1353351216 14.178 99 21 43 0 1413043040 53290736 6401088
15:09:00 161811 8192000000 10270637424 10718715904 1249470464 13.233 91 4 8 0 1262824400 12962464 391472
15:12:42 74039 8192000000 10281816768 10716151808 1361819664 14.254 99 30 58 1 1375100656 6958160 6322832
15:15:05 85304 8192000000 10287900704 10714562560 1331494112 13.981 97 41 82 0 1350475056 9168832 9812112
15:17:33 138186 8192000000 10302094592 10744520704 1381070128 14.426 100 34 68 0 1407807632 18715536 8021968
15:20:52 23160 8192000000 10292536688 10739539968 1377072368 14.390 99 22 44 0 1390344640 8296240 4976032
15:23:00 17737 8192000000 10329773504 10769616896 1403955696 14.630 100 47 94 0 1433534784 18891136 10687952
15:24:20 52184 8192000000 10301193264 10766876672 1373252464 14.356 98 12 21 0 1391101568 15348384 2500720
15:26:13 32138 8192000000 10303805776 10766831616 1399087584 14.587 100 15 30 0 1421502928 18726960 3688384
15:27:49 83509 8192000000 10288210352 10751815680 1360233776 14.239 98 19 37 1 1374009472 10105552 3670144
15:30:16 71565 8192000000 10292776224 10753581056 1364501648 14.278 98 11 22 0 1381486784 14165152 2819984
15:32:34 84506 8192000000 10285877696 10753826816 1330815104 13.975 96 22 44 0 1358775216 23226528 4733584
15:35:22 37619 8192000000 10303754896 10751344640 1365227008 14.284 98 25 50 0 1377731488 7062832 5441648
15:37:03 63305 8192000000 10315146624 10775609344 1382211392 14.436 99 10 18 0 1405104336 20414016 2478928
15:39:08 74897 8192000000 10295716320 10756128768 1345964752 14.111 96 8 16 0 1365104896 17721264 1418880
15:41:24 25049 8192000000 10274489584 10722598912 1312184688 13.806 95 16 32 0 1323370448 7736960 3448800
15:42:52 52529 8192000000 10289457280 10725076992 1350891440 14.155 99 21 40 1 1368681632 13362080 4428112
15:44:47 91945 8192000000 10254014512 10699395072 1329952064 13.967 98 33 66 0 1352170112 14307904 7910144
15:47:21 14105 8192000000 10274155920 10707685376 1347465216 14.125 99 41 82 0 1368733104 11703504 9564384
15:48:36 65713 8192000000 10251877808 10706391040 1336566736 14.026 98 12 24 0 1353830320 14178176 3085408
15:50:42 35940 8192000000 10264919808 10710110208 1345856848 14.110 99 24 48 0 1364701680 13414528 5430304
15:52:29 95691 8192000000 10279786208 10737586176 1353554352 14.179 98 13 27 0 1370830064 13466720 3808992
15:55:11 72439 8192000000 10264108560 10733137920 1341233264 14.069 98 12 24 0 1364185632 19989120 2963248
15:57:32 30375 8192000000 10269783408 10720198656 1346292112 14.114 98 20 36 0 1357460624 7322928 3845584
15:59:07 65870 8192000000 10254713344 10711601152 1323460912 13.908 97 26 52 0 1345993184 16675776 5856496
16:01:15 45359 8192000000 10262820032 10708406272 1326273248 13.933 98 27 54 0 1346644928 15067664 5304016
16:03:06 52352 8192000000 10267623440 10719506432 1341328144 14.069 99 19 37 0 1360200224 14879744 3992336
16:04:59 43370 8192000000 10280457696 10717376512 1341615984 14.072 98 33 65 0 1358855024 10351728 6887312
16:06:51 114685 8192000000 10270612208 10739712000 1340286752 14.060 98 5 10 0 1358404624 17116720 1001152
16:10:52 43730 8192000000 10258801200 10716819456 1360379728 14.241 99 10 20 0 1370569600 8441440 1748432
16:12:38 52232 8192000000 10278942480 10744262656 1377449872 14.394 100 6 12 0 1390964864 12312384 1202608
16:15:07 91860 8192000000 10275917296 10745618432 1347801408 14.128 97 11 22 0 1361552800 11572720 2178672
16:17:39 42527 8192000000 10312661744 10764128256 1398237904 14.579 100 37 74 0 1418116848 11252704 8626240
16:19:22 80034 8192000000 10306644688 10774929408 1399309488 14.589 100 17 34 0 1414617808 11487568 3820752
16:22:24 37865 8192000000 10306908672 10758942720 1382720272 14.441 99 26 50 0 1401967232 13898624 5348336
16:24:07 109466 8192000000 10315997568 10775908352 1394469952 14.546 100 9 18 0 1409262880 13087808 1705120
16:26:57 42147 8192000000 10307528224 10754015232 1380283552 14.419 99 28 56 0 1395518656 9258816 5976288
16:28:41 109150 8192000000 10294312336 10756276224 1370686944 14.333 98 25 50 0 1384748480 8579152 5482384
16:31:34 20610 8192000000 10272404064 10730627072 1371144512 14.337 100 18 36 0 1384613392 9425168 4043712
16:32:58 47812 8192000000 10261968816 10724683776 1347734480 14.127 98 12 24 0 1356763424 6212512 2816432
16:34:47 75275 8192000000 10251585840 10707599360 1316434480 13.844 96 13 26 0 1327071728 7599248 3038000
16:37:27 46353 8192000000 10253404000 10707992576 1297095120 13.669 95 14 28 0 1309828240 9432224 3300896

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 11-10-2022 16:40:22 to 11-10-2022 16:40:37.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 108 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 11-10-2022 16:40:38 to 11-10-2022 16:40:40.

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          239132
MON$OLDEST_ACTIVE               240144
MON$OLDEST_SNAPSHOT             240144
MON$NEXT_TRANSACTION            240144
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                       2045600
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.33634 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33634 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33634 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33634 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 ---
:186: 
:187: mscs-test-161.budzdorov.ru    Tue Oct 11 16:38:36 2022
:188:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:189: 
:190: 
:191: mscs-test-161.budzdorov.ru    Tue Oct 11 16:38:38 2022
:192:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:193: 
:194: 
:195: mscs-test-161.budzdorov.ru    Tue Oct 11 16:38:40 2022
:196:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:197: 
:198: 
:199: mscs-test-161.budzdorov.ru    Tue Oct 11 16:38:40 2022
:200:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:201: 
:202: 
:203: mscs-test-161.budzdorov.ru    Tue Oct 11 16:38:43 2022
:204:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:205: 
--- end of diff output ---

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

11.10.22 16:40:41. 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-10-11 16:40:44 No severe PSQL-related problems occured
11.10.22 16:40:45. End of report.