Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 19.09.2021 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 58e1ddde69c44dad93d327777e5becec
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-05-09 08:45
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 4793.92
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 15902668 kB
MemAvailable 30826996 kB
Buffers 0 kB
Cached 15803032 kB
SwapCached 488 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.8
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 2021-09-19 22:37:51
DTS_END                         2021-09-19 22:37:51.1690
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(127:13) ==> X_MAKE_QSTORNO_2100_3300(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/49560
TRN_ID                          249623
ATT_ID                          708
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: 9509.88 19824 1141186
customer order: creation 181.15 346 21738
customer order: refuse 15.87 408 1904
order to supplier: creation 49.73 1394 5967
order to supplier: removal 3.48 97247 418
invoice (draft): creation 48.25 3235 5790
invoice (draft): removal 53.69 864 6443
invoice accept: apply 44.38 16704 5325
invoice accept: cancel 48.06 793 5767
customer reserve: creation 4041.95 167 485034
customer reserve: removal 4714.23 56 565707
realization accept: apply 88.71 203 10645
realization accept: cancel 83.57 109 10028
payment from customer: creation 63.43 137 7612
payment from customer: removal 6.25 120 750
payment to supplier: creation 51.08 158 6129
payment to supplier: removal 3.70 77 444
service: total inventory turnovers 4.89 1109 587
service: total monetary turnovers 6.53 44 784
service: aggregate perf. data 0.92 16576 110
service: refresh index statistics 0.03 256732 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 19-09-2021 22:39:38 to 19-09-2021 22:39:40.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4372
TEST_TIME 2 3024
TEST_TIME 3 3283
TEST_TIME 4 3854
TEST_TIME 5 3922
TEST_TIME 6 3697
TEST_TIME 7 4097
TEST_TIME 8 4280
TEST_TIME 9 4396
TEST_TIME 10 4463
TEST_TIME 11 4948
TEST_TIME 12 5209
TEST_TIME 13 5222
TEST_TIME 14 5761
TEST_TIME 15 6098
TEST_TIME 16 6250
TEST_TIME 17 6533
TEST_TIME 18 6641
TEST_TIME 19 7018
TEST_TIME 20 7255
TEST_TIME 21 7883
TEST_TIME 22 8225
TEST_TIME 23 8317
TEST_TIME 24 8580
TEST_TIME 25 8787
TEST_TIME 26 8752
TEST_TIME 27 8988
TEST_TIME 28 9771
TEST_TIME 29 10576
TEST_TIME 30 10903
TEST_TIME 31 10902
TEST_TIME 32 10859
TEST_TIME 33 10627
TEST_TIME 34 10735
TEST_TIME 35 10632
TEST_TIME 36 10481
TEST_TIME 37 10300
TEST_TIME 38 10229
TEST_TIME 39 10242
TEST_TIME 40 10213
TEST_TIME 41 10175
TEST_TIME 42 10079
TEST_TIME 43 10104
TEST_TIME 44 10031
TEST_TIME 45 9995
TEST_TIME 46 9994
TEST_TIME 47 9998
TEST_TIME 48 9981
TEST_TIME 49 9885
TEST_TIME 50 9786
TEST_TIME 51 9654
TEST_TIME 52 9639
TEST_TIME 53 9744
TEST_TIME 54 9745
TEST_TIME 55 9738
TEST_TIME 56 9705
TEST_TIME 57 9745
TEST_TIME 58 9719
TEST_TIME 59 9731
TEST_TIME 60 9697
TEST_TIME 61 9777
TEST_TIME 62 9905
TEST_TIME 63 9950
TEST_TIME 64 9949
TEST_TIME 65 10066
TEST_TIME 66 9987
TEST_TIME 67 10057
TEST_TIME 68 10036
TEST_TIME 69 9978
TEST_TIME 70 9844
TEST_TIME 71 9845
TEST_TIME 72 9893
TEST_TIME 73 9878
TEST_TIME 74 9855
TEST_TIME 75 9821
TEST_TIME 76 9763
TEST_TIME 77 9768
TEST_TIME 78 9716
TEST_TIME 79 9655
TEST_TIME 80 9662
TEST_TIME 81 9627
TEST_TIME 82 9662
TEST_TIME 83 9621
TEST_TIME 84 9656
TEST_TIME 85 9663
TEST_TIME 86 9678
TEST_TIME 87 9712
TEST_TIME 88 9703
TEST_TIME 89 9664
TEST_TIME 90 9711
TEST_TIME 91 9822
TEST_TIME 92 9846
TEST_TIME 93 9813
TEST_TIME 94 9757
TEST_TIME 95 9688
TEST_TIME 96 9709
TEST_TIME 97 9691
TEST_TIME 98 9641
TEST_TIME 99 9665
TEST_TIME 100 9630
TEST_TIME 101 9645
TEST_TIME 102 9620
TEST_TIME 103 9600
TEST_TIME 104 9649
TEST_TIME 105 9608
TEST_TIME 106 9633
TEST_TIME 107 9619
TEST_TIME 108 9611
TEST_TIME 109 9587
TEST_TIME 110 9677
TEST_TIME 111 9648
TEST_TIME 112 9686
TEST_TIME 113 9654
TEST_TIME 114 9592
TEST_TIME 115 9602
TEST_TIME 116 9554
TEST_TIME 117 9500
TEST_TIME 118 9564
TEST_TIME 119 9541
TEST_TIME 120 9513

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 19-09-2021 22:39:42 to 19-09-2021 22:39:45.

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 1590673 1590673 0 0.00 0 20365 7 0 0
doc_list_biud 1590673 1590673 0 0.00 0 70791 26 0 0
sp_add_invoice_to_stock 5448 5325 123 2.25 38 176567 16704 0 123
sp_cancel_adding_invoice 5767 5767 0 0.00 4 104743 794 0 0
sp_cancel_client_order 1904 1904 0 0.00 5 11869 409 0 0
sp_cancel_customer_reserve 565707 565707 0 0.00 1 67446 56 0 0
sp_cancel_pay_from_customer 750 750 0 0.00 3 7122 121 0 0
sp_cancel_pay_to_supplier 444 444 0 0.00 3 2711 78 0 0
sp_cancel_supplier_invoice 6453 6443 10 0.15 1 70819 865 0 10
sp_cancel_supplier_order 418 418 0 0.00 8 1190154 97247 0 0
sp_cancel_write_off 10028 10028 0 0.00 1 10453 110 0 0
sp_client_order 21738 21738 0 0.00 4 23277 346 0 0
sp_customer_reserve 485034 485034 0 0.00 0 84010 167 0 0
sp_fill_shopping_cart 33491 33491 0 0.00 1 24446 41 0 0
sp_fill_shopping_cart_clo_res 485031 485031 0 0.00 0 13020 12 0 0
sp_get_clo_for_invoice 5321 5321 0 0.00 0 21019 254 0 0
sp_kill_cost_storno 16989 16989 0 0.00 0 7116 59 0 0
sp_kill_qstorno_ret_qs2qd 1148303 1148303 0 0.00 0 70783 31 0 0
sp_kill_qty_storno 606231 606231 0 0.00 0 70788 67 0 0
sp_lock_selected_doc 22140 22140 0 0.00 0 9052 6 0 0
sp_make_cost_storno 29705 29705 0 0.00 0 20347 67 0 0
sp_multiply_rows_for_pdistr 29705 29705 0 0.00 0 8883 29 0 0
sp_multiply_rows_for_qdistr 21738 21738 0 0.00 0 12534 130 0 0
sp_pay_from_customer 7612 7612 0 0.00 4 15244 137 0 0
sp_pay_to_supplier 6129 6129 0 0.00 5 18908 158 0 0
sp_payment_common 13741 13741 0 0.00 2 13408 41 0 0
sp_qd_handle_on_cancel_clo 1904 1904 0 0.00 0 11861 366 0 0
sp_qd_handle_on_invoice_upd_sts 11086 11086 0 0.00 0 27316 327 0 0
sp_qd_handle_on_reserve_upd_sts 10645 10645 0 0.00 0 19074 57 0 0
sp_reserve_write_off 10645 10645 0 0.00 6 20373 203 0 0
sp_supplier_invoice 5790 5790 0 0.00 9 71917 3235 0 0
sp_supplier_order 5967 5967 0 0.00 6 78930 1395 0 0
srv_aggregate_perf_data 110 110 0 0.00 2680 97978 16577 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1780 121089 42825 0 0
srv_find_qd_qs_mism 1090987 1090987 0 0.00 0 13914 5 0 0
srv_make_invnt_saldo 587 587 0 0.00 25 70496 1109 0 0
srv_make_money_saldo 784 784 0 0.00 1 9996 45 0 0
srv_recalc_idx_stat 4 4 0 0.00 63085 383164 256733 0 0
t$perf-abend:sp_add_invoice_to_stock 123 123 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5326 5326 0 0.00 0 7179 144 0 0
t$perf-norm:sp_cancel_adding_invoice 414 414 0 0.00 0 3368 100 0 0
t$perf-norm:sp_cancel_client_order 1904 1904 0 0.00 0 4392 15 0 0
t$perf-norm:sp_cancel_customer_reserve 694 694 0 0.00 0 669 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 750 750 0 0.00 0 3465 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 444 444 0 0.00 0 1340 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 437 437 0 0.00 0 2841 22 0 0
t$perf-norm:sp_cancel_supplier_order 418 418 0 0.00 0 46257 1615 0 0
t$perf-norm:sp_cancel_write_off 1115 1115 0 0.00 0 5338 12 0 0
t$perf-norm:sp_client_order 21738 21738 0 0.00 0 11350 18 0 0
t$perf-norm:sp_customer_reserve 1122 1122 0 0.00 0 1715 7 0 0
t$perf-norm:sp_pay_from_customer 7612 7612 0 0.00 0 5404 10 0 0
t$perf-norm:sp_pay_to_supplier 6129 6129 0 0.00 0 5453 9 0 0
t$perf-norm:sp_reserve_write_off 10645 10645 0 0.00 0 4397 9 0 0
t$perf-norm:sp_supplier_invoice 5790 5790 0 0.00 0 8039 16 0 0
t$perf-norm:sp_supplier_order 5967 5967 0 0.00 0 9245 15 0 0
t$perf-norm:srv_aggregate_perf_data 110 110 0 0.00 0 341 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 5225 82 0 0
t$perf-norm:srv_make_invnt_saldo 587 587 0 0.00 0 3646 20 0 0
t$perf-norm:srv_make_money_saldo 784 784 0 0.00 0 790 14 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 9 5 0 0
v_add_invoice_to_stock 10884 10638 246 2.26 0 8591 22 0 246
v_all_customers 39486 39486 0 0.00 0 44 1 0 0
v_all_suppliers 32094 32094 0 0.00 0 43 1 0 0
v_all_wares 43476 43476 0 0.00 0 22 1 0 0
v_cancel_adding_invoice 828 828 0 0.00 0 1972 12 0 0
v_cancel_client_order 3808 3808 0 0.00 0 5573 11 0 0
v_cancel_customer_prepayment 1500 1500 0 0.00 0 1021 5 0 0
v_cancel_customer_reserve 1388 1388 0 0.00 0 5390 9 0 0
v_cancel_payment_to_supplier 888 888 0 0.00 0 2280 12 0 0
v_cancel_supplier_invoice 894 874 20 2.23 0 13021 33 0 20
v_cancel_supplier_order 836 836 0 0.00 0 1383 5 0 0
v_cancel_write_off 2230 2230 0 0.00 0 5976 18 0 0
v_max_id_clo_ord 5966 5966 0 0.00 0 731 1 0 0
v_max_id_clo_res 1122 1122 0 0.00 0 6684 19 0 0
v_max_id_ord_sup 5787 5787 0 0.00 0 6277 7 0 0
v_max_non_paid_invoice 1835 1835 0 0.00 0 373 3 0 0
v_max_non_paid_realizn 4140 4140 0 0.00 0 4176 5 0 0
v_min_id_clo_ord 5966 5966 0 0.00 0 6990 9 0 0
v_min_id_clo_res 1122 1122 0 0.00 0 3867 6 0 0
v_min_id_ord_sup 5787 5787 0 0.00 0 4164 5 0 0
v_min_non_paid_invoice 6129 6129 0 0.00 0 13402 29 0 0
v_min_non_paid_realizn 7612 7612 0 0.00 0 13207 27 0 0
v_our_firm 10934 10934 0 0.00 0 86 1 0 0
v_reserve_write_off 21290 21290 0 0.00 0 9281 10 0 0
x_lock_depdocs_on_canc_invoice 11773 11773 0 0.00 0 13910 110 0 0
x_lock_depdocs_on_canc_sup_ord 418 418 0 0.00 0 11195 347 0 0
x_make_qstorno_1000_1200 5967 5967 0 0.00 2 78912 1300 0 0
x_make_qstorno_1200_2000 5790 5790 0 0.00 3 70566 3096 0 0
x_make_qstorno_2100_3300 485034 485034 0 0.00 0 84008 153 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 19-09-2021 22:39:46 to 19-09-2021 22:39:50.

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:07:54 11 8192000000 8803394256 8815476736 7256432 0.088 2 1 2 0 7375808 56816 62560
20:08:55 31248 8192000000 10383987856 10707845120 1428848384 14.851 100 83 166 0 1462995088 6502992 27643712
20:10:40 20131 8192000000 10457140720 10790637568 1505281472 15.522 100 86 172 0 1542117440 9381728 27454240
20:12:05 3679 8192000000 10493921728 10836733952 1551286576 15.921 100 85 170 0 1588268768 10586656 26395536
20:13:16 14673 8192000000 10570894432 10926338048 1626090000 16.562 100 80 160 2 1661852880 11257264 24505616
20:14:33 7291 8192000000 10634805248 11005169664 1700012128 17.185 100 75 150 0 1733564256 10964144 22587984
20:15:43 5101 8192000000 10719817216 11095805952 1772827744 17.790 100 82 165 0 1809589616 13734256 23027616
20:16:50 3074 8192000000 10760132096 11138572288 1815156656 18.138 100 86 171 0 1853384720 14038064 24190000
20:17:54 1658 8192000000 10795994656 11184599040 1854324000 18.457 100 87 175 1 1892887424 14164816 24398608
20:18:57 22592 8192000000 10790789264 11193843712 1862774304 18.526 100 53 104 1 1886070480 8449440 14846736
20:20:21 5512 8192000000 10802277568 11224743936 1887794656 18.728 100 35 70 0 1905773216 8242240 9736320
20:21:27 47781 8192000000 10841616816 11248889856 1903553424 18.855 100 48 98 3 1922723248 4909344 14260480
20:23:15 6385 8192000000 10827712304 11249307648 1905172688 18.868 100 29 60 1 1920465904 6966096 8327120
20:24:24 15164 8192000000 10826819424 11259494400 1915912192 18.954 100 20 40 0 1927536240 5979568 5644480
20:25:39 53681 8192000000 10866743728 11268812800 1924756784 19.025 100 60 119 2 1949217568 6435408 18025376
20:27:33 5579 8192000000 10839918160 11268980736 1921089584 18.996 100 35 68 5 1939126528 8038288 9998656
20:28:44 10179 8192000000 10791827152 11205427200 1821666848 18.191 97 24 48 0 1834469200 5884080 6918272
20:29:55 8165 8192000000 10725252144 11149701120 1739553744 17.515 94 16 32 0 1749968704 5643008 4771952
20:31:06 6579 8192000000 10693420224 11103748096 1762064656 17.701 98 34 69 0 1777539152 5245136 10229360
20:32:16 7895 8192000000 10725578720 11135021056 1775316400 17.811 98 26 52 1 1788276080 5903792 7055888
20:33:24 60936 8192000000 10750744496 11182604288 1839379616 18.336 100 25 50 1 1856121088 9415984 7325488
20:35:27 9870 8192000000 10775193360 11202916352 1862549776 18.524 100 26 52 1 1875868528 6035088 7283664
20:36:39 6070 8192000000 10799906048 11219337216 1877285744 18.643 100 35 70 0 1893396560 5842912 10267904
20:37:47 7051 8192000000 10822871328 11249537024 1907652560 18.888 100 19 39 1 1917930096 4914496 5363040
20:38:58 23899 8192000000 10864421232 11283353600 1925602560 19.032 100 47 93 2 1948197728 8252912 14342256
20:40:27 17934 8192000000 10867270272 11302354944 1941179920 19.156 100 37 69 3 1964285648 12686096 10419632
20:41:50 9073 8192000000 10900161360 11329171456 1964988784 19.346 100 45 90 2 1994732928 15709600 14034544
20:43:02 23716 8192000000 10906129728 11334873088 1964430144 19.341 100 46 92 1 1997091712 19644688 13016880
20:44:33 11015 8192000000 10918696288 11351818240 1973468544 19.413 100 48 96 0 2013385136 25828432 14088160
20:45:47 13218 8192000000 10935392240 11368308736 1984170752 19.498 100 53 105 0 2025899616 26423824 15305040
20:47:07 7622 8192000000 10959747296 11386765312 2000851136 19.629 100 56 111 2 2047027104 29217056 16958912
20:48:23 1779 8192000000 10982550544 11402973184 2010287072 19.704 100 82 164 0 2068910032 34146112 24476848
20:49:30 5380 8192000000 10957892608 11384885248 1994867888 19.582 100 67 134 0 2049149136 34346224 19935024
20:50:45 4826 8192000000 10968727120 11397320704 1998617040 19.612 99 70 139 1 2059283616 39014592 21651984
20:51:53 4377 8192000000 10950019568 11375927296 1969087472 19.378 99 73 146 0 2026774224 35290560 22396192
20:53:03 3382 8192000000 10931480288 11346305024 1939217024 19.141 98 80 156 0 2001422992 37384416 24821552
20:54:09 1947 8192000000 10971010352 11390717952 1986483248 19.516 100 75 150 0 2052040704 42597504 22959952
20:55:17 4672 8192000000 10953822304 11371974656 1968311280 19.372 100 74 140 1 2035622304 45357680 21953344
20:56:27 3556 8192000000 10940414688 11352559616 1933559264 19.095 99 76 151 2 2018202544 60297136 24346144
20:57:35 8203 8192000000 10965776592 11396513792 1969892800 19.385 100 45 90 2 2046985600 61930608 15162192
20:58:47 13922 8192000000 10942982416 11385999360 1959233904 19.300 100 34 67 1 2029352752 58991968 11126880
21:00:10 11572 8192000000 10956181184 11397308416 1976380832 19.436 100 46 92 0 2045359072 53435872 15542368
21:01:30 13287 8192000000 10927828752 11373174784 1957659760 19.287 100 35 70 1 2016371264 46869968 11841536
21:02:47 21971 8192000000 10943357024 11396632576 1971657728 19.399 100 28 56 1 2029990688 48487216 9845744
21:04:18 24918 8192000000 10929200512 11392311296 1949395024 19.222 100 21 42 0 2021478352 63548608 8534720
21:06:16 30055 8192000000 10878502032 11356549120 1925151216 19.028 99 15 30 1 1956505376 26422752 4931408
21:07:46 65844 8192000000 10903085968 11374120960 1960019648 19.306 100 18 34 0 1988160000 23648320 4492032
21:09:53 58768 8192000000 10917032592 11350601728 1940659472 19.152 98 29 60 0 1960564960 11590432 8315056
21:11:54 50767 8192000000 10918536704 11363901440 1963879424 19.337 100 10 20 0 1974481424 8138304 2463696
21:13:45 121088 8192000000 10913816672 11360751616 1955261232 19.268 99 26 52 0 1976569616 13663584 7644800
21:16:52 69203 8192000000 10913351568 11369984000 1957994272 19.290 99 33 64 1 1980936352 13464640 9477440
21:19:02 31692 8192000000 10899296896 11371048960 1961813744 19.320 99 24 48 3 1978719760 10710832 6195184
21:20:44 42039 8192000000 10921387872 11395182592 1991806192 19.558 100 15 30 1 2009223184 13174288 4242704
21:22:28 27034 8192000000 10937307056 11394367488 1984072416 19.497 99 47 93 1 2009437120 11507120 13857584
21:24:10 31551 8192000000 10920196384 11397189632 1979095600 19.458 99 19 39 0 2003103024 18700608 5306816
21:25:42 39981 8192000000 10916775504 11386793984 1985960448 19.512 100 18 36 1 2000174432 9510528 4703456
21:27:22 96393 8192000000 10911958704 11389657088 1966381200 19.357 99 10 20 0 1986565344 17050160 3133984
21:31:36 56072 8192000000 10888277472 11360014336 1937238336 19.125 98 17 33 3 1949730976 7157472 5335168
21:33:35 53915 8192000000 10885654400 11362586624 1929823520 19.065 98 22 44 3 1950093904 14343360 5927024
21:35:29 60093 8192000000 10901400224 11362385920 1932687024 19.088 98 27 53 2 1950592448 10638048 7267376
21:37:33 27588 8192000000 10908456672 11371143168 1949824912 19.225 98 36 72 3 1980268448 20209840 10233696
21:39:42 54908 8192000000 10846585632 11314913280 1809327808 18.090 92 25 50 1 1824270496 8540208 6402480
21:41:37 118662 8192000000 10851684496 11307630592 1766234480 17.736 90 22 44 1 1785984864 13619024 6131360
21:44:44 44776 8192000000 10882813056 11349323776 1918332704 18.973 98 16 32 0 1934249056 11157952 4758400
21:46:29 103828 8192000000 10879807696 11349086208 1924833984 19.026 98 31 62 0 1942625632 8200192 9591456
21:49:16 71177 8192000000 10869293344 11355082752 1893184496 18.771 97 11 22 0 1922988720 26555808 3248416
21:51:31 61021 8192000000 10845482864 11321376768 1862842128 18.526 96 15 30 1 1877013184 9741312 4429744
21:54:01 48405 8192000000 10846498816 11321585664 1904929920 18.866 98 11 22 0 1919425168 10931696 3563552
21:55:50 68638 8192000000 10864277120 11344424960 1934062384 19.099 99 6 12 0 1942041984 6512080 1467520
21:58:00 56669 8192000000 10903884032 11359383552 1950050464 19.227 99 17 31 0 1961375504 7011200 4313840
21:59:58 66286 8192000000 10885906400 11346194432 1901280832 18.837 97 18 37 0 1923665680 17029776 5355072
22:02:05 55934 8192000000 10890892752 11365560320 1939161840 19.140 99 20 41 2 1963788848 18792176 5834832
22:04:01 56722 8192000000 10894524624 11377561600 1948695440 19.216 99 31 62 0 1968348432 10924832 8728160
22:06:06 22041 8192000000 10908584320 11356581888 1948567920 19.215 99 42 84 0 1972837744 12025536 12244288
22:07:29 36636 8192000000 10914577776 11398447104 1982679472 19.486 100 31 62 1 2008945712 17672448 8593792
22:09:10 73950 8192000000 10935496656 11405312000 1980959952 19.472 99 12 24 0 1995518144 11705296 2852896
22:11:26 76197 8192000000 10916009248 11394912256 1945738096 19.193 97 13 26 0 1962908144 13404256 3765792
22:13:42 32243 8192000000 10888694480 11370434560 1960048752 19.306 99 5 10 1 1972433600 11123600 1261248
22:15:19 94493 8192000000 10911056112 11409649664 1976785056 19.439 99 8 16 0 1986743616 7840720 2117840
22:18:00 61041 8192000000 10906771600 11388030976 1910993584 18.915 96 12 24 2 1925160384 10815728 3351072
22:20:14 56646 8192000000 10890461840 11368009728 1942860208 19.170 98 40 79 1 1969899280 15149136 11889936
22:22:11 57624 8192000000 10917944784 11390713856 1959198192 19.300 99 29 58 0 1985667120 18385520 8083408
22:24:12 74973 8192000000 10885624816 11365740544 1919573760 18.983 97 18 36 1 1938824224 14040208 5210256
22:26:27 38423 8192000000 10885056944 11355668480 1921746208 19.001 98 28 56 1 1939693792 10311216 7636368
22:28:10 55371 8192000000 10899131776 11370115072 1952276368 19.245 99 18 36 1 1965477728 7799360 5402000
22:30:11 99914 8192000000 10887019264 11348525056 1891082944 18.755 96 12 24 1 1904287888 8962784 4242160
22:33:17 50924 8192000000 10860546960 11334148096 1915516464 18.951 99 24 47 0 1936162064 13675376 6970224
22:35:08 44061 8192000000 10870323424 11365945344 1944396784 19.182 100 33 66 1 1973181520 18823504 9961232
22:37:16 45457 8192000000 9521365296 9839771648 672227456 7.583 35 3 4 1 678506048 5389920 888672

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 19-09-2021 22:39:52 to 19-09-2021 22:40:07.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 123 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 19-09-2021 22:40:08 to 19-09-2021 22:40:10.

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          250159
MON$OLDEST_ACTIVE               250160
MON$OLDEST_SNAPSHOT             250160
MON$NEXT_TRANSACTION            250160
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                       2050848
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33498 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33498 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33498 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33498 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 ---
--- end of diff output ---

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

19.09.21 22:40:11. 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
2021-09-19 22:40:13 No severe PSQL-related problems occured
19.09.21 22:40:14. End of report.