Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 10.12.2021 04:37
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 16122012 kB
MemAvailable 31414000 kB
Buffers 20 kB
Cached 15462108 kB
SwapCached 0 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 4.0.1
DB_NAME                         /home/bases/oltp40-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/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/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=3400
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/oltp40-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-oltp40
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-12-10 04:37:11
DTS_END                         2021-12-10 04:37:11.5320
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/49620
TRN_ID                          226391
ATT_ID                          589
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: 9048.13 29178 1085775
customer order: creation 158.88 426 19066
customer order: refuse 13.53 399 1624
order to supplier: creation 43.24 1622 5189
order to supplier: removal 3.43 130115 412
invoice (draft): creation 41.93 4149 5032
invoice (draft): removal 53.38 1031 6405
invoice accept: apply 38.99 20164 4679
invoice accept: cancel 48.52 933 5822
customer reserve: creation 3591.09 196 430931
customer reserve: removal 4778.85 71 573462
realization accept: apply 76.39 252 9167
realization accept: cancel 79.78 159 9574
payment from customer: creation 55.38 178 6645
payment from customer: removal 5.67 181 680
payment to supplier: creation 46.00 196 5520
payment to supplier: removal 3.03 132 363
service: total inventory turnovers 3.31 4939 397
service: total monetary turnovers 5.78 632 694
service: aggregate perf. data 0.91 19174 109
service: refresh index statistics 0.03 398604 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 10-12-2021 04:38:08 to 10-12-2021 04:38:10.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3238
TEST_TIME 2 3878
TEST_TIME 3 3788
TEST_TIME 4 4012
TEST_TIME 5 4107
TEST_TIME 6 4678
TEST_TIME 7 4819
TEST_TIME 8 5025
TEST_TIME 9 5010
TEST_TIME 10 5140
TEST_TIME 11 5828
TEST_TIME 12 6124
TEST_TIME 13 6324
TEST_TIME 14 7131
TEST_TIME 15 7221
TEST_TIME 16 7619
TEST_TIME 17 7971
TEST_TIME 18 8475
TEST_TIME 19 8526
TEST_TIME 20 8429
TEST_TIME 21 8516
TEST_TIME 22 8798
TEST_TIME 23 8677
TEST_TIME 24 8702
TEST_TIME 25 8769
TEST_TIME 26 8932
TEST_TIME 27 9029
TEST_TIME 28 9198
TEST_TIME 29 9156
TEST_TIME 30 9543
TEST_TIME 31 10250
TEST_TIME 32 10787
TEST_TIME 33 10640
TEST_TIME 34 10571
TEST_TIME 35 10581
TEST_TIME 36 10553
TEST_TIME 37 10789
TEST_TIME 38 10589
TEST_TIME 39 10505
TEST_TIME 40 10495
TEST_TIME 41 10428
TEST_TIME 42 10278
TEST_TIME 43 10175
TEST_TIME 44 10324
TEST_TIME 45 10389
TEST_TIME 46 10199
TEST_TIME 47 10054
TEST_TIME 48 10040
TEST_TIME 49 9942
TEST_TIME 50 9913
TEST_TIME 51 9845
TEST_TIME 52 9761
TEST_TIME 53 9646
TEST_TIME 54 9665
TEST_TIME 55 9632
TEST_TIME 56 9613
TEST_TIME 57 9544
TEST_TIME 58 9453
TEST_TIME 59 9374
TEST_TIME 60 9310
TEST_TIME 61 9378
TEST_TIME 62 9323
TEST_TIME 63 9253
TEST_TIME 64 9262
TEST_TIME 65 9213
TEST_TIME 66 9246
TEST_TIME 67 9318
TEST_TIME 68 9380
TEST_TIME 69 9326
TEST_TIME 70 9319
TEST_TIME 71 9376
TEST_TIME 72 9377
TEST_TIME 73 9330
TEST_TIME 74 9428
TEST_TIME 75 9436
TEST_TIME 76 9420
TEST_TIME 77 9323
TEST_TIME 78 9301
TEST_TIME 79 9334
TEST_TIME 80 9315
TEST_TIME 81 9254
TEST_TIME 82 9234
TEST_TIME 83 9185
TEST_TIME 84 9146
TEST_TIME 85 9136
TEST_TIME 86 9062
TEST_TIME 87 9104
TEST_TIME 88 9052
TEST_TIME 89 9048
TEST_TIME 90 9015
TEST_TIME 91 9030
TEST_TIME 92 9052
TEST_TIME 93 9059
TEST_TIME 94 9149
TEST_TIME 95 9212
TEST_TIME 96 9201
TEST_TIME 97 9205
TEST_TIME 98 9341
TEST_TIME 99 9316
TEST_TIME 100 9360
TEST_TIME 101 9325
TEST_TIME 102 9267
TEST_TIME 103 9267
TEST_TIME 104 9198
TEST_TIME 105 9216
TEST_TIME 106 9188
TEST_TIME 107 9154
TEST_TIME 108 9156
TEST_TIME 109 9124
TEST_TIME 110 9083
TEST_TIME 111 9048
TEST_TIME 112 9042
TEST_TIME 113 9055
TEST_TIME 114 9041
TEST_TIME 115 9065
TEST_TIME 116 9136
TEST_TIME 117 9140
TEST_TIME 118 9118
TEST_TIME 119 9122
TEST_TIME 120 9053

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 10-12-2021 04:38:11 to 10-12-2021 04:38:15.

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1484481 1484481 0 0.00 0 26545 8 0 0
doc_list_biud 1484470 1484470 0 0.00 0 67675 35 0 0
sp_add_invoice_to_stock 4877 4679 198 4.05 32 188909 20164 0 198
sp_cancel_adding_invoice 5822 5822 0 0.00 3 58652 934 0 0
sp_cancel_client_order 1624 1624 0 0.00 5 23118 399 0 0
sp_cancel_customer_reserve 573462 573462 0 0.00 1 27995 72 0 0
sp_cancel_pay_from_customer 680 680 0 0.00 3 15753 182 0 0
sp_cancel_pay_to_supplier 363 363 0 0.00 3 4630 133 0 0
sp_cancel_supplier_invoice 6417 6405 12 0.18 2 67676 1032 0 12
sp_cancel_supplier_order 412 412 0 0.00 6 1058702 130116 0 0
sp_cancel_write_off 9574 9574 0 0.00 1 20672 159 0 0
sp_client_order 19066 19066 0 0.00 4 23100 426 0 0
sp_customer_reserve 430931 430931 0 0.00 0 174217 197 0 0
sp_fill_shopping_cart 29265 29265 0 0.00 1 34722 58 0 0
sp_fill_shopping_cart_clo_res 430918 430918 0 0.00 0 19638 17 0 0
sp_get_clo_for_invoice 4665 4665 0 0.00 0 40368 473 0 0
sp_kill_cost_storno 16439 16439 0 0.00 0 26518 88 0 0
sp_kill_qstorno_ret_qs2qd 1163315 1163315 0 0.00 0 67673 39 0 0
sp_kill_qty_storno 611129 611129 0 0.00 0 67674 84 0 0
sp_lock_selected_doc 19177 19177 0 0.00 0 12509 9 0 0
sp_make_cost_storno 25997 25997 0 0.00 0 24437 82 0 0
sp_multiply_rows_for_pdistr 25996 25996 0 0.00 0 18136 47 0 0
sp_multiply_rows_for_qdistr 19066 19066 0 0.00 0 20417 206 0 0
sp_pay_from_customer 6645 6645 0 0.00 4 12715 179 0 0
sp_pay_to_supplier 5520 5520 0 0.00 5 18150 196 0 0
sp_payment_common 12164 12164 0 0.00 1 13718 42 0 0
sp_qd_handle_on_cancel_clo 1624 1624 0 0.00 0 23111 364 0 0
sp_qd_handle_on_invoice_upd_sts 10485 10485 0 0.00 0 44237 436 0 0
sp_qd_handle_on_reserve_upd_sts 9167 9167 0 0.00 0 17585 74 0 0
sp_reserve_write_off 9167 9167 0 0.00 7 24452 252 0 0
sp_supplier_invoice 5032 5032 0 0.00 7 80686 4150 0 0
sp_supplier_order 5189 5189 0 0.00 5 51411 1622 0 0
srv_aggregate_perf_data 110 109 1 0.90 3011 264753 19174 0 1
srv_fill_mon_cache_memory 62 62 0 0.00 1187 160458 46828 0 0
srv_find_qd_qs_mism 1041214 1041214 0 0.00 0 17725 5 0 0
srv_make_invnt_saldo 397 397 0 0.00 61 115763 4940 0 0
srv_make_money_saldo 694 694 0 0.00 1 12611 632 0 0
srv_recalc_idx_stat 4 4 0 0.00 246016 522455 398605 0 0
t$perf-abend:sp_add_invoice_to_stock 198 198 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4680 4680 0 0.00 0 26505 228 0 0
t$perf-norm:sp_cancel_adding_invoice 359 359 0 0.00 0 5243 100 0 0
t$perf-norm:sp_cancel_client_order 1624 1624 0 0.00 0 1825 14 0 0
t$perf-norm:sp_cancel_customer_reserve 674 674 0 0.00 0 1645 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 680 680 0 0.00 0 23894 42 0 0
t$perf-norm:sp_cancel_pay_to_supplier 363 363 0 0.00 0 2744 18 0 0
t$perf-norm:sp_cancel_supplier_invoice 379 379 0 0.00 0 2176 21 0 0
t$perf-norm:sp_cancel_supplier_order 412 412 0 0.00 0 27792 1916 0 0
t$perf-norm:sp_cancel_write_off 857 857 0 0.00 0 9900 20 0 0
t$perf-norm:sp_client_order 19066 19066 0 0.00 0 10027 24 0 0
t$perf-norm:sp_customer_reserve 972 972 0 0.00 0 6116 13 0 0
t$perf-norm:sp_pay_from_customer 6645 6645 0 0.00 0 2497 7 0 0
t$perf-norm:sp_pay_to_supplier 5520 5520 0 0.00 0 4418 15 0 0
t$perf-norm:sp_reserve_write_off 9167 9167 0 0.00 0 6403 11 0 0
t$perf-norm:sp_supplier_invoice 5032 5032 0 0.00 0 7568 24 0 0
t$perf-norm:sp_supplier_order 5189 5189 0 0.00 0 9246 23 0 0
t$perf-norm:srv_aggregate_perf_data 109 109 0 0.00 0 967 33 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 1575 45 0 0
t$perf-norm:srv_make_invnt_saldo 397 397 0 0.00 0 991 12 0 0
t$perf-norm:srv_make_money_saldo 694 694 0 0.00 0 3389 20 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 5 3 0 0
v_add_invoice_to_stock 9720 9324 396 4.07 0 11582 22 0 396
v_all_customers 34866 34866 0 0.00 0 20 1 0 0
v_all_suppliers 28374 28374 0 0.00 0 26 1 0 0
v_all_wares 38130 38130 0 0.00 0 25 1 0 0
v_cancel_adding_invoice 718 718 0 0.00 0 1408 10 0 0
v_cancel_client_order 3248 3248 0 0.00 0 3209 5 0 0
v_cancel_customer_prepayment 1360 1360 0 0.00 0 6628 24 0 0
v_cancel_customer_reserve 1348 1348 0 0.00 0 2951 5 0 0
v_cancel_payment_to_supplier 726 726 0 0.00 0 3251 20 0 0
v_cancel_supplier_invoice 782 758 24 3.06 0 357 14 0 24
v_cancel_supplier_order 824 824 0 0.00 0 1034 7 0 0
v_cancel_write_off 1714 1714 0 0.00 0 3877 20 0 0
v_max_id_clo_ord 5179 5179 0 0.00 0 517 2 0 0
v_max_id_clo_res 972 972 0 0.00 0 1326 12 0 0
v_max_id_ord_sup 5020 5020 0 0.00 0 6967 10 0 0
v_max_non_paid_invoice 1532 1532 0 0.00 0 390 3 0 0
v_max_non_paid_realizn 3452 3452 0 0.00 0 4498 6 0 0
v_min_id_clo_ord 5179 5179 0 0.00 0 13988 29 0 0
v_min_id_clo_res 972 972 0 0.00 0 3424 11 0 0
v_min_id_ord_sup 5020 5020 0 0.00 0 5270 10 0 0
v_min_non_paid_invoice 5520 5520 0 0.00 0 13714 25 0 0
v_min_non_paid_realizn 6644 6644 0 0.00 0 12110 38 0 0
v_our_firm 9648 9648 0 0.00 0 18 1 0 0
v_reserve_write_off 18334 18334 0 0.00 0 7910 8 0 0
x_lock_depdocs_on_canc_invoice 11848 11848 0 0.00 0 18402 130 0 0
x_lock_depdocs_on_canc_sup_ord 412 412 0 0.00 0 12520 421 0 0
x_make_qstorno_1000_1200 5189 5189 0 0.00 2 51405 1495 0 0
x_make_qstorno_1200_2000 5032 5032 0 0.00 3 80382 3944 0 0
x_make_qstorno_2100_3300 430931 430931 0 0.00 0 174214 177 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 10-12-2021 04:38:16 to 10-12-2021 04:38:20.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
02:07:15 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:16 36238 8192000000 9730807616 10028994560 810454304 9.002 100 89 178 1 839644288 7879648 21310336
02:10:03 25839 8192000000 9776591232 10087415808 849295360 9.393 100 86 172 0 882677504 13479184 19902960
02:11:35 19913 8192000000 9817129808 10141986816 883414784 9.734 100 79 158 0 922078944 20737264 17926896
02:12:58 10952 8192000000 9900987776 10232172544 928603024 10.181 100 86 172 1 1006051840 58621216 18827600
02:14:13 8049 8192000000 9934932240 10275831808 952501584 10.416 100 84 168 1 1054056912 83934176 17621152
02:15:23 5880 8192000000 9941759312 10290806784 981517360 10.699 100 87 171 3 1048917040 48746992 18652688
02:16:32 2839 8192000000 9991908096 10351001600 1021073248 11.082 100 91 180 0 1112920416 73712224 18134944
02:17:36 2767 8192000000 10016144000 10381262848 1046560608 11.328 100 81 162 0 1126242384 63509952 16171824
02:18:40 1361 8192000000 10060986416 10434408448 1067857936 11.532 100 93 186 1 1172721232 84656704 20206592
02:19:42 15102 8192000000 9996898080 10401271808 1072055008 11.572 100 40 79 1 1108263280 28167888 8040384
02:20:58 3986 8192000000 10011416064 10404401152 1084075088 11.686 100 42 83 2 1122876768 30189904 8611776
02:22:04 6311 8192000000 9996782944 10400448512 1092812256 11.769 100 18 37 1 1115142048 18381312 3948480
02:23:14 71692 8192000000 10016685296 10413662208 1097019296 11.809 100 38 76 1 1121948112 16501904 8426912
02:25:26 6881 8192000000 10041961472 10462117888 1104426320 11.880 100 54 107 0 1165081584 49678816 10976448
02:26:34 5162 8192000000 10017184560 10417983488 1105145520 11.886 100 43 91 0 1130812032 15258912 10407600
02:27:41 54280 8192000000 9977239648 10384449536 1019256928 11.065 93 32 63 2 1054756336 28788544 6710864
02:29:36 4348 8192000000 9984083264 10375344128 1067892256 11.532 100 39 80 1 1100726512 23403088 9431168
02:30:43 3809 8192000000 9949107440 10314952704 1000736160 10.886 96 55 109 4 1035604944 22353264 12515520
02:31:48 8198 8192000000 9945224640 10330562560 1006067376 10.937 98 29 59 3 1040670144 28468032 6134736
02:32:57 38703 8192000000 9939258448 10335043584 989231552 10.774 96 22 44 1 1019099024 25068096 4799376
02:34:38 4998 8192000000 9949582640 10346422272 1034394752 11.211 99 46 94 0 1059361200 14856432 10110016
02:35:44 5125 8192000000 9970169776 10358202368 1053175104 11.391 100 42 83 1 1085887984 23676496 9036384
02:36:52 7812 8192000000 9974649072 10368389120 1060747264 11.464 100 55 110 0 1094884000 20766080 13370656
02:38:09 92738 8192000000 10026489696 10428669952 1088612928 11.729 100 45 88 2 1133719328 35133008 9973392
02:40:48 11740 8192000000 10068232464 10465320960 1107749456 11.911 100 54 107 1 1182512064 63586720 11175888
02:42:02 23490 8192000000 10098499776 10502602752 1119832656 12.025 100 51 101 2 1209716944 79003456 10880832
02:43:34 7469 8192000000 10133580272 10539925504 1126975168 12.093 100 74 149 0 1242585824 98952400 16658256
02:44:55 2769 8192000000 10164179104 10565894144 1138698112 12.203 100 62 124 0 1268618144 117219712 12700320
02:46:03 4571 8192000000 10205538960 10607149056 1147491072 12.286 100 68 134 1 1302188224 139722016 14975136
02:47:18 6288 8192000000 10230527888 10642456576 1160412848 12.407 100 59 118 0 1346845456 173668256 12764352
02:48:30 2204 8192000000 10255566960 10676121600 1166035248 12.460 100 84 167 2 1358479680 173842336 18602096
02:49:37 3083 8192000000 10260776464 10677735424 1159911312 12.402 100 72 141 1 1368297776 191789312 16597152
02:50:50 1186 8192000000 10297423376 10704793600 1166834976 12.467 100 85 168 1 1400237632 214139120 19263536
02:51:56 1665 8192000000 10265160400 10689048576 1157244688 12.377 99 73 143 1 1368233472 195627952 15360832
02:53:02 1747 8192000000 10267680976 10691747840 1166884784 12.468 100 67 131 1 1369847312 188299440 14663088
02:54:10 1547 8192000000 10282908000 10684461056 1139310016 12.209 99 93 187 1 1378058832 217914880 20833936
02:55:16 11335 8192000000 10224924704 10629328896 1133340192 12.153 98 69 133 3 1312159232 164607280 14211760
02:56:31 6937 8192000000 10242121808 10655571968 1149493472 12.305 100 54 108 0 1347609696 186218272 11897952
02:57:45 3145 8192000000 10333261200 10733289472 1157708592 12.382 100 67 132 0 1431913984 258992752 15212640
02:58:54 12985 8192000000 10349853280 10765545472 1153208160 12.340 99 53 106 1 1439121696 272689072 13224464
03:00:15 2910 8192000000 10329945888 10736050176 1141473824 12.229 98 56 112 0 1415922448 260776384 13672240
03:01:25 3817 8192000000 10409289440 10805891072 1149606224 12.306 99 80 160 0 1501494512 333381152 18507136
03:02:30 9727 8192000000 10433988896 10850197504 1164288464 12.443 100 50 100 0 1533860064 356546912 13024688
03:03:42 11415 8192000000 10448180320 10877284352 1165171424 12.452 100 38 76 0 1550250496 375186128 9892944
03:05:14 6207 8192000000 10460081584 10906570752 1154265680 12.350 100 55 110 0 1576614544 408647760 13701104
03:06:41 18209 8192000000 10493497456 10920812544 1119880144 12.026 98 37 74 1 1554203776 424986448 9337184
03:08:58 33381 8192000000 10319594624 10761777152 1140036192 12.216 100 49 98 2 1409403936 258647120 10720624
03:10:32 58975 8192000000 10181554224 10604888064 1120303920 12.030 98 47 94 2 1267274720 136513344 10457456
03:12:34 69434 8192000000 10169859792 10643992576 1133910000 12.158 100 22 44 1 1302498816 163541696 5047120
03:14:44 57506 8192000000 10114323392 10580803584 1120570560 12.032 99 13 26 1 1219136512 95941024 2624928
03:16:44 90022 8192000000 10127241856 10594713600 1126340992 12.087 98 25 48 1 1225991120 94355536 5294592
03:19:22 129578 8192000000 10141602528 10664595456 1142439296 12.238 100 17 36 1 1246913744 100943824 3530624
03:22:34 156627 8192000000 10088542720 10522161152 1123255888 12.058 98 24 48 2 1169598480 41504160 4838432
03:26:27 35870 8192000000 10103885360 10528628736 1127688880 12.100 98 53 106 0 1188262304 49291600 11281824
03:28:06 45731 8192000000 10058154544 10533928960 1143610384 12.249 100 24 48 0 1200320112 50516048 6193680
03:30:02 17197 8192000000 10082769504 10535178240 1148000400 12.291 100 15 30 2 1191200432 39976112 3223920
03:31:22 64942 8192000000 10101524736 10586497024 1128120608 12.104 98 33 67 1 1215980752 79808896 8051248
03:34:29 122939 8192000000 10106070256 10565636096 1113101648 11.962 97 14 28 0 1176919744 60421936 3396160
03:37:57 57544 8192000000 10079141616 10547761152 1143091536 12.245 100 16 32 0 1215478992 69571200 2816256
03:39:58 56572 8192000000 10108857984 10565062656 1156021088 12.366 100 20 40 0 1218521264 58811792 3688384
03:41:58 94347 8192000000 10162536320 10658295808 1154851616 12.355 99 16 32 0 1258380480 100142928 3385936
03:44:36 53725 8192000000 10090004384 10578604032 1148956480 12.300 100 18 36 0 1213367504 60601984 3809040
03:46:39 27736 8192000000 10117197264 10557272064 1161294576 12.415 100 34 68 0 1214529680 46272672 6962432
03:48:32 56243 8192000000 10072421808 10521210880 1093732496 11.778 96 25 50 1 1129218688 29518800 5967392
03:50:29 73131 8192000000 10074377296 10557415424 1116636528 11.995 98 13 26 0 1194429840 75012576 2780736
03:52:43 160457 8192000000 10080371136 10527404032 1106117520 11.896 97 24 48 2 1156850976 45464080 5269376
03:56:31 82212 8192000000 10054558320 10506498048 1078162448 11.630 95 25 50 1 1113287136 29645760 5478928
03:59:27 54818 8192000000 10067083920 10525880320 1104603744 11.881 98 16 30 2 1166365856 58395632 3366480
04:01:24 65847 8192000000 10055731024 10528276480 1092582208 11.767 97 24 49 0 1182669920 84688368 5399344
04:04:34 85885 8192000000 10044782480 10492805120 1084329952 11.689 97 15 30 0 1110542400 23187792 3024656
04:07:01 56302 8192000000 10118380464 10551336960 1102893616 11.865 97 24 48 0 1167850000 60345888 4610496
04:08:57 67764 8192000000 10083370480 10526101504 1103578144 11.872 98 21 41 1 1203134832 95455648 4101040
04:11:05 98667 8192000000 10068279504 10523672576 1102071488 11.857 97 13 26 1 1204267376 99679440 2516448
04:13:49 41379 8192000000 10156604896 10647117824 1137857408 12.195 99 23 44 0 1207166448 64652320 4656720
04:15:31 80163 8192000000 10106524656 10558722048 1137967024 12.196 99 16 32 0 1191158112 50098864 3092224
04:17:57 58721 8192000000 10075430976 10510626816 1128202128 12.104 99 21 42 0 1162239360 29603024 4434208
04:19:56 69269 8192000000 10075000864 10552180736 1145246144 12.265 100 21 40 3 1197194800 47214752 4733904
04:22:28 15550 8192000000 10114588000 10558337024 1151436896 12.323 100 55 108 2 1227259872 64762736 11060240
04:23:45 75648 8192000000 10063672128 10511650816 1128058848 12.103 98 16 32 0 1169962160 38728256 3175056
04:26:01 58496 8192000000 10086764928 10550849536 1149694784 12.307 100 12 25 2 1203657824 51619568 2343472
04:28:08 76339 8192000000 10095015520 10574544896 1145650224 12.269 99 18 36 0 1191773536 42381936 3741376
04:30:25 77767 8192000000 10096856176 10554810368 1133033280 12.150 98 12 24 0 1198167200 62593184 2540736
04:32:44 53264 8192000000 10113240752 10576900096 1140324448 12.219 99 27 54 0 1207451472 61867408 5259616
04:34:51 48772 8192000000 10113063888 10563223552 1145287008 12.265 99 23 46 0 1197452256 47704192 4461056
04:36:55 27225 8192000000 9083887472 9360863232 229243920 2.722 21 7 12 1 251216032 20529824 1442288

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 10-12-2021 04:38:22 to 10-12-2021 04:38:36.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 198 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 10-12-2021 04:38:37 to 10-12-2021 04:38:39.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          226673
MON$OLDEST_ACTIVE               226674
MON$OLDEST_SNAPSHOT             226674
MON$NEXT_TRANSACTION            226674
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-30 07:30:08.6030 GMT
MON$PAGES                       2091008
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             778
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2676 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2676 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2676 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2676 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.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 ---
:149: 
:150: mscs-test-161.budzdorov.ru    Fri Dec 10 04:37:24 2021
:151:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:152: 
:153: 
:154: mscs-test-161.budzdorov.ru    Fri Dec 10 04:37:24 2021
:155:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:156: 
--- end of diff output ---

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

10.12.21 04:38:40. There are 3 logs in /var/tmp/logs-oltp40 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-12-10 04:38:42 No severe PSQL-related problems occured
10.12.21 04:38:44. End of report.