Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 21.05.2022 16:40
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 16455904 kB
MemAvailable 31449220 kB
Buffers 0 kB
Cached 15287372 kB
SwapCached 2588 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-05-21 16:38:18
DTS_END                         2022-05-21 16:38:18.3520
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(131:22) ==> SP_CANCEL_ADDING_INVOICE(33:5) ==> SP_ADD_INVOICE_TO_STOCK(69:14) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/50824
TRN_ID                          246937
ATT_ID                          734
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: 9515.94 20603 1141913
customer order: creation 182.16 375 21859
customer order: refuse 15.89 361 1907
order to supplier: creation 48.60 1307 5832
order to supplier: removal 3.52 97595 422
invoice (draft): creation 47.03 3152 5644
invoice (draft): removal 53.13 779 6375
invoice accept: apply 43.73 18793 5248
invoice accept: cancel 47.83 798 5740
customer reserve: creation 4070.98 186 488518
customer reserve: removal 4694.75 57 563370
realization accept: apply 88.42 196 10610
realization accept: cancel 83.48 124 10018
payment from customer: creation 62.23 124 7467
payment from customer: removal 6.45 91 774
payment to supplier: creation 51.21 133 6145
payment to supplier: removal 3.79 71 455
service: total inventory turnovers 5.07 1172 608
service: total monetary turnovers 6.73 32 807
service: aggregate perf. data 0.92 17934 110
service: refresh index statistics 0.03 268784 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 21-05-2022 16:40:23 to 21-05-2022 16:40:26.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5345
TEST_TIME 2 5514
TEST_TIME 3 4983
TEST_TIME 4 4852
TEST_TIME 5 4533
TEST_TIME 6 4811
TEST_TIME 7 5003
TEST_TIME 8 5117
TEST_TIME 9 5461
TEST_TIME 10 5769
TEST_TIME 11 6384
TEST_TIME 12 6746
TEST_TIME 13 6686
TEST_TIME 14 6909
TEST_TIME 15 7047
TEST_TIME 16 7492
TEST_TIME 17 7585
TEST_TIME 18 8202
TEST_TIME 19 8541
TEST_TIME 20 8928
TEST_TIME 21 9122
TEST_TIME 22 9163
TEST_TIME 23 9925
TEST_TIME 24 9946
TEST_TIME 25 10259
TEST_TIME 26 10752
TEST_TIME 27 10721
TEST_TIME 28 11073
TEST_TIME 29 11128
TEST_TIME 30 10968
TEST_TIME 31 10959
TEST_TIME 32 10888
TEST_TIME 33 10844
TEST_TIME 34 11009
TEST_TIME 35 10886
TEST_TIME 36 10707
TEST_TIME 37 10444
TEST_TIME 38 10462
TEST_TIME 39 10642
TEST_TIME 40 10449
TEST_TIME 41 10351
TEST_TIME 42 10305
TEST_TIME 43 10257
TEST_TIME 44 10358
TEST_TIME 45 10323
TEST_TIME 46 10289
TEST_TIME 47 10318
TEST_TIME 48 10246
TEST_TIME 49 10074
TEST_TIME 50 9947
TEST_TIME 51 9986
TEST_TIME 52 9882
TEST_TIME 53 9902
TEST_TIME 54 9927
TEST_TIME 55 9884
TEST_TIME 56 9954
TEST_TIME 57 9953
TEST_TIME 58 9963
TEST_TIME 59 9910
TEST_TIME 60 9947
TEST_TIME 61 10139
TEST_TIME 62 10120
TEST_TIME 63 10130
TEST_TIME 64 10118
TEST_TIME 65 10209
TEST_TIME 66 10240
TEST_TIME 67 10162
TEST_TIME 68 10141
TEST_TIME 69 10081
TEST_TIME 70 9995
TEST_TIME 71 9989
TEST_TIME 72 9935
TEST_TIME 73 9932
TEST_TIME 74 9894
TEST_TIME 75 9797
TEST_TIME 76 9828
TEST_TIME 77 9829
TEST_TIME 78 9777
TEST_TIME 79 9824
TEST_TIME 80 9822
TEST_TIME 81 9841
TEST_TIME 82 9851
TEST_TIME 83 9868
TEST_TIME 84 9806
TEST_TIME 85 9856
TEST_TIME 86 9846
TEST_TIME 87 9912
TEST_TIME 88 9944
TEST_TIME 89 9964
TEST_TIME 90 9896
TEST_TIME 91 9864
TEST_TIME 92 9794
TEST_TIME 93 9744
TEST_TIME 94 9705
TEST_TIME 95 9706
TEST_TIME 96 9744
TEST_TIME 97 9755
TEST_TIME 98 9781
TEST_TIME 99 9780
TEST_TIME 100 9761
TEST_TIME 101 9756
TEST_TIME 102 9756
TEST_TIME 103 9817
TEST_TIME 104 9804
TEST_TIME 105 9849
TEST_TIME 106 9814
TEST_TIME 107 9799
TEST_TIME 108 9930
TEST_TIME 109 9870
TEST_TIME 110 9789
TEST_TIME 111 9740
TEST_TIME 112 9699
TEST_TIME 113 9719
TEST_TIME 114 9666
TEST_TIME 115 9642
TEST_TIME 116 9627
TEST_TIME 117 9638
TEST_TIME 118 9603
TEST_TIME 119 9586
TEST_TIME 120 9521

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 21-05-2022 16:40:27 to 21-05-2022 16:40:30.

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 1593413 1593413 0 0.00 0 30902 7 0 0
doc_list_biud 1593411 1593411 0 0.00 0 43768 26 0 0
sp_add_invoice_to_stock 5348 5248 100 1.86 33 163668 18793 0 100
sp_cancel_adding_invoice 5740 5740 0 0.00 2 74895 798 0 0
sp_cancel_client_order 1907 1907 0 0.00 4 11513 361 0 0
sp_cancel_customer_reserve 563370 563370 0 0.00 1 26396 58 0 0
sp_cancel_pay_from_customer 774 774 0 0.00 2 7387 92 0 0
sp_cancel_pay_to_supplier 455 455 0 0.00 3 2138 71 0 0
sp_cancel_supplier_invoice 6382 6375 7 0.10 1 43840 780 0 7
sp_cancel_supplier_order 422 422 0 0.00 6 979358 97596 0 0
sp_cancel_write_off 10018 10018 0 0.00 1 37513 124 0 0
sp_client_order 21859 21859 0 0.00 3 55883 376 0 0
sp_customer_reserve 488518 488518 0 0.00 0 47539 186 0 0
sp_fill_shopping_cart 33331 33331 0 0.00 1 17973 36 0 0
sp_fill_shopping_cart_clo_res 488513 488513 0 0.00 0 18403 14 0 0
sp_get_clo_for_invoice 5241 5241 0 0.00 0 29425 273 0 0
sp_kill_cost_storno 16987 16987 0 0.00 0 9750 56 0 0
sp_kill_qstorno_ret_qs2qd 1143555 1143555 0 0.00 0 43766 31 0 0
sp_kill_qty_storno 603681 603681 0 0.00 0 43767 69 0 0
sp_lock_selected_doc 21957 21957 0 0.00 0 8166 6 0 0
sp_make_cost_storno 29463 29463 0 0.00 0 22089 54 0 0
sp_multiply_rows_for_pdistr 29462 29462 0 0.00 0 12352 32 0 0
sp_multiply_rows_for_qdistr 21859 21859 0 0.00 0 13024 172 0 0
sp_pay_from_customer 7467 7467 0 0.00 4 14178 124 0 0
sp_pay_to_supplier 6145 6145 0 0.00 4 22267 134 0 0
sp_payment_common 13612 13612 0 0.00 2 15496 38 0 0
sp_qd_handle_on_cancel_clo 1907 1907 0 0.00 0 11504 337 0 0
sp_qd_handle_on_invoice_upd_sts 10979 10979 0 0.00 0 21320 333 0 0
sp_qd_handle_on_reserve_upd_sts 10610 10610 0 0.00 0 24624 72 0 0
sp_reserve_write_off 10610 10610 0 0.00 6 24639 197 0 0
sp_supplier_invoice 5644 5644 0 0.00 7 76674 3153 0 0
sp_supplier_order 5832 5832 0 0.00 5 62881 1307 0 0
srv_aggregate_perf_data 110 110 0 0.00 2750 125226 17934 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 2539 112097 44598 0 0
srv_find_qd_qs_mism 1091130 1091130 0 0.00 0 30902 6 0 0
srv_make_invnt_saldo 608 608 0 0.00 32 149021 1173 0 0
srv_make_money_saldo 807 807 0 0.00 0 8952 32 0 0
srv_recalc_idx_stat 4 4 0 0.00 159299 429107 268785 0 0
t$perf-abend:sp_add_invoice_to_stock 100 100 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-norm:sp_add_invoice_to_stock 5249 5249 0 0.00 0 18860 187 0 0
t$perf-norm:sp_cancel_adding_invoice 448 448 0 0.00 0 10299 140 0 0
t$perf-norm:sp_cancel_client_order 1907 1907 0 0.00 0 6350 13 0 0
t$perf-norm:sp_cancel_customer_reserve 721 721 0 0.00 0 4259 20 0 0
t$perf-norm:sp_cancel_pay_from_customer 774 774 0 0.00 0 2318 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 455 455 0 0.00 0 2461 25 0 0
t$perf-norm:sp_cancel_supplier_invoice 402 402 0 0.00 0 793 14 0 0
t$perf-norm:sp_cancel_supplier_order 422 422 0 0.00 0 27536 1576 0 0
t$perf-norm:sp_cancel_write_off 979 979 0 0.00 0 916 6 0 0
t$perf-norm:sp_client_order 21859 21859 0 0.00 0 18636 19 0 0
t$perf-norm:sp_customer_reserve 1079 1079 0 0.00 0 4399 11 0 0
t$perf-norm:sp_pay_from_customer 7467 7467 0 0.00 0 13551 14 0 0
t$perf-norm:sp_pay_to_supplier 6145 6145 0 0.00 0 6751 13 0 0
t$perf-norm:sp_reserve_write_off 10610 10610 0 0.00 0 5911 16 0 0
t$perf-norm:sp_supplier_invoice 5644 5644 0 0.00 0 9862 18 0 0
t$perf-norm:sp_supplier_order 5832 5832 0 0.00 0 6339 16 0 0
t$perf-norm:srv_aggregate_perf_data 110 110 0 0.00 0 8196 82 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 360 7 0 0
t$perf-norm:srv_make_invnt_saldo 608 608 0 0.00 0 6129 32 0 0
t$perf-norm:srv_make_money_saldo 807 807 0 0.00 0 1023 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 3 0 0
v_add_invoice_to_stock 10678 10478 200 1.87 0 11684 17 0 200
v_all_customers 39548 39548 0 0.00 0 13 0 0 0
v_all_suppliers 31742 31742 0 0.00 0 20 1 0 0
v_all_wares 43718 43718 0 0.00 0 21 1 0 0
v_cancel_adding_invoice 896 896 0 0.00 0 5774 16 0 0
v_cancel_client_order 3814 3814 0 0.00 0 2233 5 0 0
v_cancel_customer_prepayment 1548 1548 0 0.00 0 2796 7 0 0
v_cancel_customer_reserve 1442 1442 0 0.00 0 1270 4 0 0
v_cancel_payment_to_supplier 910 910 0 0.00 0 558 4 0 0
v_cancel_supplier_invoice 818 804 14 1.71 0 3712 20 0 14
v_cancel_supplier_order 844 844 0 0.00 0 820 3 0 0
v_cancel_write_off 1958 1958 0 0.00 0 2754 12 0 0
v_max_id_clo_ord 5830 5830 0 0.00 0 2270 2 0 0
v_max_id_clo_res 1079 1079 0 0.00 0 1998 15 0 0
v_max_id_ord_sup 5642 5642 0 0.00 0 4841 5 0 0
v_max_non_paid_invoice 1746 1746 0 0.00 0 4334 6 0 0
v_max_non_paid_realizn 4114 4114 0 0.00 0 11735 9 0 0
v_min_id_clo_ord 5830 5830 0 0.00 0 2076 5 0 0
v_min_id_clo_res 1079 1079 0 0.00 0 4705 8 0 0
v_min_id_ord_sup 5642 5642 0 0.00 0 6488 9 0 0
v_min_non_paid_invoice 6145 6145 0 0.00 0 15478 27 0 0
v_min_non_paid_realizn 7467 7467 0 0.00 0 11487 27 0 0
v_our_firm 10876 10876 0 0.00 0 15 1 0 0
v_reserve_write_off 21220 21220 0 0.00 0 6711 6 0 0
x_lock_depdocs_on_canc_invoice 11713 11713 0 0.00 0 26487 111 0 0
x_lock_depdocs_on_canc_sup_ord 422 422 0 0.00 0 9748 362 0 0
x_make_qstorno_1000_1200 5832 5832 0 0.00 1 62875 1227 0 0
x_make_qstorno_1200_2000 5644 5644 0 0.00 3 76659 3025 0 0
x_make_qstorno_2100_3300 488518 488518 0 0.00 0 47536 170 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 21-05-2022 16:40:31 to 21-05-2022 16:40:36.

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:22 16 8192000000 8802565808 8815218688 8005184 0.097 4 1 2 0 8107440 59472 42784
14:09:23 34551 8192000000 9928499488 10239483904 998029152 10.859 100 91 182 0 1028049968 6094224 23926592
14:11:04 20159 8192000000 9959404656 10282622976 1037448448 11.240 100 91 182 0 1069920720 8407616 24064656
14:12:27 12262 8192000000 9990506528 10322763776 1073280544 11.583 100 85 170 2 1104523136 10172688 21069904
14:13:54 13221 8192000000 10063374768 10414358528 1141006256 12.225 100 74 148 0 1169182640 10208320 17968064
14:15:11 10056 8192000000 10102414240 10464481280 1186190960 12.648 100 83 164 1 1218216480 12647216 19378304
14:16:25 3781 8192000000 10147926192 10519089152 1234256832 13.093 100 80 161 0 1263949184 11597664 18094688
14:17:31 2378 8192000000 10180389584 10558218240 1263452560 13.362 100 90 180 2 1296789248 13434720 19901968
14:18:36 1730 8192000000 10206503744 10589417472 1293476208 13.636 100 88 176 1 1326744912 13093280 20175424
14:19:39 6266 8192000000 10219388048 10604904448 1304537232 13.736 100 82 163 2 1334694752 11663184 18494336
14:20:46 7768 8192000000 10203496176 10612793344 1314216464 13.824 100 43 84 1 1332143472 8987440 8939568
14:21:55 10516 8192000000 10197900384 10619146240 1318506240 13.863 100 16 30 2 1328195152 6059792 3629120
14:23:06 38090 8192000000 10226198592 10642055168 1328131216 13.950 100 48 95 1 1345927008 7110352 10685440
14:24:46 4286 8192000000 10225415104 10641776640 1331771264 13.983 100 22 44 0 1342899440 5866880 5261296
14:25:53 8173 8192000000 10222930368 10645237760 1334028912 14.004 100 19 38 1 1343894000 5652016 4213072
14:27:03 49071 8192000000 10210828080 10629386240 1303260032 13.725 98 20 39 1 1312842688 4827104 4755552
14:29:11 7620 8192000000 10162873280 10571575296 1235841888 13.108 96 23 46 0 1247067328 6041328 5184112
14:30:19 12799 8192000000 10123764304 10518650880 1127733104 12.100 89 14 28 1 1135667424 4621280 3313040
14:31:34 64179 8192000000 10157092592 10568347648 1239718768 13.144 98 30 60 1 1255103408 9396208 5988432
14:33:41 7557 8192000000 10165037424 10575130624 1265970608 13.385 99 15 30 0 1275105760 5896528 3238624
14:34:50 9501 8192000000 10192600800 10602606592 1293177792 13.633 100 24 49 0 1308350464 9732064 5440608
14:36:03 51249 8192000000 10204913696 10608529408 1302498384 13.718 100 30 58 2 1313382592 4783120 6101088
14:38:04 8680 8192000000 10213573104 10625314816 1316720960 13.847 100 31 62 2 1329896240 6252976 6922304
14:39:22 14640 8192000000 10246999024 10656882688 1339487200 14.053 100 56 111 2 1362358688 11115424 11756064
14:40:45 28094 8192000000 10269736560 10689884160 1364105888 14.274 100 42 83 1 1386544512 12670784 9767840
14:42:19 17788 8192000000 10298048128 10712719360 1376401760 14.384 100 56 112 0 1408903664 19466144 13035760
14:43:38 25970 8192000000 10300271632 10723168256 1384728928 14.459 100 53 105 3 1415581216 18028896 12823392
14:45:22 9549 8192000000 10317911312 10742059008 1399076768 14.587 100 49 96 1 1430717696 20533424 11107504
14:46:41 11969 8192000000 10334687168 10759950336 1397533104 14.573 99 60 120 1 1436623440 24379008 14711328
14:47:58 15544 8192000000 10342242112 10774691840 1412929104 14.710 100 46 89 1 1451471648 27120240 11422304
14:49:17 4248 8192000000 10354350576 10769645568 1408614768 14.672 100 69 138 2 1455098560 29397824 17085968
14:50:25 3299 8192000000 10358282832 10771664896 1412841552 14.709 100 68 136 1 1462849088 33823088 16184448
14:51:35 6743 8192000000 10332903008 10750767104 1390582304 14.511 99 55 108 1 1434898352 30838896 13477152
14:52:48 2648 8192000000 10351984128 10759307264 1397781776 14.575 100 80 158 1 1453694928 35834992 20078160
14:53:55 2537 8192000000 10366576224 10772824064 1405090208 14.640 100 87 174 1 1469338880 42305776 21942896
14:55:03 5883 8192000000 10339203712 10749870080 1386557680 14.475 100 73 146 2 1444461856 39031312 18872864
14:56:15 5400 8192000000 10351882336 10762489856 1384368800 14.456 99 74 146 1 1446714192 43950752 18394640
14:57:22 10187 8192000000 10334132160 10755063808 1383584944 14.449 100 45 90 0 1441454320 45443808 12425568
14:58:38 13915 8192000000 10318587264 10745724928 1379065840 14.408 100 38 76 2 1431000800 41456064 10478896
14:59:56 14344 8192000000 10313266704 10742710272 1369817056 14.325 100 27 54 0 1419137296 41212896 8107344
15:01:21 21324 8192000000 10295157888 10725662720 1363892064 14.272 100 27 54 1 1414087392 42818208 7377120
15:02:48 13691 8192000000 10281164448 10706993152 1317735616 13.856 97 30 60 1 1363032624 37405568 7891440
15:04:19 36967 8192000000 10277202512 10723180544 1346879984 14.119 99 19 38 1 1380933248 29072896 4980368
15:06:02 28682 8192000000 10269259568 10712977408 1350388128 14.151 99 24 48 1 1382617280 24772160 7456992
15:07:34 69627 8192000000 10277876304 10709684224 1320333552 13.880 97 30 60 0 1336520928 9557504 6629872
15:09:51 82125 8192000000 10299494064 10737049600 1352111968 14.166 98 17 33 0 1363812304 8663536 3036800
15:12:16 112097 8192000000 10294429776 10732220416 1356053920 14.202 98 11 22 1 1371129664 12295888 2779856
15:15:09 75191 8192000000 10320041088 10764369920 1395199904 14.552 100 31 62 2 1411684000 8661680 7822416
15:17:34 73283 8192000000 10295949696 10757025792 1369424336 14.322 98 28 56 1 1391797600 15443936 6929328
15:20:02 25967 8192000000 10309878928 10762248192 1399622096 14.592 100 39 78 0 1417936016 9565488 8748432
15:21:41 24516 8192000000 10316929984 10763329536 1391946704 14.523 99 49 98 0 1414473120 11140912 11385504
15:23:18 43772 8192000000 10295357456 10759503872 1400003456 14.595 100 27 54 1 1415554016 8194480 7356080
15:25:03 30998 8192000000 10307411952 10771030016 1407029088 14.658 100 8 16 0 1417452336 7934544 2488704
15:27:08 50523 8192000000 10298334880 10765893632 1400691728 14.601 100 12 24 1 1414667232 11381552 2593952
15:29:03 27925 8192000000 10289817968 10754441216 1375496224 14.376 99 20 40 0 1392658768 12884336 4278208
15:30:32 75840 8192000000 10282012864 10753843200 1350553312 14.152 97 9 18 0 1364067968 11688448 1826208
15:32:52 39161 8192000000 10311022976 10753183744 1378644688 14.404 99 33 66 0 1395158896 8872512 7641696
15:34:41 62184 8192000000 10258286144 10709245952 1302344848 13.717 94 13 26 0 1312267376 7199920 2722608
15:36:46 38355 8192000000 10298542240 10739871744 1347948048 14.129 98 25 50 0 1368397952 14750192 5699712
15:38:27 62327 8192000000 10280979520 10747805696 1356857504 14.209 99 16 32 1 1386855184 26208368 3789312
15:40:38 49205 8192000000 10264378240 10706452480 1287651248 13.583 94 39 78 2 1310651216 13892384 9107584
15:42:31 77667 8192000000 10283198368 10742501376 1348912896 14.138 99 13 26 0 1368297552 16639776 2744880
15:44:49 71906 8192000000 10272940624 10719227904 1337539792 14.035 98 35 70 0 1363559632 18015536 8004304
15:47:17 62019 8192000000 10257822768 10711830528 1323667712 13.910 97 13 26 0 1335885328 9779936 2437680
15:49:21 59046 8192000000 10238492880 10691686400 1313221296 13.815 97 23 46 0 1330440720 12201744 5017680
15:51:26 58844 8192000000 10254716224 10706374656 1353132288 14.176 100 17 34 0 1364739536 7461760 4145488
15:53:25 53416 8192000000 10255040800 10716712960 1352798832 14.173 99 37 74 1 1370922080 9344544 8778704
15:55:28 63331 8192000000 10261474880 10720522240 1346035488 14.112 99 14 28 0 1358716880 9613776 3067616
15:57:38 41828 8192000000 10262878944 10718908416 1345119488 14.104 98 24 48 0 1359380384 8591296 5669600
15:59:22 59536 8192000000 10276609088 10741415936 1354019600 14.184 99 14 28 0 1367520416 10496528 3004288
16:01:32 54813 8192000000 10292837920 10735828992 1336978272 14.030 97 19 38 2 1350029456 8678176 4373008
16:03:29 17800 8192000000 10292419856 10741137408 1379472544 14.412 100 31 62 0 1395767168 9808928 6485696
16:04:51 91880 8192000000 10296539984 10769330176 1373176560 14.355 99 19 38 1 1392173664 14763072 4234032
16:07:23 57769 8192000000 10295835504 10751766528 1360808624 14.245 98 30 61 4 1382679824 15300336 6570864
16:09:26 100875 8192000000 10304306272 10758975488 1359688800 14.235 97 18 36 2 1371674688 7712512 4273376
16:12:19 83001 8192000000 10297137376 10766888960 1389867920 14.505 100 19 38 1 1412890688 18572864 4449904
16:14:45 35586 8192000000 10295905488 10753548288 1367171728 14.302 98 23 46 1 1381128640 8126976 5829936
16:16:26 47775 8192000000 10280401616 10742706176 1351769824 14.163 97 27 53 0 1371927456 13676144 6481488
16:18:17 66268 8192000000 10286409264 10730741760 1296767456 13.666 94 30 60 1 1317422688 14034240 6620992
16:20:28 87725 8192000000 10257346128 10712907776 1259570928 13.326 92 11 22 0 1278350080 16562384 2216768
16:23:07 39395 8192000000 10258352784 10708824064 1326650128 13.937 99 27 54 0 1344494608 11880704 5963776
16:24:55 22576 8192000000 10262878000 10706481152 1340855280 14.065 100 26 52 0 1356477776 9846000 5776496
16:26:20 54214 8192000000 10262897344 10733490176 1333323680 13.997 99 10 20 0 1355670448 19893680 2453088
16:28:19 88414 8192000000 10255816464 10697191424 1304858624 13.739 96 23 46 0 1317665856 8102304 4704928
16:31:02 80978 8192000000 10258747152 10730086400 1363711152 14.271 100 9 18 1 1377414544 11605824 2097568
16:33:56 81037 8192000000 10266316528 10730463232 1349892048 14.147 98 12 24 0 1364028704 10754752 3381904
16:36:18 60022 8192000000 10243409344 10699309056 1318058720 13.859 96 18 36 3 1332751712 10365120 4327872

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 21-05-2022 16:40:37 to 21-05-2022 16:40:52.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 100 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 21-05-2022 16:40:53 to 21-05-2022 16:40:55.

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          246469
MON$OLDEST_ACTIVE               248939
MON$OLDEST_SNAPSHOT             248939
MON$NEXT_TRANSACTION            248939
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                       2035552
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.33593 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33593 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33593 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33593 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 ---
:114: 
:115: mscs-test-161.budzdorov.ru    Sat May 21 16:38:40 2022
:116:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:117: 
--- end of diff output ---

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

21.05.22 16:40:56. 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-05-21 16:40:58 No severe PSQL-related problems occured
21.05.22 16:41:00. End of report.