Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 22.10.2021 10:38
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 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 15610628 kB
MemAvailable 30652716 kB
Buffers 0 kB
Cached 16110720 kB
SwapCached 3704 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-10-22 10:37:09
DTS_END                         2021-10-22 10:37:09.4500
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/53952
TRN_ID                          236671
ATT_ID                          660
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: 8931.18 23447 1071742
customer order: creation 176.35 408 21162
customer order: refuse 14.78 380 1774
order to supplier: creation 46.68 1562 5602
order to supplier: removal 3.35 118827 402
invoice (draft): creation 45.39 4084 5447
invoice (draft): removal 50.36 1007 6043
invoice accept: apply 42.05 20180 5046
invoice accept: cancel 45.23 961 5427
customer reserve: creation 3782.13 203 453855
customer reserve: removal 4420.32 70 530438
realization accept: apply 86.32 203 10358
realization accept: cancel 89.58 127 10749
payment from customer: creation 60.77 121 7292
payment from customer: removal 5.75 87 690
payment to supplier: creation 48.04 144 5765
payment to supplier: removal 3.39 106 407
service: total inventory turnovers 3.54 4570 425
service: total monetary turnovers 6.27 737 752
service: aggregate perf. data 0.86 19575 103
service: refresh index statistics 0.04 295584 5

Performance in TOTAL, chart


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

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3982
TEST_TIME 2 3646
TEST_TIME 3 4702
TEST_TIME 4 5159
TEST_TIME 5 4987
TEST_TIME 6 5027
TEST_TIME 7 5695
TEST_TIME 8 5845
TEST_TIME 9 6033
TEST_TIME 10 6485
TEST_TIME 11 6675
TEST_TIME 12 6762
TEST_TIME 13 6939
TEST_TIME 14 7302
TEST_TIME 15 7263
TEST_TIME 16 7352
TEST_TIME 17 7477
TEST_TIME 18 7408
TEST_TIME 19 7995
TEST_TIME 20 8459
TEST_TIME 21 8379
TEST_TIME 22 8683
TEST_TIME 23 8759
TEST_TIME 24 8622
TEST_TIME 25 8967
TEST_TIME 26 9309
TEST_TIME 27 9291
TEST_TIME 28 9417
TEST_TIME 29 9603
TEST_TIME 30 10342
TEST_TIME 31 10635
TEST_TIME 32 10676
TEST_TIME 33 10854
TEST_TIME 34 11001
TEST_TIME 35 10777
TEST_TIME 36 10519
TEST_TIME 37 10440
TEST_TIME 38 10303
TEST_TIME 39 10251
TEST_TIME 40 10220
TEST_TIME 41 10162
TEST_TIME 42 10197
TEST_TIME 43 10186
TEST_TIME 44 10022
TEST_TIME 45 9940
TEST_TIME 46 9982
TEST_TIME 47 9918
TEST_TIME 48 9895
TEST_TIME 49 9875
TEST_TIME 50 9755
TEST_TIME 51 9660
TEST_TIME 52 9563
TEST_TIME 53 9487
TEST_TIME 54 9401
TEST_TIME 55 9315
TEST_TIME 56 9337
TEST_TIME 57 9268
TEST_TIME 58 9286
TEST_TIME 59 9272
TEST_TIME 60 9249
TEST_TIME 61 9394
TEST_TIME 62 9388
TEST_TIME 63 9380
TEST_TIME 64 9423
TEST_TIME 65 9533
TEST_TIME 66 9521
TEST_TIME 67 9543
TEST_TIME 68 9626
TEST_TIME 69 9509
TEST_TIME 70 9435
TEST_TIME 71 9400
TEST_TIME 72 9356
TEST_TIME 73 9311
TEST_TIME 74 9289
TEST_TIME 75 9249
TEST_TIME 76 9166
TEST_TIME 77 9190
TEST_TIME 78 9177
TEST_TIME 79 9138
TEST_TIME 80 9130
TEST_TIME 81 9199
TEST_TIME 82 9155
TEST_TIME 83 9134
TEST_TIME 84 9133
TEST_TIME 85 9209
TEST_TIME 86 9299
TEST_TIME 87 9286
TEST_TIME 88 9340
TEST_TIME 89 9327
TEST_TIME 90 9250
TEST_TIME 91 9207
TEST_TIME 92 9180
TEST_TIME 93 9160
TEST_TIME 94 9085
TEST_TIME 95 9080
TEST_TIME 96 9067
TEST_TIME 97 9038
TEST_TIME 98 9046
TEST_TIME 99 9057
TEST_TIME 100 9068
TEST_TIME 101 9097
TEST_TIME 102 9153
TEST_TIME 103 9127
TEST_TIME 104 9117
TEST_TIME 105 9136
TEST_TIME 106 9165
TEST_TIME 107 9139
TEST_TIME 108 9153
TEST_TIME 109 9187
TEST_TIME 110 9166
TEST_TIME 111 9249
TEST_TIME 112 9176
TEST_TIME 113 9151
TEST_TIME 114 9108
TEST_TIME 115 9061
TEST_TIME 116 9024
TEST_TIME 117 9016
TEST_TIME 118 9034
TEST_TIME 119 9003
TEST_TIME 120 8935

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 22-10-2021 10:38:42 to 22-10-2021 10:38:45.

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1492045 1492045 0 0.00 0 21421 8 0 0
doc_list_biud 1492045 1492045 0 0.00 0 34175 32 0 0
sp_add_invoice_to_stock 5165 5046 119 2.30 38 210602 20180 0 119
sp_cancel_adding_invoice 5427 5427 0 0.00 7 84135 962 0 0
sp_cancel_client_order 1774 1774 0 0.00 5 13279 380 0 0
sp_cancel_customer_reserve 530438 530438 0 0.00 1 34178 70 0 0
sp_cancel_pay_from_customer 690 690 0 0.00 3 4653 87 0 0
sp_cancel_pay_to_supplier 407 407 0 0.00 3 4950 106 0 0
sp_cancel_supplier_invoice 6055 6043 12 0.19 3 18726 1008 0 12
sp_cancel_supplier_order 402 402 0 0.00 6 1098875 118828 0 0
sp_cancel_write_off 10749 10749 0 0.00 1 11003 127 0 0
sp_client_order 21162 21162 0 0.00 4 19415 409 0 0
sp_customer_reserve 453855 453855 0 0.00 0 147307 204 0 0
sp_fill_shopping_cart 32192 32192 0 0.00 1 24150 49 0 0
sp_fill_shopping_cart_clo_res 453851 453851 0 0.00 0 15432 14 0 0
sp_get_clo_for_invoice 5041 5041 0 0.00 0 21756 358 0 0
sp_kill_cost_storno 17273 17273 0 0.00 0 19178 73 0 0
sp_kill_qstorno_ret_qs2qd 1078070 1078070 0 0.00 0 34098 38 0 0
sp_kill_qty_storno 570232 570232 0 0.00 0 34175 82 0 0
sp_lock_selected_doc 21175 21175 0 0.00 0 5471 3 0 0
sp_make_cost_storno 28456 28456 0 0.00 0 21417 62 0 0
sp_multiply_rows_for_pdistr 28456 28456 0 0.00 0 10517 37 0 0
sp_multiply_rows_for_qdistr 21162 21162 0 0.00 0 13692 202 0 0
sp_pay_from_customer 7292 7292 0 0.00 4 14513 121 0 0
sp_pay_to_supplier 5765 5765 0 0.00 5 14770 144 0 0
sp_payment_common 13057 13057 0 0.00 1 14745 24 0 0
sp_qd_handle_on_cancel_clo 1774 1774 0 0.00 0 13272 353 0 0
sp_qd_handle_on_invoice_upd_sts 10468 10468 0 0.00 0 24369 406 0 0
sp_qd_handle_on_reserve_upd_sts 10358 10358 0 0.00 0 16994 62 0 0
sp_reserve_write_off 10358 10358 0 0.00 7 19401 204 0 0
sp_supplier_invoice 5447 5447 0 0.00 6 98092 4085 0 0
sp_supplier_order 5602 5602 0 0.00 5 45834 1562 0 0
srv_aggregate_perf_data 104 103 1 0.96 2599 489972 19575 0 1
srv_fill_mon_cache_memory 67 67 0 0.00 1531 147289 42647 0 0
srv_find_qd_qs_mism 1024070 1024070 0 0.00 0 12630 6 0 0
srv_make_invnt_saldo 425 425 0 0.00 57 123000 4571 0 0
srv_make_money_saldo 752 752 0 0.00 1 20075 738 0 0
srv_recalc_idx_stat 5 5 0 0.00 201916 428354 295585 0 0
t$perf-abend:sp_add_invoice_to_stock 119 119 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 5047 5047 0 0.00 0 10929 179 0 0
t$perf-norm:sp_cancel_adding_invoice 411 411 0 0.00 0 7879 180 0 0
t$perf-norm:sp_cancel_client_order 1774 1774 0 0.00 0 1724 13 0 0
t$perf-norm:sp_cancel_customer_reserve 707 707 0 0.00 0 1138 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 690 690 0 0.00 0 482 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 407 407 0 0.00 0 1897 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 390 390 0 0.00 0 819 20 0 0
t$perf-norm:sp_cancel_supplier_order 402 402 0 0.00 0 29984 1850 0 0
t$perf-norm:sp_cancel_write_off 998 998 0 0.00 0 1206 8 0 0
t$perf-norm:sp_client_order 21162 21162 0 0.00 0 9824 21 0 0
t$perf-norm:sp_customer_reserve 980 980 0 0.00 0 8941 17 0 0
t$perf-norm:sp_pay_from_customer 7292 7292 0 0.00 0 7067 12 0 0
t$perf-norm:sp_pay_to_supplier 5765 5765 0 0.00 0 5130 9 0 0
t$perf-norm:sp_reserve_write_off 10358 10358 0 0.00 0 6754 11 0 0
t$perf-norm:sp_supplier_invoice 5447 5447 0 0.00 0 6209 23 0 0
t$perf-norm:sp_supplier_order 5602 5602 0 0.00 0 3672 19 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 666 18 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 168 7 0 0
t$perf-norm:srv_make_invnt_saldo 425 425 0 0.00 0 2520 13 0 0
t$perf-norm:srv_make_money_saldo 752 752 0 0.00 0 656 7 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 2 9 4 0 0
v_add_invoice_to_stock 10314 10076 238 2.30 0 5334 18 0 238
v_all_customers 38498 38498 0 0.00 0 18 1 0 0
v_all_suppliers 30286 30286 0 0.00 0 56 1 0 0
v_all_wares 42324 42324 0 0.00 0 38 1 0 0
v_cancel_adding_invoice 822 822 0 0.00 0 3946 13 0 0
v_cancel_client_order 3548 3548 0 0.00 0 4579 5 0 0
v_cancel_customer_prepayment 1380 1380 0 0.00 0 4630 8 0 0
v_cancel_customer_reserve 1414 1414 0 0.00 0 3589 9 0 0
v_cancel_payment_to_supplier 814 814 0 0.00 0 4916 18 0 0
v_cancel_supplier_invoice 804 780 24 2.98 0 4156 22 0 24
v_cancel_supplier_order 804 804 0 0.00 0 4218 15 0 0
v_cancel_write_off 1996 1996 0 0.00 0 1078 13 0 0
v_max_id_clo_ord 5602 5602 0 0.00 0 2639 2 0 0
v_max_id_clo_res 980 980 0 0.00 0 5055 19 0 0
v_max_id_ord_sup 5428 5428 0 0.00 0 6369 5 0 0
v_max_non_paid_invoice 1652 1652 0 0.00 0 119 2 0 0
v_max_non_paid_realizn 3931 3931 0 0.00 0 2948 4 0 0
v_min_id_clo_ord 5602 5602 0 0.00 0 12149 12 0 0
v_min_id_clo_res 980 980 0 0.00 0 1701 6 0 0
v_min_id_ord_sup 5428 5428 0 0.00 0 8042 9 0 0
v_min_non_paid_invoice 5765 5765 0 0.00 0 14742 17 0 0
v_min_non_paid_realizn 7292 7292 0 0.00 0 5798 16 0 0
v_our_firm 10548 10548 0 0.00 0 17 1 0 0
v_reserve_write_off 20716 20716 0 0.00 0 6036 8 0 0
x_lock_depdocs_on_canc_invoice 11080 11080 0 0.00 0 16844 119 0 0
x_lock_depdocs_on_canc_sup_ord 402 402 0 0.00 0 13959 457 0 0
x_make_qstorno_1000_1200 5602 5602 0 0.00 2 45824 1453 0 0
x_make_qstorno_1200_2000 5447 5447 0 0.00 3 98080 3906 0 0
x_make_qstorno_2100_3300 453855 453855 0 0.00 0 147306 186 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 22-10-2021 10:38:46 to 22-10-2021 10:38:50.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
08:07:13 1 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:14 21408 8192000000 9724969280 10026373120 813237552 9.030 100 82 164 0 840134112 7518944 19377616
08:09:41 20593 8192000000 9775997984 10085294080 847472592 9.375 100 91 183 0 881323392 12042784 21808016
08:11:11 19332 8192000000 9814369104 10132811776 879308880 9.693 100 91 182 0 920808544 20556784 20942880
08:12:37 12541 8192000000 9864623216 10203959296 919290768 10.089 100 70 140 0 976740240 41354576 16094896
08:13:58 11082 8192000000 9927212752 10277470208 966688096 10.554 100 74 148 0 1041065056 58697312 15679648
08:15:13 5716 8192000000 9959391088 10316333056 1010095248 10.976 100 85 170 0 1074936512 47542656 17298608
08:16:22 3129 8192000000 10031344272 10391363584 1028449104 11.154 100 92 183 1 1141011680 94109984 18452592
08:17:26 2814 8192000000 10001588160 10369597440 1049593440 11.357 100 78 156 0 1115872064 50284720 15993904
08:18:31 2092 8192000000 10035650464 10408546304 1069390928 11.546 100 90 183 0 1146944784 59521856 18032000
08:19:34 5327 8192000000 10001813184 10400129024 1069479712 11.547 100 30 60 1 1121390640 46038032 5872896
08:20:41 4078 8192000000 10014541040 10399211520 1081988912 11.666 100 54 108 2 1123615296 29829616 11796768
08:21:46 4917 8192000000 10002515136 10399641600 1086212816 11.707 100 45 88 1 1122605008 26925168 9467024
08:22:53 4109 8192000000 10022458800 10407395328 1088448160 11.728 100 81 164 0 1123597664 17157696 17991808
08:23:57 5205 8192000000 10016033360 10408988672 1095178128 11.792 100 40 78 0 1125595312 21703632 8713552
08:25:03 8113 8192000000 10018105088 10431823872 1098058064 11.819 100 37 74 1 1128229440 22366000 7805376
08:26:14 8662 8192000000 10018269568 10422620160 1095116416 11.791 100 23 46 0 1120866624 20998704 4751504
08:27:26 33497 8192000000 9994991040 10403557376 1067604224 11.529 97 26 51 2 1091253232 18581664 5067344
08:29:04 6357 8192000000 9989784096 10384965632 1062745360 11.483 99 44 86 2 1090787776 18734800 9307616
08:30:13 7754 8192000000 9973340368 10359754752 1036395984 11.230 99 36 72 1 1066414016 22403088 7614944
08:31:24 65671 8192000000 9919674016 10309103616 961745968 10.506 94 29 59 0 989343120 20905824 6691328
08:33:30 9860 8192000000 9955114048 10350444544 1046447888 11.327 100 31 62 0 1084002896 30952976 6602032
08:34:42 4527 8192000000 9971194320 10364321792 1062466672 11.480 100 34 68 1 1090831360 19282928 9081760
08:35:47 6880 8192000000 9985881856 10383204352 1066377008 11.517 100 36 70 1 1102139632 27440016 8322608
08:36:57 78911 8192000000 10007505760 10407858176 1085385184 11.699 100 42 84 2 1109423552 15038640 8999728
08:39:27 34159 8192000000 10065942400 10476904448 1113631024 11.967 100 35 70 0 1178553168 57625664 7296480
08:41:18 14451 8192000000 10117133600 10519117824 1133117712 12.151 100 59 118 0 1220153120 74681344 12354064
08:42:36 11835 8192000000 10162161280 10564931584 1145845440 12.270 100 59 118 1 1269939872 111949488 12144944
08:43:52 28951 8192000000 10165696800 10595606528 1152527200 12.333 100 48 96 0 1287905136 124145872 11232064
08:45:31 5881 8192000000 10209058912 10622652416 1166131440 12.461 100 74 148 1 1311461040 129592192 15737408
08:46:42 7418 8192000000 10218072912 10625880064 1172645248 12.522 100 67 132 0 1325428944 138173776 14609920
08:47:52 2991 8192000000 10245894448 10659516416 1185386592 12.640 100 69 137 1 1347049968 147343808 14319568
08:49:04 6397 8192000000 10271577392 10682421248 1184274016 12.630 100 68 135 0 1378655920 179042400 15339504
08:50:16 6468 8192000000 10310655840 10727014400 1189981904 12.683 100 61 122 0 1413495792 210219744 13294144
08:51:31 2201 8192000000 10300174928 10707673088 1182411472 12.613 100 79 154 1 1397213104 198369392 16432240
08:52:41 5021 8192000000 10304487856 10712821760 1166998544 12.469 99 65 130 0 1401607920 220310576 14298800
08:53:51 4544 8192000000 10358900816 10759688192 1175486112 12.548 100 85 168 1 1451144240 257638752 18019376
08:55:03 2974 8192000000 10363139760 10766016512 1164459152 12.445 100 86 172 2 1462277536 277769344 20049040
08:56:13 1529 8192000000 10330421168 10781585408 1146848384 12.280 100 81 159 0 1428792736 263183488 18760864
08:57:18 6671 8192000000 10323425904 10740846592 1141305104 12.228 99 64 126 1 1428152720 271972800 14874816
08:58:34 1527 8192000000 10311378736 10711322624 1140825264 12.223 100 81 158 3 1402363280 243201744 18336272
08:59:44 3024 8192000000 10366147888 10777710592 1155330480 12.360 100 57 114 0 1459740160 291081840 13327840
09:00:56 1883 8192000000 10375584640 10772312064 1148718960 12.297 99 91 182 0 1466544864 297171376 20654528
09:02:06 8976 8192000000 10449124464 10868367360 1171275680 12.509 100 63 126 0 1542800384 355117968 16406736
09:03:17 7114 8192000000 10348384208 10797105152 1158016784 12.385 100 32 64 0 1471130496 304646720 8466992
09:04:27 5419 8192000000 10347164000 10795917312 1149240160 12.302 100 42 84 0 1442391888 282479504 10672224
09:05:39 17224 8192000000 10349135440 10800726016 1149577760 12.306 100 33 66 0 1452073552 293637616 8858176
09:07:00 22691 8192000000 10212636640 10783780864 1123897536 12.064 100 21 42 0 1379585520 250211456 5476528
09:08:32 27195 8192000000 10135306624 10613768192 1122071936 12.047 99 12 24 0 1233233456 108335184 2826336
09:10:02 37516 8192000000 10148526432 10626191360 1137772416 12.195 100 12 24 1 1258556608 118456880 2327312
09:12:04 89568 8192000000 10113676160 10543960064 1144456848 12.257 100 33 65 3 1183320048 32279952 6583248
09:14:42 48554 8192000000 10122981904 10546163712 1141163264 12.226 100 56 112 1 1202111008 48397120 12550624
09:16:38 39346 8192000000 10115292512 10526322688 1114128032 11.971 97 60 116 1 1173593984 46896928 12569024
09:18:21 22713 8192000000 10126783216 10564722688 1140959600 12.225 100 23 46 0 1214314416 67938992 5415824
09:19:46 37164 8192000000 10111492272 10588909568 1144353824 12.256 100 17 34 0 1216920176 68167216 4399136
09:21:24 73623 8192000000 10159187232 10609192960 1150691552 12.316 100 26 52 2 1230108592 73816288 5600752
09:23:40 53717 8192000000 10132826176 10579959808 1137526880 12.192 98 20 41 2 1210872000 68999008 4346112
09:25:42 147289 8192000000 10058358736 10557861888 1099628432 11.834 96 11 21 1 1140843040 38783008 2431600
09:29:23 72678 8192000000 10100799024 10544857088 1143544384 12.249 100 29 58 1 1196542784 47466720 5531680
09:31:46 41272 8192000000 10092250032 10564984832 1144194160 12.255 100 16 32 0 1190450240 43238352 3017728
09:33:30 61234 8192000000 10052792080 10530689024 1101664528 11.853 97 7 14 0 1141346336 38404992 1276816
09:35:32 44092 8192000000 10073619408 10528018432 1100001952 11.838 97 18 36 0 1161212720 57362528 3848240
09:37:16 29601 8192000000 10131859824 10565251072 1128286464 12.105 99 34 68 3 1222540784 86652304 7602016
09:38:50 70265 8192000000 10130480336 10592321536 1115902640 11.988 98 26 52 2 1197160608 75008400 6249568
09:41:02 71924 8192000000 10097356960 10583482368 1136619408 12.184 100 16 32 0 1248808160 108670576 3518176
09:43:15 36694 8192000000 10096503552 10554884096 1126579568 12.089 98 33 66 1 1182458352 49330688 6548096
09:44:52 48113 8192000000 10070189056 10543038464 1075119952 11.601 95 23 46 0 1137808544 58123920 4564672
09:46:49 81546 8192000000 10088335888 10522120192 1059514896 11.452 94 33 66 0 1108741712 42665680 6561136
09:49:18 50456 8192000000 10080126800 10526994432 1096530080 11.805 97 26 52 1 1159405072 57784352 5090640
09:51:26 53600 8192000000 10046333520 10486435840 1101664288 11.853 97 13 26 0 1132915408 28320752 2930368
09:53:24 86881 8192000000 10051543344 10534043648 1073731264 11.588 95 6 12 0 1118892496 44227776 933456
09:55:55 18879 8192000000 10061509056 10489638912 1116161616 11.991 99 41 81 1 1144904032 20162880 8579536
09:57:23 53121 8192000000 10091375152 10547671040 1139881568 12.214 100 13 25 0 1210810880 68048640 2880672
09:59:18 29622 8192000000 10079169392 10513571840 1084345424 11.689 95 39 78 0 1131994432 40126336 7522672
10:00:56 57122 8192000000 10165053088 10625056768 1139869488 12.214 100 27 54 0 1261329632 115399536 6060608
10:03:01 71533 8192000000 10071982240 10516000768 1097398160 11.813 97 16 32 0 1165527056 64474752 3654144
10:05:13 72515 8192000000 10089013296 10589396992 1129226000 12.114 99 19 39 1 1197974880 64967952 3780928
10:07:26 89071 8192000000 10080343280 10532130816 1127678144 12.099 99 32 63 0 1159332000 25163936 6489920
10:09:55 84406 8192000000 10070064048 10569322496 1119366592 12.021 98 5 10 0 1174499120 54001616 1130912
10:12:51 76222 8192000000 10071967216 10523402240 1103275424 11.869 96 17 33 1 1162391600 55701104 3415072
10:15:12 60043 8192000000 10139500656 10604326912 1148136480 12.292 99 20 40 1 1211743184 59009344 4597360
10:17:12 95860 8192000000 10133303072 10639675392 1141045392 12.225 99 19 37 2 1210512304 65433248 4033664
10:19:55 35014 8192000000 10074758096 10511134720 1113717040 11.968 97 35 71 0 1150993504 29791728 7484736
10:21:34 22667 8192000000 10084780512 10515968000 1111072848 11.943 98 37 74 2 1161585072 43142224 7370000
10:23:05 80843 8192000000 10047400512 10480566272 1048257120 11.344 93 23 46 0 1100318992 47196592 4865280
10:25:29 65077 8192000000 10064686224 10547417088 1094441840 11.785 97 28 56 0 1161565264 61250944 5872480
10:27:36 131564 8192000000 10076836624 10618859520 1098846256 11.827 98 11 22 0 1150472224 49296352 2329616
10:30:49 66942 8192000000 10074075008 10543423488 1113559344 11.966 99 14 28 2 1176584576 59946912 3078320
10:32:56 71167 8192000000 10074027920 10537721856 1096467120 11.804 98 7 14 0 1172032880 74087344 1478416
10:35:07 38217 8192000000 10083418960 10570002432 1135409104 12.172 100 19 38 0 1199105648 59438256 4258288
10:36:53 24065 8192000000 9014871664 9260830720 164150896 1.964 15 6 11 0 191746816 26511920 1084000

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 119 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 22-10-2021 10:39:08 to 22-10-2021 10:39:10.

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          236881
MON$OLDEST_ACTIVE               237208
MON$OLDEST_SNAPSHOT             237208
MON$NEXT_TRANSACTION            237208
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                       2066720
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             805
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2637 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2637 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2637 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2637 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 ---
:15: 
:16: mscs-test-161.budzdorov.ru    Fri Oct 22 10:37:30 2021
:17:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:18: 
:19: 
:20: mscs-test-161.budzdorov.ru    Fri Oct 22 10:37:35 2021
:21:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:22: 
--- end of diff output ---

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

22.10.21 10:39:11. 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-10-22 10:39:14 No severe PSQL-related problems occured
22.10.21 10:39:15. End of report.