Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 19.09.2021 01: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 16178068 kB
MemAvailable 30830340 kB
Buffers 0 kB
Cached 15538676 kB
SwapCached 492 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 01:37:50
DTS_END                         2021-09-19 01:37:50.4360
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/35718
TRN_ID                          236225
ATT_ID                          650
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: 8889.19 24903 1066703
customer order: creation 165.60 363 19872
customer order: refuse 13.93 351 1672
order to supplier: creation 45.23 1238 5428
order to supplier: removal 3.23 115878 388
invoice (draft): creation 45.08 4035 5409
invoice (draft): removal 50.22 890 6026
invoice accept: apply 40.13 19048 4815
invoice accept: cancel 46.07 820 5528
customer reserve: creation 3676.43 190 441172
customer reserve: removal 4513.34 64 541601
realization accept: apply 80.78 193 9693
realization accept: cancel 81.71 119 9805
payment from customer: creation 58.37 131 7004
payment from customer: removal 6.05 101 726
payment to supplier: creation 47.38 159 5686
payment to supplier: removal 3.51 88 421
service: total inventory turnovers 4.73 1202 568
service: total monetary turnovers 6.70 28 804
service: aggregate perf. data 0.68 29535 81
service: refresh index statistics 0.03 323635 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 19-09-2021 01:39:29 to 19-09-2021 01:39:31.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2242
TEST_TIME 2 2739
TEST_TIME 3 2620
TEST_TIME 4 4029
TEST_TIME 5 4284
TEST_TIME 6 4598
TEST_TIME 7 4858
TEST_TIME 8 5498
TEST_TIME 9 5932
TEST_TIME 10 6139
TEST_TIME 11 6097
TEST_TIME 12 6275
TEST_TIME 13 6245
TEST_TIME 14 6399
TEST_TIME 15 6469
TEST_TIME 16 6583
TEST_TIME 17 6722
TEST_TIME 18 6928
TEST_TIME 19 7018
TEST_TIME 20 7478
TEST_TIME 21 7687
TEST_TIME 22 7740
TEST_TIME 23 8077
TEST_TIME 24 8391
TEST_TIME 25 8279
TEST_TIME 26 8773
TEST_TIME 27 8991
TEST_TIME 28 9428
TEST_TIME 29 10414
TEST_TIME 30 10336
TEST_TIME 31 10129
TEST_TIME 32 9968
TEST_TIME 33 10036
TEST_TIME 34 9828
TEST_TIME 35 9886
TEST_TIME 36 9725
TEST_TIME 37 9810
TEST_TIME 38 9786
TEST_TIME 39 9620
TEST_TIME 40 9410
TEST_TIME 41 9297
TEST_TIME 42 9456
TEST_TIME 43 9501
TEST_TIME 44 9386
TEST_TIME 45 9248
TEST_TIME 46 9175
TEST_TIME 47 9086
TEST_TIME 48 9189
TEST_TIME 49 9055
TEST_TIME 50 8962
TEST_TIME 51 9044
TEST_TIME 52 8927
TEST_TIME 53 8850
TEST_TIME 54 8850
TEST_TIME 55 8799
TEST_TIME 56 8785
TEST_TIME 57 8759
TEST_TIME 58 8773
TEST_TIME 59 8907
TEST_TIME 60 8948
TEST_TIME 61 8979
TEST_TIME 62 9052
TEST_TIME 63 8981
TEST_TIME 64 8993
TEST_TIME 65 8976
TEST_TIME 66 9093
TEST_TIME 67 9153
TEST_TIME 68 9177
TEST_TIME 69 9204
TEST_TIME 70 9115
TEST_TIME 71 9057
TEST_TIME 72 9047
TEST_TIME 73 9064
TEST_TIME 74 9068
TEST_TIME 75 9016
TEST_TIME 76 8971
TEST_TIME 77 8933
TEST_TIME 78 8935
TEST_TIME 79 8960
TEST_TIME 80 8988
TEST_TIME 81 8934
TEST_TIME 82 8933
TEST_TIME 83 8980
TEST_TIME 84 9028
TEST_TIME 85 9067
TEST_TIME 86 9141
TEST_TIME 87 9177
TEST_TIME 88 9152
TEST_TIME 89 9200
TEST_TIME 90 9189
TEST_TIME 91 9163
TEST_TIME 92 9139
TEST_TIME 93 9077
TEST_TIME 94 9043
TEST_TIME 95 8997
TEST_TIME 96 8954
TEST_TIME 97 8932
TEST_TIME 98 8960
TEST_TIME 99 8946
TEST_TIME 100 8910
TEST_TIME 101 8927
TEST_TIME 102 8932
TEST_TIME 103 8961
TEST_TIME 104 8943
TEST_TIME 105 8938
TEST_TIME 106 8955
TEST_TIME 107 8974
TEST_TIME 108 8970
TEST_TIME 109 8979
TEST_TIME 110 8995
TEST_TIME 111 9008
TEST_TIME 112 9068
TEST_TIME 113 9028
TEST_TIME 114 8983
TEST_TIME 115 8959
TEST_TIME 116 8917
TEST_TIME 117 8873
TEST_TIME 118 8867
TEST_TIME 119 8842
TEST_TIME 120 8895

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 19-09-2021 01:39:32 to 19-09-2021 01:39:36.

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 1477415 1477415 0 0.00 0 30971 7 0 0
doc_list_biud 1477415 1477415 0 0.00 0 49264 30 0 0
sp_add_invoice_to_stock 4930 4815 115 2.33 29 195353 19048 0 115
sp_cancel_adding_invoice 5528 5528 0 0.00 4 58264 820 0 0
sp_cancel_client_order 1672 1672 0 0.00 4 12781 352 0 0
sp_cancel_customer_reserve 541601 541601 0 0.00 1 49266 64 0 0
sp_cancel_pay_from_customer 726 726 0 0.00 3 10150 101 0 0
sp_cancel_pay_to_supplier 421 421 0 0.00 3 5967 88 0 0
sp_cancel_supplier_invoice 6031 6026 5 0.08 1 45948 891 0 5
sp_cancel_supplier_order 388 388 0 0.00 7 1207891 115878 0 0
sp_cancel_write_off 9805 9805 0 0.00 1 17138 119 0 0
sp_client_order 19872 19872 0 0.00 3 35298 363 0 0
sp_customer_reserve 441172 441172 0 0.00 0 87647 190 0 0
sp_fill_shopping_cart 30707 30707 0 0.00 1 30728 48 0 0
sp_fill_shopping_cart_clo_res 441168 441168 0 0.00 0 18112 14 0 0
sp_get_clo_for_invoice 4811 4811 0 0.00 0 31985 337 0 0
sp_kill_cost_storno 16480 16480 0 0.00 0 14239 61 0 0
sp_kill_qstorno_ret_qs2qd 1099421 1099421 0 0.00 0 49256 35 0 0
sp_kill_qty_storno 579524 579524 0 0.00 0 49264 75 0 0
sp_lock_selected_doc 20084 20084 0 0.00 0 5095 3 0 0
sp_make_cost_storno 27194 27194 0 0.00 0 16952 56 0 0
sp_multiply_rows_for_pdistr 27194 27194 0 0.00 0 15421 33 0 0
sp_multiply_rows_for_qdistr 19872 19872 0 0.00 0 24097 159 0 0
sp_pay_from_customer 7004 7004 0 0.00 4 20120 131 0 0
sp_pay_to_supplier 5686 5686 0 0.00 4 22987 160 0 0
sp_payment_common 12690 12690 0 0.00 2 10330 35 0 0
sp_qd_handle_on_cancel_clo 1672 1672 0 0.00 0 12776 329 0 0
sp_qd_handle_on_invoice_upd_sts 10339 10339 0 0.00 0 30422 347 0 0
sp_qd_handle_on_reserve_upd_sts 9693 9693 0 0.00 0 10930 57 0 0
sp_reserve_write_off 9693 9693 0 0.00 6 20218 194 0 0
sp_supplier_invoice 5409 5409 0 0.00 6 107474 4035 0 0
sp_supplier_order 5428 5428 0 0.00 6 34589 1238 0 0
srv_aggregate_perf_data 81 81 0 0.00 2788 766835 29536 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1434 126272 44666 0 0
srv_find_qd_qs_mism 1021552 1021552 0 0.00 0 30968 6 0 0
srv_make_invnt_saldo 568 568 0 0.00 57 74918 1203 0 0
srv_make_money_saldo 804 804 0 0.00 1 1771 28 0 0
srv_recalc_idx_stat 4 4 0 0.00 141603 574593 323635 0 0
t$perf-abend:sp_add_invoice_to_stock 115 115 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4818 4818 0 0.00 0 14343 202 0 0
t$perf-norm:sp_cancel_adding_invoice 418 418 0 0.00 1 3255 85 0 0
t$perf-norm:sp_cancel_client_order 1672 1672 0 0.00 0 14552 32 0 0
t$perf-norm:sp_cancel_customer_reserve 626 626 0 0.00 0 737 8 0 0
t$perf-norm:sp_cancel_pay_from_customer 726 726 0 0.00 0 2641 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 421 421 0 0.00 0 1194 9 0 0
t$perf-norm:sp_cancel_supplier_invoice 374 374 0 0.00 0 3151 21 0 0
t$perf-norm:sp_cancel_supplier_order 388 388 0 0.00 0 28277 1684 0 0
t$perf-norm:sp_cancel_write_off 955 955 0 0.00 0 6858 17 0 0
t$perf-norm:sp_client_order 19872 19872 0 0.00 0 6732 18 0 0
t$perf-norm:sp_customer_reserve 972 972 0 0.00 0 3004 13 0 0
t$perf-norm:sp_pay_from_customer 7004 7004 0 0.00 0 9598 12 0 0
t$perf-norm:sp_pay_to_supplier 5686 5686 0 0.00 0 5426 10 0 0
t$perf-norm:sp_reserve_write_off 9693 9693 0 0.00 0 7417 9 0 0
t$perf-norm:sp_supplier_invoice 5409 5409 0 0.00 0 4517 18 0 0
t$perf-norm:sp_supplier_order 5428 5428 0 0.00 0 5407 18 0 0
t$perf-norm:srv_aggregate_perf_data 81 81 0 0.00 0 81 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 75 2 0 0
t$perf-norm:srv_make_invnt_saldo 568 568 0 0.00 0 3585 30 0 0
t$perf-norm:srv_make_money_saldo 804 804 0 0.00 0 3035 26 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 2 0 0
v_add_invoice_to_stock 9852 9622 230 2.33 0 15389 24 0 230
v_all_customers 36284 36284 0 0.00 0 259 1 0 0
v_all_suppliers 29770 29770 0 0.00 0 77 1 0 0
v_all_wares 39744 39744 0 0.00 0 74 1 0 0
v_cancel_adding_invoice 836 836 0 0.00 0 1139 8 0 0
v_cancel_client_order 3344 3344 0 0.00 0 1651 5 0 0
v_cancel_customer_prepayment 1452 1452 0 0.00 0 10091 12 0 0
v_cancel_customer_reserve 1252 1252 0 0.00 0 1078 5 0 0
v_cancel_payment_to_supplier 842 842 0 0.00 0 5960 12 0 0
v_cancel_supplier_invoice 758 748 10 1.31 0 1087 18 0 10
v_cancel_supplier_order 776 776 0 0.00 0 979 6 0 0
v_cancel_write_off 1910 1910 0 0.00 0 3280 15 0 0
v_max_id_clo_ord 5428 5428 0 0.00 0 2679 2 0 0
v_max_id_clo_res 972 972 0 0.00 0 3280 15 0 0
v_max_id_ord_sup 5406 5406 0 0.00 0 2642 4 0 0
v_max_non_paid_invoice 1635 1635 0 0.00 0 2519 6 0 0
v_max_non_paid_realizn 3780 3780 0 0.00 0 320 3 0 0
v_min_id_clo_ord 5428 5428 0 0.00 0 21562 16 0 0
v_min_id_clo_res 972 972 0 0.00 0 9449 24 0 0
v_min_id_ord_sup 5406 5406 0 0.00 0 6512 10 0 0
v_min_non_paid_invoice 5686 5686 0 0.00 0 6733 26 0 0
v_min_non_paid_realizn 7004 7004 0 0.00 0 10307 20 0 0
v_our_firm 9908 9908 0 0.00 0 17 1 0 0
v_reserve_write_off 19386 19386 0 0.00 0 20102 10 0 0
x_lock_depdocs_on_canc_invoice 11180 11180 0 0.00 0 16893 138 0 0
x_lock_depdocs_on_canc_sup_ord 388 388 0 0.00 0 15174 385 0 0
x_make_qstorno_1000_1200 5428 5428 0 0.00 1 34244 1135 0 0
x_make_qstorno_1200_2000 5409 5409 0 0.00 3 107375 3870 0 0
x_make_qstorno_2100_3300 441172 441172 0 0.00 0 87645 173 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 19-09-2021 01:39:37 to 19-09-2021 01:39:41.

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
23:07:54 2 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
23:08:54 20311 8192000000 10355254448 10670796800 1411662960 14.699 100 86 172 0 1445714336 6150848 27900528
23:10:19 16240 8192000000 10420253728 10748530688 1472000320 15.231 100 85 170 0 1507951168 8302224 27648624
23:11:43 17906 8192000000 10466086560 10803441664 1519594432 15.647 100 89 178 0 1557293120 9536576 28162112
23:13:06 14864 8192000000 10553959712 10908921856 1603893920 16.373 100 79 156 1 1637738000 10578480 23265600
23:14:25 7145 8192000000 10625751760 10989322240 1683288784 17.045 100 88 177 0 1722925408 13987824 25648800
23:15:40 6616 8192000000 10703112112 11073232896 1753650784 17.632 100 89 178 0 1791725856 13546304 24528768
23:16:48 4482 8192000000 10745154048 11124477952 1805439616 18.059 100 93 183 1 1845867472 14195168 26232688
23:17:56 1920 8192000000 10775543520 11159019520 1835289536 18.302 100 87 175 1 1871449504 12635072 23524896
23:18:59 944 8192000000 10823705648 11211436032 1875713200 18.630 100 96 192 2 1918354704 15322400 27319104
23:20:00 1645 8192000000 10808652816 11213692928 1880453152 18.669 100 62 128 0 1905460464 7207616 17799696
23:21:02 4550 8192000000 10798441584 11220688896 1884862992 18.704 100 32 64 1 1901065392 7214576 8987824
23:22:12 7357 8192000000 10799886464 11229908992 1890790000 18.752 100 17 34 0 1901293264 5460176 5043088
23:23:23 6211 8192000000 10807178224 11243823104 1902663888 18.848 100 25 50 2 1916668416 6741120 7263408
23:24:31 50592 8192000000 10827812640 11256958976 1910015408 18.907 100 37 73 4 1927120992 6624992 10480592
23:26:22 9756 8192000000 10828568832 11256610816 1893401920 18.773 99 20 38 1 1904433568 5623008 5408640
23:27:35 6104 8192000000 10797827536 11231199232 1889664928 18.743 100 26 52 0 1903207600 6317504 7225168
23:28:43 74769 8192000000 10716140720 11144384512 1641792384 16.695 87 35 64 3 1655239584 4335312 9111888
23:30:58 6324 8192000000 10726884832 11135307776 1783207840 17.876 98 41 81 1 1800591008 6936512 10446656
23:32:05 5258 8192000000 10712535936 11129290752 1799739600 18.012 100 32 64 1 1815400464 5802352 9858512
23:33:11 20770 8192000000 10741132464 11173761024 1822796864 18.201 99 23 46 0 1835477120 5445456 7234800
23:34:32 9271 8192000000 10761800576 11188080640 1835380688 18.303 99 28 55 1 1851222400 6581056 9260656
23:35:43 4154 8192000000 10792716720 11211030528 1870979152 18.592 100 26 52 2 1883598256 4907856 7711248
23:36:51 6579 8192000000 10808014624 11230810112 1884817856 18.704 100 27 54 0 1902431440 9581104 8032480
23:37:59 25149 8192000000 10848054688 11265458176 1916309760 18.957 100 27 54 1 1932299472 8543648 7446064
23:39:28 87923 8192000000 10882386304 11308552192 1945908624 19.194 100 40 80 2 1967876832 10107632 11860576
23:41:59 10888 8192000000 10886990896 11317809152 1957789936 19.288 100 37 75 1 1979421152 11095296 10535920
23:43:17 7073 8192000000 10920108176 11339120640 1953201792 19.252 99 71 141 2 1988629728 15085616 20342320
23:44:29 6971 8192000000 10918129184 11341881344 1969366256 19.380 100 61 122 1 2002196592 15654640 17175696
23:45:38 6529 8192000000 10942828112 11372621824 1976915776 19.440 99 64 125 2 2018633360 23539152 18178432
23:46:50 1434 8192000000 10971474144 11387838464 2008494320 19.690 100 73 146 0 2054775520 25433968 20847232
23:47:53 3162 8192000000 10954739920 11372077056 1988131216 19.529 100 80 161 1 2039673056 28029296 23512544
23:48:59 10999 8192000000 10929112768 11354013696 1966595792 19.358 99 63 126 2 2015433264 30234976 18602496
23:50:20 3186 8192000000 10925331328 11338153984 1936325760 19.117 99 81 162 0 1999159648 37923728 24910160
23:51:30 2624 8192000000 10927979632 11342508032 1954748080 19.264 100 83 162 1 2016049296 37037392 24263824
23:52:37 3102 8192000000 10943025488 11348504576 1959066096 19.299 100 87 171 1 2025173952 39922256 26185600
23:53:46 5279 8192000000 10948921024 11364945920 1966401424 19.357 100 74 147 3 2035984112 46069392 23513296
23:54:59 2215 8192000000 10971488176 11385749504 1976123808 19.434 100 73 146 0 2050775392 51775920 22875664
23:56:08 3131 8192000000 10953619216 11374080000 1962254064 19.324 100 61 122 1 2038757584 56170608 20332912
23:57:16 4840 8192000000 10987700912 11399303168 1984145872 19.498 100 91 181 2 2071581248 58780576 28654800
23:58:26 6013 8192000000 10987446832 11412582400 1989309504 19.538 100 54 108 0 2062775920 55640416 17826000
23:59:50 11127 8192000000 10967473920 11399794688 1965312128 19.348 99 47 95 2 2040903312 60030352 15560832
00:01:04 8385 8192000000 10951505232 11395858432 1969084048 19.378 100 41 82 3 2042630816 59422896 14123872
00:02:16 27699 8192000000 10957478816 11412365312 1952269632 19.245 99 29 58 1 2021960320 58613200 11077488
00:03:46 25899 8192000000 10919057376 11373326336 1933459520 19.095 99 22 44 0 1997248112 55141888 8646704
00:05:14 32082 8192000000 10910485440 11373400064 1927221904 19.045 99 24 48 2 1991030528 55455824 8352800
00:06:51 43736 8192000000 10900897856 11340795904 1938697824 19.136 99 18 34 3 1953218448 9019408 5501216
00:08:54 41343 8192000000 10909929760 11365011456 1973787776 19.415 100 24 49 0 1991505360 11176736 6540848
00:10:36 68086 8192000000 10925287216 11370078208 1984744928 19.502 100 26 52 0 2009138112 16778640 7614544
00:12:47 64250 8192000000 10892532368 11361947648 1940977616 19.155 98 8 16 1 1961738688 18535344 2225728
00:14:52 34688 8192000000 10905542800 11369508864 1956699792 19.280 99 13 26 0 1974418336 14116656 3601888
00:16:29 46060 8192000000 10892143680 11354722304 1963414016 19.333 99 16 31 0 1981277536 13214544 4648976
00:18:19 108999 8192000000 10896128704 11378520064 1977857344 19.448 100 18 36 0 1997976192 14479760 5639088
00:21:09 57833 8192000000 10909064640 11375833088 1984965728 19.504 100 19 38 3 1998558656 8417856 5175072
00:23:18 67226 8192000000 10907849632 11381055488 1979285184 19.459 99 22 44 2 1997777440 11880560 6611696
00:25:37 38221 8192000000 10908860800 11387056128 1971548304 19.398 99 10 19 1 1984012992 9445056 3019632
00:27:27 81869 8192000000 10907957232 11398922240 1979820912 19.463 99 12 24 1 2000055264 17087024 3147328
00:29:49 58613 8192000000 10903550768 11372056576 1957224544 19.284 98 27 54 1 1976212128 10675568 8312016
00:31:50 58606 8192000000 10926398768 11400163328 1991723040 19.557 100 15 30 0 2009301168 13601264 3976864
00:33:49 35298 8192000000 10895501824 11356299264 1908336160 18.893 96 21 42 0 1922078400 7856720 5885520
00:35:25 33044 8192000000 10913246000 11373350912 1973050032 19.410 100 25 50 0 1996722304 16784848 6887424
00:37:00 36379 8192000000 10893113040 11357237248 1936350528 19.118 98 17 34 0 1952213664 11315184 4547952
00:38:39 89957 8192000000 10870476336 11333873664 1842809840 18.364 93 20 40 0 1856637984 8032464 5795680
00:41:10 21904 8192000000 10918492144 11375026176 1977937728 19.448 100 40 80 0 2000304656 11624592 10742336
00:42:35 64188 8192000000 10890854704 11364315136 1925511712 19.031 98 20 40 1 1953949536 22102688 6335136
00:44:40 103464 8192000000 10856154016 11328024576 1843246576 18.367 94 8 16 1 1859252672 14061728 1944368
00:47:24 111644 8192000000 10830082480 11304415232 1893698048 18.776 98 37 74 1 1913544800 8552432 11294320
00:50:17 51706 8192000000 10849763184 11327938560 1898266256 18.812 98 16 32 0 1921798640 18763792 4768592
00:52:16 33269 8192000000 10814512768 11283120128 1867018128 18.560 97 7 14 0 1881344080 12530608 1795344
00:53:50 73883 8192000000 10843972768 11314360320 1902672752 18.848 99 16 32 0 1915589088 8425760 4490576
00:56:12 44952 8192000000 10861384880 11328659456 1921331552 18.998 100 11 22 0 1941144192 16691296 3121344
00:57:57 42886 8192000000 10871549136 11328004096 1907445648 18.886 97 48 95 3 1934128896 13454608 13228640
00:59:43 68741 8192000000 10889399664 11361419264 1937051600 19.123 99 20 40 1 1965059072 22346144 5661328
01:02:04 97772 8192000000 10886640752 11373707264 1910751936 18.913 97 14 28 1 1936001872 21115552 4134384
01:04:52 32457 8192000000 10915408112 11370016768 1955530672 19.271 99 36 71 0 1976004096 10588848 9884576
01:06:34 56231 8192000000 10943607440 11387441152 1977543632 19.445 100 32 62 1 2001229280 15206048 8479600
01:08:32 126272 8192000000 10918168720 11393073152 1992184080 19.561 100 15 30 1 2011677456 15557296 3936080
01:11:39 47300 8192000000 10883975360 11362078720 1947194016 19.204 98 12 24 1 1959773760 9539328 3040416
01:13:27 51690 8192000000 10884788752 11364900864 1957945920 19.290 99 11 22 0 1969611104 8241024 3424160
01:15:20 77862 8192000000 10882140256 11358281728 1923434144 19.014 97 9 18 0 1934910576 8958432 2518000
01:17:38 26232 8192000000 10922208016 11380178944 1955078528 19.267 98 46 90 3 1977426048 9105184 13242336
01:19:06 82391 8192000000 10907379216 11389067264 1969349536 19.380 99 25 50 0 1988751264 12237024 7164704
01:21:31 43484 8192000000 10928147072 11397926912 1960972336 19.314 98 43 86 1 1981950336 8947280 12030720
01:23:16 90582 8192000000 10918694896 11402919936 1971812928 19.400 99 25 50 0 1990789248 11928768 7047552
01:25:47 83387 8192000000 10891425504 11371610112 1874735392 18.623 94 20 40 0 1891000864 10858912 5406560
01:28:11 53163 8192000000 10915880240 11391209472 1961880288 19.321 99 26 52 1 1986787200 17124800 7782112
01:31:20 26997 8192000000 10885834672 11353686016 1957502624 19.286 99 34 67 2 1974184048 7117120 9564304
01:32:52 43243 8192000000 10872275232 11349725184 1912906528 18.930 97 4 8 0 1926525680 12139920 1479232
01:34:41 76690 8192000000 10846560752 11320709120 1884371424 18.700 97 20 40 1 1905441472 15146384 5923664
01:37:00 55535 8192000000 9943962992 10333556736 1069088080 11.543 56 7 14 0 1083749632 12686560 1974992

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 01:39:43 to 19-09-2021 01:39:58.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 115 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 19-09-2021 01:39:59 to 19-09-2021 01:40:01.

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          239899
MON$OLDEST_ACTIVE               239900
MON$OLDEST_SNAPSHOT             239900
MON$NEXT_TRANSACTION            239900
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                       2024192
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 01:40:02. 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 01:40:04 No severe PSQL-related problems occured
19.09.21 01:40:05. End of report.