Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 10.06.2022 19:38
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 16371824 kB
MemAvailable 31280900 kB
Buffers 0 kB
Cached 15319664 kB
SwapCached 2436 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.10
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-06-10 19:38:18
DTS_END                         2022-06-10 19:38:18.8630
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/49074
TRN_ID                          244279
ATT_ID                          670
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: 9540.27 19475 1144832
customer order: creation 180.71 354 21685
customer order: refuse 15.26 324 1831
order to supplier: creation 47.72 1295 5726
order to supplier: removal 3.47 104279 416
invoice (draft): creation 47.91 3098 5749
invoice (draft): removal 54.69 782 6563
invoice accept: apply 42.95 16763 5154
invoice accept: cancel 49.39 745 5927
customer reserve: creation 3994.03 164 479283
customer reserve: removal 4794.90 59 575388
realization accept: apply 87.45 156 10494
realization accept: cancel 85.74 114 10289
payment from customer: creation 62.26 100 7471
payment from customer: removal 6.24 77 749
payment to supplier: creation 51.36 116 6163
payment to supplier: removal 3.36 97 403
service: total inventory turnovers 5.29 877 635
service: total monetary turnovers 6.82 51 818
service: aggregate perf. data 0.70 21157 84
service: refresh index statistics 0.03 238899 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 10-06-2022 19:39:10 to 10-06-2022 19:39:12.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3867
TEST_TIME 2 5234
TEST_TIME 3 5268
TEST_TIME 4 5823
TEST_TIME 5 5316
TEST_TIME 6 5941
TEST_TIME 7 5588
TEST_TIME 8 5777
TEST_TIME 9 5834
TEST_TIME 10 5811
TEST_TIME 11 6050
TEST_TIME 12 6064
TEST_TIME 13 6436
TEST_TIME 14 6694
TEST_TIME 15 6731
TEST_TIME 16 6850
TEST_TIME 17 7011
TEST_TIME 18 7363
TEST_TIME 19 7472
TEST_TIME 20 8164
TEST_TIME 21 8311
TEST_TIME 22 9237
TEST_TIME 23 9363
TEST_TIME 24 9407
TEST_TIME 25 10364
TEST_TIME 26 10970
TEST_TIME 27 11144
TEST_TIME 28 11030
TEST_TIME 29 10769
TEST_TIME 30 10573
TEST_TIME 31 10503
TEST_TIME 32 10434
TEST_TIME 33 10531
TEST_TIME 34 10297
TEST_TIME 35 10192
TEST_TIME 36 10039
TEST_TIME 37 9917
TEST_TIME 38 10182
TEST_TIME 39 10215
TEST_TIME 40 10066
TEST_TIME 41 9952
TEST_TIME 42 9885
TEST_TIME 43 9920
TEST_TIME 44 9893
TEST_TIME 45 9830
TEST_TIME 46 9911
TEST_TIME 47 9949
TEST_TIME 48 10087
TEST_TIME 49 10108
TEST_TIME 50 10039
TEST_TIME 51 10032
TEST_TIME 52 9997
TEST_TIME 53 10066
TEST_TIME 54 10092
TEST_TIME 55 10106
TEST_TIME 56 9979
TEST_TIME 57 9889
TEST_TIME 58 9835
TEST_TIME 59 9769
TEST_TIME 60 9787
TEST_TIME 61 9669
TEST_TIME 62 9674
TEST_TIME 63 9620
TEST_TIME 64 9638
TEST_TIME 65 9629
TEST_TIME 66 9548
TEST_TIME 67 9609
TEST_TIME 68 9631
TEST_TIME 69 9636
TEST_TIME 70 9638
TEST_TIME 71 9682
TEST_TIME 72 9713
TEST_TIME 73 9701
TEST_TIME 74 9841
TEST_TIME 75 9837
TEST_TIME 76 9851
TEST_TIME 77 9888
TEST_TIME 78 9829
TEST_TIME 79 9720
TEST_TIME 80 9733
TEST_TIME 81 9667
TEST_TIME 82 9614
TEST_TIME 83 9656
TEST_TIME 84 9621
TEST_TIME 85 9600
TEST_TIME 86 9585
TEST_TIME 87 9608
TEST_TIME 88 9572
TEST_TIME 89 9605
TEST_TIME 90 9568
TEST_TIME 91 9560
TEST_TIME 92 9600
TEST_TIME 93 9606
TEST_TIME 94 9590
TEST_TIME 95 9681
TEST_TIME 96 9674
TEST_TIME 97 9648
TEST_TIME 98 9734
TEST_TIME 99 9765
TEST_TIME 100 9780
TEST_TIME 101 9782
TEST_TIME 102 9693
TEST_TIME 103 9688
TEST_TIME 104 9646
TEST_TIME 105 9644
TEST_TIME 106 9598
TEST_TIME 107 9603
TEST_TIME 108 9582
TEST_TIME 109 9532
TEST_TIME 110 9541
TEST_TIME 111 9547
TEST_TIME 112 9525
TEST_TIME 113 9522
TEST_TIME 114 9539
TEST_TIME 115 9584
TEST_TIME 116 9563
TEST_TIME 117 9605
TEST_TIME 118 9578
TEST_TIME 119 9595
TEST_TIME 120 9543

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 10-06-2022 19:39:13 to 10-06-2022 19:39:17.

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 1589410 1589410 0 0.00 0 24053 6 0 0
doc_list_biud 1589410 1589410 0 0.00 0 46353 27 0 0
sp_add_invoice_to_stock 5332 5154 178 3.33 35 190641 16764 0 178
sp_cancel_adding_invoice 5927 5927 0 0.00 5 94932 746 0 0
sp_cancel_client_order 1831 1831 0 0.00 4 22102 325 0 0
sp_cancel_customer_reserve 575388 575388 0 0.00 1 46357 59 0 0
sp_cancel_pay_from_customer 749 749 0 0.00 2 6827 77 0 0
sp_cancel_pay_to_supplier 403 403 0 0.00 3 12606 98 0 0
sp_cancel_supplier_invoice 6575 6563 12 0.18 2 14796 782 0 12
sp_cancel_supplier_order 416 416 0 0.00 11 1020413 104279 0 0
sp_cancel_write_off 10289 10289 0 0.00 1 16157 114 0 0
sp_client_order 21685 21685 0 0.00 3 49063 355 0 0
sp_customer_reserve 479283 479283 0 0.00 0 64641 165 0 0
sp_fill_shopping_cart 33154 33154 0 0.00 1 15669 37 0 0
sp_fill_shopping_cart_clo_res 479280 479280 0 0.00 0 18518 15 0 0
sp_get_clo_for_invoice 5149 5149 0 0.00 0 26632 261 0 0
sp_kill_cost_storno 17368 17368 0 0.00 0 8621 52 0 0
sp_kill_qstorno_ret_qs2qd 1168044 1168044 0 0.00 0 46349 32 0 0
sp_kill_qty_storno 616057 616057 0 0.00 0 46353 69 0 0
sp_lock_selected_doc 21598 21598 0 0.00 0 8455 5 0 0
sp_make_cost_storno 29277 29277 0 0.00 0 24046 48 0 0
sp_multiply_rows_for_pdistr 29277 29277 0 0.00 0 15783 26 0 0
sp_multiply_rows_for_qdistr 21685 21685 0 0.00 0 17962 154 0 0
sp_pay_from_customer 7471 7471 0 0.00 4 15683 101 0 0
sp_pay_to_supplier 6163 6163 0 0.00 5 16584 117 0 0
sp_payment_common 13634 13634 0 0.00 1 15655 29 0 0
sp_qd_handle_on_cancel_clo 1831 1831 0 0.00 0 22046 297 0 0
sp_qd_handle_on_invoice_upd_sts 11076 11076 0 0.00 0 25852 301 0 0
sp_qd_handle_on_reserve_upd_sts 10494 10494 0 0.00 0 31923 58 0 0
sp_reserve_write_off 10494 10494 0 0.00 6 32442 157 0 0
sp_supplier_invoice 5749 5749 0 0.00 6 95300 3098 0 0
sp_supplier_order 5726 5726 0 0.00 5 33386 1295 0 0
srv_aggregate_perf_data 85 84 1 1.17 2900 301346 21158 0 1
srv_fill_mon_cache_memory 61 61 0 0.00 1973 148608 49625 0 0
srv_find_qd_qs_mism 1095344 1095344 0 0.00 0 18980 5 0 0
srv_make_invnt_saldo 635 635 0 0.00 23 31041 878 0 0
srv_make_money_saldo 818 818 0 0.00 1 21754 51 0 0
srv_recalc_idx_stat 4 4 0 0.00 109808 339613 238900 0 0
t$perf-abend:sp_add_invoice_to_stock 178 178 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-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5154 5154 0 0.00 0 13786 168 0 0
t$perf-norm:sp_cancel_adding_invoice 432 432 0 0.00 0 2849 71 0 0
t$perf-norm:sp_cancel_client_order 1831 1831 0 0.00 0 9059 19 0 0
t$perf-norm:sp_cancel_customer_reserve 694 694 0 0.00 0 1695 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 749 749 0 0.00 0 2432 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 403 403 0 0.00 0 2452 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 408 408 0 0.00 0 527 6 0 0
t$perf-norm:sp_cancel_supplier_order 416 416 0 0.00 0 22928 1586 0 0
t$perf-norm:sp_cancel_write_off 1022 1022 0 0.00 0 2042 9 0 0
t$perf-norm:sp_client_order 21685 21685 0 0.00 0 23783 20 0 0
t$perf-norm:sp_customer_reserve 1016 1016 0 0.00 0 4379 12 0 0
t$perf-norm:sp_pay_from_customer 7471 7471 0 0.00 0 9636 12 0 0
t$perf-norm:sp_pay_to_supplier 6163 6163 0 0.00 0 2772 7 0 0
t$perf-norm:sp_reserve_write_off 10494 10494 0 0.00 0 7710 12 0 0
t$perf-norm:sp_supplier_invoice 5749 5749 0 0.00 0 5999 22 0 0
t$perf-norm:sp_supplier_order 5726 5726 0 0.00 0 5599 14 0 0
t$perf-norm:srv_aggregate_perf_data 84 84 0 0.00 0 1081 20 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 13 1 0 0
t$perf-norm:srv_make_invnt_saldo 635 635 0 0.00 0 940 21 0 0
t$perf-norm:srv_make_money_saldo 818 818 0 0.00 0 1238 12 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 10 6 0 0
v_add_invoice_to_stock 10654 10298 356 3.34 0 8813 18 0 356
v_all_customers 39346 39346 0 0.00 0 41 0 0 0
v_all_suppliers 32240 32240 0 0.00 0 40 1 0 0
v_all_wares 43370 43370 0 0.00 0 110 1 0 0
v_cancel_adding_invoice 864 864 0 0.00 0 5177 16 0 0
v_cancel_client_order 3662 3662 0 0.00 0 7162 6 0 0
v_cancel_customer_prepayment 1498 1498 0 0.00 0 1821 6 0 0
v_cancel_customer_reserve 1388 1388 0 0.00 0 5147 7 0 0
v_cancel_payment_to_supplier 806 806 0 0.00 0 2581 8 0 0
v_cancel_supplier_invoice 840 816 24 2.85 0 1171 14 0 24
v_cancel_supplier_order 832 832 0 0.00 0 8272 20 0 0
v_cancel_write_off 2044 2044 0 0.00 0 6085 15 0 0
v_max_id_clo_ord 5726 5726 0 0.00 0 6097 2 0 0
v_max_id_clo_res 1016 1016 0 0.00 0 2704 19 0 0
v_max_id_ord_sup 5743 5743 0 0.00 0 2704 2 0 0
v_max_non_paid_invoice 1512 1512 0 0.00 0 796 2 0 0
v_max_non_paid_realizn 3964 3964 0 0.00 0 2056 4 0 0
v_min_id_clo_ord 5726 5726 0 0.00 0 1440 5 0 0
v_min_id_clo_res 1016 1016 0 0.00 0 2496 8 0 0
v_min_id_ord_sup 5743 5743 0 0.00 0 6547 10 0 0
v_min_non_paid_invoice 6163 6163 0 0.00 0 15496 19 0 0
v_min_non_paid_realizn 7471 7471 0 0.00 0 15647 25 0 0
v_our_firm 11038 11038 0 0.00 0 41 1 0 0
v_reserve_write_off 20988 20988 0 0.00 0 8347 6 0 0
x_lock_depdocs_on_canc_invoice 12082 12082 0 0.00 0 17308 110 0 0
x_lock_depdocs_on_canc_sup_ord 416 416 0 0.00 0 16471 402 0 0
x_make_qstorno_1000_1200 5726 5726 0 0.00 1 33358 1224 0 0
x_make_qstorno_1200_2000 5749 5749 0 0.00 3 95256 2962 0 0
x_make_qstorno_2100_3300 479283 479283 0 0.00 0 64619 148 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 10-06-2022 19:39:18 to 10-06-2022 19:39:22.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
17:08:22 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:23 22871 8192000000 9916932768 10227429376 987292784 10.755 100 82 164 0 1013965792 5493696 21179312
17:10:49 15822 8192000000 9958440368 10279243776 1039836992 11.263 100 88 176 0 1069619968 7601456 22181520
17:12:21 21843 8192000000 9994667072 10329272320 1071222608 11.564 100 77 154 0 1103543312 13511632 18809072
17:13:58 10509 8192000000 10042790208 10390798336 1126415472 12.088 100 83 166 2 1157541280 11210960 19914848
17:15:20 7142 8192000000 10092980896 10457477120 1185796992 12.644 100 71 142 1 1213417104 11386784 16233328
17:16:29 5193 8192000000 10134044864 10509438976 1229867824 13.053 100 67 132 0 1255678224 11577168 14233232
17:17:36 3211 8192000000 10162133088 10541682688 1249483360 13.233 100 79 155 2 1278789520 12295696 17010464
17:18:41 2566 8192000000 10201364560 10583007232 1285674288 13.565 100 96 193 2 1321655760 14638160 21343312
17:19:45 1348 8192000000 10220312032 10606280704 1302894688 13.722 100 88 177 2 1335857344 13147664 19814992
17:20:47 6480 8192000000 10201109280 10610270208 1308907680 13.776 100 43 84 1 1325717424 7445088 9364656
17:21:58 5736 8192000000 10203934544 10615406592 1315863056 13.839 100 25 50 0 1328317968 6478400 5976512
17:23:07 12325 8192000000 10229151920 10626727936 1320187792 13.878 100 55 112 4 1339341456 6158000 12995664
17:24:20 7861 8192000000 10221303344 10634264576 1325197360 13.924 100 30 60 1 1338407008 6581632 6628016
17:25:29 7044 8192000000 10214049312 10636800000 1330450816 13.971 100 20 40 0 1340309392 5079504 4779072
17:26:41 21185 8192000000 10213344128 10639773696 1333719408 14.001 100 18 36 1 1343772080 5500320 4552352
17:28:36 7667 8192000000 10208446816 10623574016 1296131088 13.660 98 32 63 2 1310626384 7190240 7305056
17:29:44 7197 8192000000 10201348112 10618806272 1300882784 13.703 99 25 48 4 1312448704 5659344 5906576
17:30:57 9158 8192000000 10152454784 10546946048 1231530960 13.068 98 17 35 1 1244739504 9437840 3770704
17:32:09 19715 8192000000 10110740496 10507280384 1141445072 12.229 91 24 48 3 1152540032 5763264 5331696
17:33:32 6878 8192000000 10155519936 10560131072 1263935312 13.366 100 21 42 1 1274278944 5103888 5239744
17:34:45 7148 8192000000 10177123392 10590257152 1290289664 13.607 100 34 69 0 1305058432 6811312 7957456
17:35:56 66954 8192000000 10190634192 10601672704 1295463344 13.654 100 23 46 0 1306256880 5019968 5773568
17:38:09 6595 8192000000 10205487104 10622541824 1316036912 13.841 100 24 48 0 1327448992 5507808 5904272
17:39:23 14230 8192000000 10251757328 10658275328 1336891008 14.029 100 63 126 1 1362784384 12316144 13577232
17:40:40 21790 8192000000 10259514992 10677510144 1352460224 14.170 100 38 76 2 1373654480 12132208 9062048
17:42:05 38428 8192000000 10275608304 10699698176 1372153920 14.346 100 31 62 0 1393534688 13266528 8114240
17:43:55 12357 8192000000 10292438080 10719133696 1379004656 14.408 100 40 78 0 1403843424 15343344 9495424
17:45:17 17649 8192000000 10317808944 10740092928 1393460048 14.537 100 37 73 0 1426142080 23619552 9062480
17:46:41 9667 8192000000 10329987152 10753216512 1405582464 14.645 100 52 102 1 1441729760 23916464 12230832
17:47:57 5256 8192000000 10351871712 10776203264 1417594192 14.751 100 66 133 0 1461904480 28832144 15478144
17:49:07 3560 8192000000 10372862960 10792239104 1417280848 14.749 99 76 150 0 1472313936 36746816 18286272
17:50:15 4264 8192000000 10384782864 10797670400 1422728656 14.797 100 77 150 1 1481612928 40094976 18789296
17:51:23 3521 8192000000 10379554400 10799157248 1422249936 14.793 100 71 142 1 1484723104 45244000 17229168
17:52:36 3508 8192000000 10384944192 10808332288 1433229456 14.890 100 72 144 1 1494660992 43380992 18050544
17:53:44 2726 8192000000 10397350640 10809249792 1428014512 14.844 100 84 169 0 1497228272 47966064 21247696
17:54:50 2094 8192000000 10409132192 10824343552 1439965168 14.949 100 82 161 0 1511577824 51768272 19844384
17:55:57 1970 8192000000 10394423824 10805940224 1409390688 14.679 99 95 189 1 1491266016 57875104 24000224
17:57:01 8618 8192000000 10393890528 10805792768 1405821456 14.647 99 61 121 3 1484491152 62035280 16634416
17:58:15 16744 8192000000 10355474544 10790768640 1390356992 14.509 98 37 74 1 1453558944 52636976 10564976
17:59:37 3815 8192000000 10356178144 10775547904 1382190256 14.436 99 63 125 1 1450610624 51202080 17218288
18:00:41 13674 8192000000 10317519920 10763046912 1342993360 14.084 97 35 70 1 1408335200 55173760 10168080
18:02:06 19878 8192000000 10306354256 10740899840 1349887264 14.146 99 23 46 1 1398288672 41799696 6601712
18:03:58 48655 8192000000 10261482880 10702704640 1306691616 13.756 97 13 26 0 1349267776 39023360 3552800
18:05:47 86410 8192000000 10268589776 10712367104 1325360944 13.925 98 17 34 1 1344232720 15140032 3731744
18:08:22 99410 8192000000 10267810240 10721378304 1371934096 14.344 100 27 54 0 1390105360 11596784 6574480
18:11:05 69374 8192000000 10269536944 10721288192 1358995088 14.228 99 16 32 1 1380692192 18137536 3559568
18:13:20 49372 8192000000 10295561968 10738274304 1365763104 14.289 99 26 52 0 1385192128 13032496 6396528
18:15:15 27275 8192000000 10297353184 10735992832 1375891584 14.380 100 39 77 3 1402409616 16654608 9863424
18:16:54 107799 8192000000 10266495680 10705723392 1330737856 13.974 97 21 41 1 1348874704 13289744 4847104
18:20:06 65728 8192000000 10270731472 10731679744 1372624704 14.351 100 14 28 1 1391055456 15215856 3214896
18:22:12 46784 8192000000 10307122272 10753736704 1383734288 14.450 100 13 26 2 1396627472 9664880 3228304
18:24:05 69830 8192000000 10287747488 10754801664 1354337632 14.186 98 22 44 0 1377915968 18423056 5155280
18:26:21 89747 8192000000 10293935616 10759905280 1371726272 14.343 99 29 58 1 1399655264 21228784 6700208
18:28:52 50719 8192000000 10293432448 10744631296 1376451888 14.385 99 21 42 0 1396187312 15590672 4144752
18:30:44 94197 8192000000 10321148992 10770558976 1391375520 14.518 100 22 44 0 1417059888 20934592 4749776
18:33:18 148608 8192000000 10268575632 10727399424 1351375024 14.160 97 17 34 0 1364929552 9607664 3946864
18:36:58 4129 8192000000 10287220768 10750840832 1375987408 14.381 99 8 16 2 1390361072 12752992 1620672
18:38:03 71494 8192000000 10273624768 10733137920 1340796848 14.065 97 9 18 0 1355106736 12388384 1921504
18:40:15 45360 8192000000 10258597952 10724958208 1334172928 14.005 97 11 22 1 1346461808 9967776 2321104
18:42:29 42882 8192000000 10255631536 10711441408 1314194944 13.824 96 8 16 0 1323744368 8132512 1416912
18:44:13 29857 8192000000 10260724336 10694836224 1313124288 13.814 97 45 90 1 1334064512 11112384 9827840
18:45:43 57295 8192000000 10268078368 10712301568 1328559760 13.954 98 21 42 0 1343704128 9991536 5152832
18:47:41 98300 8192000000 10257169408 10716037120 1287581360 13.582 94 10 20 0 1307885168 17827248 2476560
18:50:59 41515 8192000000 10278488592 10715926528 1329240208 13.960 97 37 74 0 1353439488 15929408 8269872
18:52:41 96434 8192000000 10286540096 10719653888 1338647424 14.045 98 18 36 0 1358978368 16506160 3824784
18:55:18 94096 8192000000 10269697616 10706042880 1265887296 13.384 92 27 54 0 1281852320 10315248 5649776
18:57:57 86828 8192000000 10251589184 10702893056 1317303792 13.852 97 28 56 1 1338034192 14366720 6363680
19:01:10 77149 8192000000 10255830608 10717675520 1358751488 14.226 100 9 18 1 1373662144 12602208 2308448
19:03:47 69022 8192000000 10265802304 10729385984 1365731712 14.289 100 11 22 2 1383985472 15729488 2524272
19:05:59 39139 8192000000 10261123216 10708578304 1350756160 14.154 99 27 55 1 1365610672 8603728 6250784
19:08:02 36233 8192000000 10283701968 10726469632 1365080096 14.283 99 28 56 0 1379128800 8036288 6012416
19:09:44 56152 8192000000 10275009360 10731220992 1329376560 13.962 96 23 46 1 1344406000 9465392 5564048
19:11:52 45246 8192000000 10281316768 10733109248 1340720880 14.064 97 20 40 0 1355458224 10435424 4301920
19:13:47 108336 8192000000 10313231424 10753617920 1359710160 14.235 98 10 20 0 1374003488 12175888 2117440
19:16:56 125768 8192000000 10301822960 10762838016 1336059184 14.022 96 3 6 0 1355804720 19111312 634224
19:20:02 80805 8192000000 10316468368 10755936256 1394596640 14.547 100 17 35 0 1414681600 16008832 4076128
19:22:48 48673 8192000000 10295594480 10756362240 1367512960 14.305 98 12 24 1 1383895856 13327008 3055888
19:24:36 44880 8192000000 10284674160 10741768192 1367001760 14.300 98 9 18 1 1382009360 12975008 2032592
19:26:36 91761 8192000000 10282401184 10738487296 1379423120 14.411 100 21 42 1 1395099664 11144464 4532080
19:29:30 50105 8192000000 10252804464 10699235328 1291333712 13.616 95 16 32 0 1304917984 9983504 3600768
19:31:33 50795 8192000000 10257784400 10702135296 1315514480 13.836 97 32 64 0 1337267936 14324048 7429408
19:33:24 44668 8192000000 10271070304 10709778432 1332470432 13.989 99 28 56 1 1353269472 14295696 6503344
19:35:12 101195 8192000000 10265449760 10701430784 1252472368 13.261 92 19 38 1 1271758016 15382848 3902800
19:37:56 31216 8192000000 9436294496 9748209664 573360608 6.541 43 16 29 1 586517648 9805600 3351440

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 10-06-2022 19:39:24 to 10-06-2022 19:39:38.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 178 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 10-06-2022 19:39:39 to 10-06-2022 19:39:41.

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          244278
MON$OLDEST_ACTIVE               244604
MON$OLDEST_SNAPSHOT             244604
MON$NEXT_TRANSACTION            244604
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                       2023296
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33602 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33602 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33602 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33602 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 ---
:335: 
:336: mscs-test-161.budzdorov.ru    Fri Jun 10 19:38:36 2022
:337:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:338: 
:339: 
:340: mscs-test-161.budzdorov.ru    Fri Jun 10 19:38:37 2022
:341:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:342: 
:343: 
:344: mscs-test-161.budzdorov.ru    Fri Jun 10 19:38:38 2022
:345:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:346: 
:347: 
:348: mscs-test-161.budzdorov.ru    Fri Jun 10 19:38:39 2022
:349:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:350: 
--- end of diff output ---

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

10.06.22 19:39:42. 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-06-10 19:39:45 No severe PSQL-related problems occured
10.06.22 19:39:46. End of report.