Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 09.05.2022 04: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 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 15849016 kB
MemAvailable 31477652 kB
Buffers 20 kB
Cached 15818168 kB
SwapCached 2560 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.2
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 2022-05-09 04:37:16
DTS_END                         2022-05-09 04:37:16.2760
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/43902
TRN_ID                          214146
ATT_ID                          593
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: 8984.98 25543 1078197
customer order: creation 171.39 479 20567
customer order: refuse 14.58 453 1750
order to supplier: creation 44.98 1468 5398
order to supplier: removal 3.28 208966 393
invoice (draft): creation 44.37 3461 5324
invoice (draft): removal 50.58 1194 6069
invoice accept: apply 40.66 26130 4879
invoice accept: cancel 45.88 1377 5505
customer reserve: creation 3835.58 240 460269
customer reserve: removal 4440.80 119 532896
realization accept: apply 82.91 313 9949
realization accept: cancel 83.06 178 9967
payment from customer: creation 58.03 190 6963
payment from customer: removal 5.85 147 702
payment to supplier: creation 46.86 216 5623
payment to supplier: removal 3.45 127 414
service: total inventory turnovers 4.04 4694 485
service: total monetary turnovers 7.74 387 929
service: aggregate perf. data 0.92 16626 110
service: refresh index statistics 0.04 244097 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 09-05-2022 04:39:36 to 09-05-2022 04:39:38.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 12300
TEST_TIME 2 10345
TEST_TIME 3 8060
TEST_TIME 4 8008
TEST_TIME 5 7302
TEST_TIME 6 7107
TEST_TIME 7 6765
TEST_TIME 8 6535
TEST_TIME 9 6463
TEST_TIME 10 6457
TEST_TIME 11 6364
TEST_TIME 12 6376
TEST_TIME 13 6360
TEST_TIME 14 6456
TEST_TIME 15 6609
TEST_TIME 16 6579
TEST_TIME 17 6852
TEST_TIME 18 6996
TEST_TIME 19 6871
TEST_TIME 20 6992
TEST_TIME 21 7185
TEST_TIME 22 7256
TEST_TIME 23 7243
TEST_TIME 24 7405
TEST_TIME 25 7670
TEST_TIME 26 7724
TEST_TIME 27 7789
TEST_TIME 28 7875
TEST_TIME 29 7741
TEST_TIME 30 7624
TEST_TIME 31 7967
TEST_TIME 32 8425
TEST_TIME 33 8749
TEST_TIME 34 9314
TEST_TIME 35 9658
TEST_TIME 36 9842
TEST_TIME 37 9990
TEST_TIME 38 10015
TEST_TIME 39 10284
TEST_TIME 40 10641
TEST_TIME 41 10493
TEST_TIME 42 10850
TEST_TIME 43 10822
TEST_TIME 44 10666
TEST_TIME 45 10523
TEST_TIME 46 10370
TEST_TIME 47 10372
TEST_TIME 48 10241
TEST_TIME 49 10129
TEST_TIME 50 10037
TEST_TIME 51 9961
TEST_TIME 52 9942
TEST_TIME 53 9910
TEST_TIME 54 9938
TEST_TIME 55 9987
TEST_TIME 56 9969
TEST_TIME 57 9844
TEST_TIME 58 9756
TEST_TIME 59 9700
TEST_TIME 60 9706
TEST_TIME 61 9652
TEST_TIME 62 9555
TEST_TIME 63 9462
TEST_TIME 64 9488
TEST_TIME 65 9430
TEST_TIME 66 9394
TEST_TIME 67 9477
TEST_TIME 68 9549
TEST_TIME 69 9469
TEST_TIME 70 9414
TEST_TIME 71 9383
TEST_TIME 72 9330
TEST_TIME 73 9304
TEST_TIME 74 9262
TEST_TIME 75 9241
TEST_TIME 76 9160
TEST_TIME 77 9147
TEST_TIME 78 9072
TEST_TIME 79 9122
TEST_TIME 80 9102
TEST_TIME 81 9078
TEST_TIME 82 9052
TEST_TIME 83 9058
TEST_TIME 84 9135
TEST_TIME 85 9166
TEST_TIME 86 9228
TEST_TIME 87 9217
TEST_TIME 88 9186
TEST_TIME 89 9252
TEST_TIME 90 9288
TEST_TIME 91 9248
TEST_TIME 92 9284
TEST_TIME 93 9203
TEST_TIME 94 9195
TEST_TIME 95 9225
TEST_TIME 96 9162
TEST_TIME 97 9143
TEST_TIME 98 9103
TEST_TIME 99 9084
TEST_TIME 100 9099
TEST_TIME 101 9094
TEST_TIME 102 9078
TEST_TIME 103 9055
TEST_TIME 104 9063
TEST_TIME 105 9086
TEST_TIME 106 9092
TEST_TIME 107 9114
TEST_TIME 108 9238
TEST_TIME 109 9222
TEST_TIME 110 9282
TEST_TIME 111 9267
TEST_TIME 112 9283
TEST_TIME 113 9276
TEST_TIME 114 9211
TEST_TIME 115 9177
TEST_TIME 116 9158
TEST_TIME 117 9118
TEST_TIME 118 9110
TEST_TIME 119 9070
TEST_TIME 120 9009

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 09-05-2022 04:39:40 to 09-05-2022 04:39:43.

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 1500310 1500310 0 0.00 0 16873 14 0 0
doc_list_biud 1500293 1500293 0 0.00 0 36015 49 0 0
sp_add_invoice_to_stock 4970 4879 91 1.83 28 168557 26130 0 91
sp_cancel_adding_invoice 5505 5505 0 0.00 7 94370 1378 0 0
sp_cancel_client_order 1750 1750 0 0.00 7 9369 453 0 0
sp_cancel_customer_reserve 532896 532896 0 0.00 1 36027 120 0 0
sp_cancel_pay_from_customer 702 702 0 0.00 3 4500 147 0 0
sp_cancel_pay_to_supplier 414 414 0 0.00 4 1471 128 0 0
sp_cancel_supplier_invoice 6075 6069 6 0.09 3 28496 1195 0 6
sp_cancel_supplier_order 393 393 0 0.00 16 1738194 208966 0 0
sp_cancel_write_off 9967 9967 0 0.00 2 8401 178 0 0
sp_client_order 20567 20567 0 0.00 5 23952 479 0 0
sp_customer_reserve 460269 460269 0 0.00 1 45948 241 0 0
sp_fill_shopping_cart 31277 31277 0 0.00 1 22494 92 0 0
sp_fill_shopping_cart_clo_res 460233 460233 0 0.00 0 23668 21 0 0
sp_get_clo_for_invoice 4831 4831 0 0.00 0 21763 274 0 0
sp_kill_cost_storno 16588 16588 0 0.00 0 16857 90 0 0
sp_kill_qstorno_ret_qs2qd 1082221 1082221 0 0.00 0 36010 51 0 0
sp_kill_qty_storno 571360 571360 0 0.00 0 36013 118 0 0
sp_lock_selected_doc 20476 20476 0 0.00 0 5172 7 0 0
sp_make_cost_storno 27366 27366 0 0.00 0 16651 95 0 0
sp_multiply_rows_for_pdistr 27365 27365 0 0.00 0 12497 42 0 0
sp_multiply_rows_for_qdistr 20567 20567 0 0.00 0 11935 171 0 0
sp_pay_from_customer 6963 6963 0 0.00 6 16681 191 0 0
sp_pay_to_supplier 5623 5623 0 0.00 6 13312 217 0 0
sp_payment_common 12585 12585 0 0.00 2 9363 53 0 0
sp_qd_handle_on_cancel_clo 1750 1750 0 0.00 0 9361 374 0 0
sp_qd_handle_on_invoice_upd_sts 10336 10336 0 0.00 1 21916 620 0 0
sp_qd_handle_on_reserve_upd_sts 9949 9949 0 0.00 0 10837 72 0 0
sp_reserve_write_off 9949 9949 0 0.00 7 15625 314 0 0
sp_supplier_invoice 5324 5324 0 0.00 11 110011 3462 0 0
sp_supplier_order 5398 5398 0 0.00 7 39695 1469 0 0
srv_aggregate_perf_data 111 110 1 0.90 2741 233145 16627 0 1
srv_fill_mon_cache_memory 74 74 0 0.00 658 122700 30256 0 0
srv_find_qd_qs_mism 1028995 1028995 0 0.00 0 13059 9 0 0
srv_make_invnt_saldo 485 485 0 0.00 58 110809 4694 0 0
srv_make_money_saldo 929 929 0 0.00 1 25206 388 0 0
srv_recalc_idx_stat 5 5 0 0.00 50670 371476 244098 0 0
t$perf-abend:sp_add_invoice_to_stock 91 91 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 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 4881 4881 0 0.00 1 32575 1126 0 0
t$perf-norm:sp_cancel_adding_invoice 410 410 0 0.00 2 7487 533 0 0
t$perf-norm:sp_cancel_client_order 1750 1750 0 0.00 0 8264 35 0 0
t$perf-norm:sp_cancel_customer_reserve 642 642 0 0.00 0 740 27 0 0
t$perf-norm:sp_cancel_pay_from_customer 702 702 0 0.00 0 3358 26 0 0
t$perf-norm:sp_cancel_pay_to_supplier 414 414 0 0.00 0 345 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 403 403 0 0.00 0 3715 52 0 0
t$perf-norm:sp_cancel_supplier_order 393 393 0 0.00 0 94785 9178 0 0
t$perf-norm:sp_cancel_write_off 985 985 0 0.00 0 3205 23 0 0
t$perf-norm:sp_client_order 20567 20567 0 0.00 0 6733 42 0 0
t$perf-norm:sp_customer_reserve 1020 1020 0 0.00 0 5182 23 0 0
t$perf-norm:sp_pay_from_customer 6963 6963 0 0.00 0 10398 24 0 0
t$perf-norm:sp_pay_to_supplier 5623 5623 0 0.00 0 6022 23 0 0
t$perf-norm:sp_reserve_write_off 9949 9949 0 0.00 0 6554 27 0 0
t$perf-norm:sp_supplier_invoice 5324 5324 0 0.00 0 3458 36 0 0
t$perf-norm:sp_supplier_order 5398 5398 0 0.00 0 4404 35 0 0
t$perf-norm:srv_aggregate_perf_data 110 110 0 0.00 0 1266 29 0 0
t$perf-norm:srv_fill_mon_cache_memory 74 74 0 0.00 0 135 9 0 0
t$perf-norm:srv_make_invnt_saldo 485 485 0 0.00 0 1108 18 0 0
t$perf-norm:srv_make_money_saldo 929 929 0 0.00 0 390 5 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 3 179 74 0 0
v_add_invoice_to_stock 9838 9656 182 1.84 0 8249 29 0 182
v_all_customers 37627 37627 0 0.00 0 134 4 0 0
v_all_suppliers 29976 29976 0 0.00 0 65 5 0 0
v_all_wares 41132 41132 0 0.00 0 171 4 0 0
v_cancel_adding_invoice 820 820 0 0.00 0 2040 16 0 0
v_cancel_client_order 3500 3500 0 0.00 0 558 8 0 0
v_cancel_customer_prepayment 1404 1404 0 0.00 0 3139 15 0 0
v_cancel_customer_reserve 1284 1284 0 0.00 1 942 11 0 0
v_cancel_payment_to_supplier 828 828 0 0.00 0 1462 15 0 0
v_cancel_supplier_invoice 818 806 12 1.46 0 1253 26 0 12
v_cancel_supplier_order 786 786 0 0.00 0 558 12 0 0
v_cancel_write_off 1970 1970 0 0.00 0 2867 25 0 0
v_max_id_clo_ord 5395 5395 0 0.00 0 534 6 0 0
v_max_id_clo_res 1020 1020 0 0.00 0 4277 18 0 0
v_max_id_ord_sup 5315 5315 0 0.00 0 2755 6 0 0
v_max_non_paid_invoice 1345 1345 0 0.00 0 764 9 0 0
v_max_non_paid_realizn 3518 3518 0 0.00 0 5244 12 0 0
v_min_id_clo_ord 5395 5395 0 0.00 0 5723 11 0 0
v_min_id_clo_res 1020 1020 0 0.00 0 6208 26 0 0
v_min_id_ord_sup 5315 5315 0 0.00 0 6935 9 0 0
v_min_non_paid_invoice 5623 5623 0 0.00 0 9055 21 0 0
v_min_non_paid_realizn 6962 6962 0 0.00 0 9321 19 0 0
v_our_firm 10392 10392 0 0.00 0 141 5 0 0
v_reserve_write_off 19896 19896 0 0.00 0 3932 12 0 0
x_lock_depdocs_on_canc_invoice 11171 11171 0 0.00 0 20039 142 0 0
x_lock_depdocs_on_canc_sup_ord 393 393 0 0.00 0 12445 446 0 0
x_make_qstorno_1000_1200 5398 5398 0 0.00 2 39654 1266 0 0
x_make_qstorno_1200_2000 5324 5324 0 0.00 5 109995 3222 0 0
x_make_qstorno_2100_3300 460267 460267 0 0.00 0 45935 206 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 09-05-2022 04:39:44 to 09-05-2022 04:39:48.

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:20 1 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:20 33092 8192000000 9734343056 10034364416 799912448 8.895 100 85 171 0 828339728 7786704 20640576
02:09:55 19296 8192000000 9758718192 10065657856 831314048 9.212 100 84 168 0 863972480 12447824 20210608
02:11:17 22730 8192000000 9790620496 10109157376 853502288 9.435 100 83 166 0 897829376 24830688 19496400
02:12:44 11164 8192000000 9855441360 10186833920 901872384 9.917 100 83 163 0 964233456 44316704 18044368
02:14:01 6757 8192000000 9909049952 10252636160 947957344 10.371 100 77 151 1 1020621024 56370656 16293024
02:15:12 2423 8192000000 9949524992 10305150976 991585088 10.797 100 93 171 2 1067252128 57274016 18393024
02:16:17 5023 8192000000 9992576800 10349961216 1018986352 11.062 100 89 175 3 1111142736 75124768 17031616
02:17:25 1727 8192000000 10019900592 10392428544 1044742848 11.310 100 93 182 0 1140612224 78083392 17785984
02:18:28 2496 8192000000 10033480288 10405085184 1052175920 11.382 100 92 176 1 1145466224 75135648 18154656
02:19:33 1593 8192000000 10039461920 10414063616 1062259920 11.478 100 95 182 0 1155086688 73902096 18924672
02:20:39 1237 8192000000 10054158080 10431266816 1065784496 11.512 100 100 191 1 1173306992 88583360 18939136
02:21:44 1198 8192000000 10061770592 10438488064 1077782880 11.626 100 96 189 0 1180670656 84636544 18251232
02:22:47 1288 8192000000 10069488800 10450743296 1083763856 11.683 100 99 193 0 1191030160 87745392 19520912
02:23:50 1522 8192000000 10056620832 10442137600 1088782912 11.731 100 93 181 1 1178205168 70592512 18829744
02:24:52 1255 8192000000 10051239008 10439815168 1088546704 11.729 100 86 164 3 1170923856 66264960 16112192
02:25:55 1157 8192000000 10066922784 10454016000 1098013376 11.819 100 97 184 1 1190323808 73195408 19115024
02:26:58 1289 8192000000 10069954320 10460004352 1097690368 11.816 100 98 190 2 1189459280 71467648 20301264
02:28:01 1200 8192000000 10073746064 10459463680 1092911168 11.770 100 93 177 2 1195882704 82731568 20239968
02:29:03 1107 8192000000 10076645344 10467295232 1104063552 11.876 100 90 180 1 1195861552 74605136 17192864
02:30:05 978 8192000000 10048460352 10435174400 1090457280 11.747 100 89 172 0 1168779488 61208224 17113984
02:31:07 4437 8192000000 10023482976 10415288320 1078817616 11.636 99 64 118 0 1149012912 58581904 11613392
02:32:12 5035 8192000000 10043103216 10430763008 1072546608 11.576 100 68 129 0 1160653088 75294592 12811888
02:33:18 1318 8192000000 10033986016 10406400000 1059039104 11.447 100 97 187 1 1153370544 74805072 19526368
02:34:24 1239 8192000000 10006736064 10384125952 1052642960 11.386 100 97 177 0 1129333376 57817216 18873200
02:35:25 1225 8192000000 9983544176 10355752960 1032756432 11.195 100 85 168 2 1103141872 53827040 16558400
02:36:28 3388 8192000000 9946089728 10322243584 1007602496 10.952 99 67 128 0 1066351392 46391984 12356912
02:37:37 2662 8192000000 9996210800 10371526656 1043398320 11.297 100 75 139 1 1123971520 66893984 13679216
02:38:49 7558 8192000000 9985595920 10365657088 1040529328 11.270 99 67 128 2 1107174000 53800784 12843888
02:40:03 11493 8192000000 10020164384 10397110272 1066528080 11.519 99 79 148 0 1145268224 63945600 14794544
02:41:22 7990 8192000000 10064315952 10459435008 1082716272 11.673 100 65 125 0 1185693920 90502384 12475264
02:42:34 4321 8192000000 10082558224 10481381376 1090727584 11.750 100 67 123 2 1197544224 94082800 12733840
02:43:45 3880 8192000000 10102882832 10485907456 1095495216 11.795 99 76 135 1 1219440720 110864240 13081264
02:45:01 12722 8192000000 10155264080 10551128064 1117842160 12.007 100 66 128 0 1265505184 134088176 13574848
02:46:18 5500 8192000000 10175908416 10574848000 1138091376 12.198 100 70 134 0 1297341808 144176320 15074112
02:47:29 3480 8192000000 10241903024 10642309120 1143900736 12.252 100 87 174 0 1361968144 197246640 20820768
02:48:35 9877 8192000000 10248631680 10652385280 1150341376 12.313 100 60 119 1 1360261840 196129552 13790912
02:49:53 1101 8192000000 10284512352 10681573376 1164599888 12.446 100 88 165 1 1390864080 207848112 18416080
02:50:58 1177 8192000000 10340540144 10737270784 1170611184 12.503 100 97 190 3 1455399664 262099120 22689360
02:52:03 1154 8192000000 10393579056 10813353984 1176569104 12.558 100 95 185 1 1504775184 304701792 23504288
02:53:09 1017 8192000000 10419394384 10830295040 1184245872 12.630 100 97 191 0 1534691680 327911216 22534592
02:54:13 1391 8192000000 10449995696 10883215360 1184216736 12.630 100 93 182 0 1560937184 355294288 21426160
02:55:19 1551 8192000000 10448644384 10860466176 1190812480 12.691 100 82 158 2 1552493952 343091760 18589712
02:56:25 721 8192000000 10483432432 10905227264 1190384112 12.687 100 82 162 1 1593981296 384149552 19447632
02:57:34 1829 8192000000 10525271072 10959855616 1193098176 12.712 100 98 193 2 1631352112 414688560 23565376
02:58:40 954 8192000000 10551021568 10956341248 1198838656 12.766 100 95 186 0 1654018992 432150976 23029360
02:59:43 1232 8192000000 10587180480 11019509760 1199002496 12.767 100 91 181 1 1697134272 475968240 22163536
03:00:49 647 8192000000 10610987280 11036422144 1199725712 12.774 100 100 201 1 1715554608 491521392 24307504
03:01:55 1081 8192000000 10603342800 11041472512 1190299072 12.686 100 96 182 1 1709266000 496538608 22428320
03:02:59 2473 8192000000 10609398752 11035373568 1203551056 12.809 100 87 174 0 1721873744 497611120 20711568
03:04:05 788 8192000000 10640002800 11055861760 1200602752 12.782 100 94 184 0 1745022624 522406512 22013360
03:05:10 4045 8192000000 10649605136 11090116608 1199351440 12.770 100 60 117 0 1756809616 542242432 15215744
03:06:24 4352 8192000000 10702809392 11128430592 1194067872 12.721 100 61 121 0 1804189552 594032336 16089344
03:07:33 4478 8192000000 10708052592 11190124544 1180390816 12.594 100 77 145 1 1810743824 611832736 18520272
03:08:42 2701 8192000000 10688146784 11159392256 1180216832 12.592 100 67 131 2 1788526288 591434032 16875424
03:10:04 3989 8192000000 10599290928 11110002688 1171425776 12.510 100 58 109 0 1698986272 513405264 14155232
03:11:12 6732 8192000000 10471222048 11013632000 1166040000 12.460 100 53 104 0 1586668432 406163744 14464688
03:12:21 45671 8192000000 10435927808 10925907968 1142358352 12.238 98 25 50 0 1521134928 371888032 6888544
03:14:11 38000 8192000000 10353486432 10844622848 1132514176 12.145 98 30 60 0 1470423856 330429744 7479936
03:16:53 35625 8192000000 10158640800 10587189248 1141514528 12.230 100 36 67 2 1300316496 150570944 8231024
03:18:39 36318 8192000000 10209958496 10694139904 1136724128 12.185 100 30 60 1 1317750272 174239152 6786992
03:20:26 121125 8192000000 10066513840 10534350848 1100432224 11.842 97 11 23 1 1151040352 48228640 2379488
03:23:27 51069 8192000000 10101640432 10533253120 1120812976 12.035 99 38 72 3 1155856688 27694576 7349136
03:25:19 21101 8192000000 10053626592 10487631872 1127464160 12.097 100 51 95 3 1156635232 16878208 12292864
03:26:56 45206 8192000000 10095806656 10530775040 1105095296 11.886 98 56 102 2 1150623408 34480816 11047296
03:28:58 58518 8192000000 10125610672 10585620480 1140942432 12.224 100 15 30 2 1214865936 70582032 3341472
03:31:13 96963 8192000000 10095574752 10569695232 1143765936 12.251 100 13 26 0 1187013232 40463088 2784208
03:34:06 67005 8192000000 10103596224 10558312448 1139001648 12.206 100 29 58 0 1189340432 44036496 6302288
03:36:15 122695 8192000000 10094006752 10532601856 1112381648 11.955 97 27 52 0 1146640416 29015760 5243008
03:39:20 28353 8192000000 10101847520 10554765312 1139640784 12.212 100 21 43 0 1206016608 61981296 4394528
03:40:49 1256 8192000000 10129551376 10556821504 1155314592 12.359 100 90 173 5 1230657312 55800016 19542704
03:41:52 27245 8192000000 10160079648 10593398784 1170884080 12.505 100 46 92 1 1256703568 75642800 10176688
03:43:24 37308 8192000000 10176408992 10614861824 1130738080 12.128 97 28 56 1 1260177184 123225024 6214080
03:45:21 27197 8192000000 10084785440 10564214784 1119210880 12.020 98 9 18 0 1195472608 74212464 2049264
03:46:50 24386 8192000000 10076500960 10519314432 1126057552 12.084 99 24 45 1 1165129552 34633248 4438752
03:48:15 60080 8192000000 10072077536 10534633472 1131312544 12.134 100 10 20 0 1180129392 46145664 2671184
03:50:18 86879 8192000000 10079641456 10576003072 1127705760 12.100 99 14 28 0 1170603152 39929920 2967472
03:52:53 36846 8192000000 10081063280 10549329920 1131689600 12.137 99 14 26 0 1169300128 35185104 2425424
03:54:32 56686 8192000000 10110583968 10567151616 1121132496 12.038 98 16 31 0 1194851152 70546960 3171696
03:56:31 74002 8192000000 10058362208 10515546112 1104312128 11.879 97 14 26 0 1159826112 52611712 2902272
03:58:53 23082 8192000000 10116656368 10547408896 1118924640 12.017 98 57 108 3 1191596480 61592960 11078880
04:00:21 13740 8192000000 10160664208 10606862336 1139180816 12.208 100 30 60 1 1258265632 112348928 6735888
04:01:50 46809 8192000000 10102483200 10576764928 1126034288 12.084 99 22 43 2 1207612512 76365632 5212592
04:03:38 34614 8192000000 10082387888 10519941120 1113906768 11.969 98 16 32 0 1168245264 51097440 3241056
04:05:21 13667 8192000000 10098741568 10526621696 1121849296 12.044 99 37 74 0 1187033328 57650192 7533840
04:06:36 26927 8192000000 10077051072 10531667968 1078216928 11.630 96 31 62 1 1174033088 89543536 6272624
04:08:06 55116 8192000000 10078837888 10507354112 1049248352 11.353 93 18 36 0 1095146224 42308256 3589616
04:10:13 95754 8192000000 10111389168 10567995392 1110893952 11.941 99 36 72 2 1227825280 109484480 7446848
04:12:58 32225 8192000000 10063320256 10567704576 1106209072 11.897 99 9 18 0 1168807056 60969296 1628688
04:14:33 59188 8192000000 10092592192 10577608704 1127788880 12.101 100 13 26 1 1173374528 42789072 2796576
04:16:33 55845 8192000000 10052451712 10490740736 1079537472 11.643 95 20 40 0 1126727888 42893920 4296496
04:18:29 35161 8192000000 10099785616 10542481408 1129236288 12.114 99 19 38 0 1194716544 61129088 4351168
04:20:18 49188 8192000000 10174229472 10612850688 1113883040 11.969 96 29 58 0 1232969600 112514112 6572448
04:22:09 43955 8192000000 10182424064 10642087936 1139040640 12.207 99 24 48 0 1261596432 116701232 5854560
04:24:09 53349 8192000000 10152148976 10619609088 1108726000 11.920 97 21 43 0 1232252864 117434928 6091936
04:26:05 19462 8192000000 10114205824 10559377408 1127712432 12.100 99 35 67 0 1184349616 49728496 6908688
04:27:36 74657 8192000000 10111446144 10549657600 1129273968 12.115 99 32 64 0 1197297712 61416480 6607264
04:29:52 72038 8192000000 10102258368 10587901952 1120907968 12.036 98 36 72 2 1193633312 65382800 7342544
04:32:40 3343 8192000000 10079244976 10523668480 1135507312 12.173 100 59 105 1 1175645936 28126656 12011968
04:33:44 100734 8192000000 10040341088 10538528768 1101107248 11.848 97 10 20 0 1140970400 37482576 2380576
04:37:10 17351 8192000000 9254706384 9567875072 401645616 4.673 36 2 4 0 415435008 13478608 310784

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 09-05-2022 04:39:50 to 09-05-2022 04:40:06.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 91 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 09-05-2022 04:40:07 to 09-05-2022 04:40:09.

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          215261
MON$OLDEST_ACTIVE               215262
MON$OLDEST_SNAPSHOT             215262
MON$NEXT_TRANSACTION            215262
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                       2113088
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             765
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2763 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2763 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2763 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2763 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    Mon May  9 04:37:30 2022
:151:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:152: 
:153: 
:154: mscs-test-161.budzdorov.ru    Mon May  9 04:37:32 2022
:155:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:156: 
:157: 
:158: mscs-test-161.budzdorov.ru    Mon May  9 04:37:33 2022
:159:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:160: 
--- end of diff output ---

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

09.05.22 04:40:10. 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
2022-05-09 04:40:13 No severe PSQL-related problems occured
09.05.22 04:40:14. End of report.